Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11702/
Java: 64bit/jdk1.8.0_20 -XX:+UseCompressedOops -XX:+UseParallelGC (asserts:
true)
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDistribSearch
Error Message:
Could not successfully add blob { "responseHeader":{ "status":0,
"QTime":1}, "response":{ "numFound":1, "start":0, "docs":[{
"id":"test/1", "md5":"7e43b99f34490c4c331bdf25a74b6f4d",
"blobName":"test", "version":1,
"timestamp":"2015-01-08T08:38:03.291Z", "size":4566}]}}
Stack Trace:
java.lang.AssertionError: Could not successfully add blob {
"responseHeader":{
"status":0,
"QTime":1},
"response":{
"numFound":1,
"start":0,
"docs":[{
"id":"test/1",
"md5":"7e43b99f34490c4c331bdf25a74b6f4d",
"blobName":"test",
"version":1,
"timestamp":"2015-01-08T08:38:03.291Z",
"size":4566}]}}
at
__randomizedtesting.SeedInfo.seed([1B783CBC186C8EC9:9A9EB2A46F33EEF5]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.handler.TestBlobHandler.postAndCheck(TestBlobHandler.java:146)
at
org.apache.solr.core.TestDynamicLoading.dynamicLoading(TestDynamicLoading.java:111)
at
org.apache.solr.core.TestDynamicLoading.doTest(TestDynamicLoading.java:64)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor97.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.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 10121 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/init-core-data-001
[junit4] 2> 1619147 T11743 oas.SolrTestCaseJ4.buildSSLConfig Randomized
ssl (false) and clientAuth (true)
[junit4] 2> 1619147 T11743
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 1619151 T11743 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 1619152 T11743 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1619152 T11744 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1619252 T11743 oasc.ZkTestServer.run start zk server on
port:52619
[junit4] 2> 1619253 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1619253 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1619255 T11751 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@f6d5d98 name:ZooKeeperConnection
Watcher:127.0.0.1:52619 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1619256 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1619256 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1619256 T11743 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1619259 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1619259 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1619260 T11754 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@340d1871
name:ZooKeeperConnection Watcher:127.0.0.1:52619/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1619260 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1619260 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1619260 T11743 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1619262 T11743 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1619263 T11743 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1619264 T11743 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1619265 T11743 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> 1619265 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1619267 T11743 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> 1619267 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1619268 T11743 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> 1619269 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1619270 T11743 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> 1619270 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1619271 T11743 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> 1619271 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1619272 T11743 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> 1619273 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1619274 T11743 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> 1619274 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1619275 T11743 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> 1619275 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1619277 T11743 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> 1619277 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1619278 T11743 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> 1619278 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1619279 T11743 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> 1619280 T11743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1619601 T11743 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1619602 T11743 oejs.AbstractConnector.doStart Started
[email protected]:44277
[junit4] 2> 1619603 T11743 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 1619603 T11743 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1619604 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-002
[junit4] 2> 1619604 T11743 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-002/'
[junit4] 2> 1619620 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-002/solr.xml
[junit4] 2> 1619629 T11743 oasc.CoreContainer.<init> New CoreContainer
1791349673
[junit4] 2> 1619630 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-002/]
[junit4] 2> 1619631 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1619631 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1619631 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1619632 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1619632 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1619632 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1619632 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1619633 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1619633 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1619634 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1619634 T11743 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1619634 T11743 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1619635 T11743 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1619635 T11743 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1619636 T11743 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52619/solr
[junit4] 2> 1619636 T11743 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1619636 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1619637 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1619638 T11766 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1443a0d9
name:ZooKeeperConnection Watcher:127.0.0.1:52619 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1619638 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1619639 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1619640 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1619641 T11769 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@354273a2
name:ZooKeeperConnection Watcher:127.0.0.1:52619/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1619641 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1619641 T11743 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 1619643 T11743 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 1619644 T11743 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 1619645 T11743 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 1619647 T11743 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 1619648 T11743 oascc.SolrZkClient.makePath makePath:
/live_nodes
[junit4] 2> 1619649 T11743 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44277_
[junit4] 2> 1619649 T11743 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44277_
[junit4] 2> 1619650 T11743 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 1619651 T11743 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 1619652 T11743 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1619653 T11743 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:44277_
[junit4] 2> 1619654 T11743 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 1619654 T11743 oasc.Overseer.start Overseer
(id=93107406002257923-127.0.0.1:44277_-n_0000000000) starting
[junit4] 2> 1619656 T11743 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 1619659 T11743 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1619660 T11771 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 1619660 T11743 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 1619661 T11743 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 1619662 T11743
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1619664 T11770 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 1619665 T11773 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 1619665 T11773 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 1619666 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1619666 T11773 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1619667 T11770 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:44277";,
[junit4] 2> "node_name":"127.0.0.1:44277_",
[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> 1619668 T11770 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:44277";,
[junit4] 2> "node_name":"127.0.0.1:44277_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1619668 T11770 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 1619668 T11770 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1619669 T11769 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> 1620666 T11773 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 1620667 T11773 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 1620667 T11773 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1620667 T11773 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1620668 T11773 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1620668 T11773 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-002/collection1/'
[junit4] 2> 1620669 T11773 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 1620669 T11773 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 1620669 T11773 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 1620693 T11773 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1620695 T11773 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1620700 T11773 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1620705 T11773 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1620715 T11773 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1620716 T11773 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1620721 T11773 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 1620824 T11773 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1620829 T11773 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 1620830 T11773 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1620838 T11773 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1620841 T11773 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1620843 T11773 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1620844 T11773
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1620844 T11773
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1620844 T11773 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1620845 T11773
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1620845 T11773
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1620846 T11773 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 1620846 T11773 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1620846 T11773 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/control/data/
[junit4] 2> 1620846 T11773 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@33d76a9c
[junit4] 2> 1620847 T11773 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/control/data
[junit4] 2> 1620847 T11773 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/control/data/index/
[junit4] 2> 1620847 T11773 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 1620848 T11773 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/control/data/index
[junit4] 2> 1620848 T11773 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=20, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=72.2568359375,
floorSegmentMB=0.4794921875, forceMergeDeletesPctAllowed=8.938239608141156,
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1620849 T11773 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@27954294
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b7c2f1a),segFN=segments_1,generation=1}
[junit4] 2> 1620849 T11773 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1620852 T11773 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1620852 T11773 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1620852 T11773 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 1620853 T11773 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1620853 T11773 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1620853 T11773 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1620854 T11773 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1620854 T11773 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1620854 T11773 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1620854 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1620855 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1620855 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1620855 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1620856 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1620856 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1620857 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1620857 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1620857 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1620858 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1620858 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1620859 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1620859 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1620859 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1620860 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1620860 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1620860 T11773 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1620861 T11773 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1620863 T11773 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1620864 T11773 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1620865 T11773 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1620866 T11773 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1620867 T11773 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1620868 T11773 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1620868 T11773 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1620869 T11773 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=44.529296875,
floorSegmentMB=0.5146484375, forceMergeDeletesPctAllowed=11.338304114761375,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.30765235450636363
[junit4] 2> 1620882 T11773 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@27954294
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b7c2f1a),segFN=segments_1,generation=1}
[junit4] 2> 1620882 T11773 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1620883 T11773 oass.SolrIndexSearcher.<init> Opening
Searcher@47ae2660[collection1] main
[junit4] 2> 1620883 T11773 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1620883 T11773 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1620884 T11773 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 1620884 T11773
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1620884 T11773 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1620885 T11773 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1620885 T11773
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1620885 T11773 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1620885 T11773 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1620886 T11773 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1620886 T11773 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1620886 T11774 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@47ae2660[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1620887 T11777 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:44277 collection:control_collection
shard:shard1
[junit4] 2> 1620887 T11743 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1620887 T11743 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1620888 T11777 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1620888 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1620889 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1620890 T11780 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@500edf80
name:ZooKeeperConnection Watcher:127.0.0.1:52619/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1620890 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1620890 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1620891 T11743
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1620891 T11777
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 1620893 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1620893 T11743 oasc.ChaosMonkey.monkeyLog monkey: init -
expire sessions:false cause connection loss:false
[junit4] 2> 1620893 T11777
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1620894 T11777
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> 1620894 T11743 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 1620894 T11770 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> 1620894 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> ASYNC NEW_CORE C1402 name=collection1
org.apache.solr.core.SolrCore@33cfb1c url=http://127.0.0.1:44277/collection1
node=127.0.0.1:44277_ C1402_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:44277,
node_name=127.0.0.1:44277_, state=down}
[junit4] 2> 1620894 T11777 C1402 P44277 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:44277/collection1/
[junit4] 2> 1620895 T11777 C1402 P44277 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 1620895 T11777 C1402 P44277 oasc.SyncStrategy.syncToMe
http://127.0.0.1:44277/collection1/ has no replicas
[junit4] 2> 1620896 T11777
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:44277/collection1/ shard1
[junit4] 2> 1620896 T11777 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1620899 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1620899 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1620900 T11770 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:44277";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1620900 T11783 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@17b7a03d
name:ZooKeeperConnection Watcher:127.0.0.1:52619/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1620901 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1620902 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1620910 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1620914 T11770 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 1
[junit4] 2> 1620914 T11770 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 1620915 T11769 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> 1620915 T11780 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> 1621020 T11770 oasco.ZkStateWriter.writePendingUpdates going
to create_collection /collections/collection1/state.json
[junit4] 2> 1621021 T11780 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> 1621050 T11777 oasc.ZkController.register We are
http://127.0.0.1:44277/collection1/ and leader is
http://127.0.0.1:44277/collection1/
[junit4] 2> 1621050 T11777 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:44277
[junit4] 2> 1621050 T11777 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 1621051 T11777 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 1621051 T11777 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 1621052 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1621053 T11770 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:44277";,
[junit4] 2> "node_name":"127.0.0.1:44277_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1621053 T11770 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:44277";,
[junit4] 2> "node_name":"127.0.0.1:44277_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1621159 T11780 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> 1621159 T11769 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> 1621172 T11743 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 1621172 T11743 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1621173 T11743 oejs.AbstractConnector.doStart Started
[email protected]:50975
[junit4] 2> 1621174 T11743 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 1621174 T11743 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1621174 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-003
[junit4] 2> 1621175 T11743 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-003/'
[junit4] 2> 1621185 T11743 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-003/solr.xml
[junit4] 2> 1621191 T11743 oasc.CoreContainer.<init> New CoreContainer
768427063
[junit4] 2> 1621192 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-003/]
[junit4] 2> 1621192 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1621192 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1621192 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1621193 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1621193 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1621193 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1621193 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1621194 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1621194 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1621194 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1621194 T11743 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1621195 T11743 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1621195 T11743 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1621195 T11743 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1621195 T11743 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52619/solr
[junit4] 2> 1621196 T11743 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1621196 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1621196 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1621198 T11795 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7af7e93 name:ZooKeeperConnection
Watcher:127.0.0.1:52619 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1621198 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1621198 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1621199 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1621200 T11798 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@157929ec
name:ZooKeeperConnection Watcher:127.0.0.1:52619/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1621200 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1621203 T11743
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1622206 T11743 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50975_
[junit4] 2> 1622206 T11743 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:50975_
[junit4] 2> 1622209 T11743 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1622211 T11799 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1622211 T11799 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 1622212 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1622212 T11799 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1622213 T11799 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1622213 T11770 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:50975";,
[junit4] 2> "node_name":"127.0.0.1:50975_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1622213 T11799 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1622213 T11770 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:50975";,
[junit4] 2> "node_name":"127.0.0.1:50975_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1622213 T11770 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1622214 T11770 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 1622214 T11799 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 1622214 T11799 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1622315 T11770 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1622316 T11798 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1622316 T11798 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 1623214 T11799 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 1623215 T11799 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1623215 T11799 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1623215 T11799 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1623216 T11799 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1623216 T11799 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-003/collection1/'
[junit4] 2> 1623217 T11799 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 1623217 T11799 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 1623217 T11799 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 1623231 T11799 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1623233 T11799 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1623239 T11799 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1623252 T11799 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1623265 T11799 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1623272 T11799 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1623277 T11799 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 1623386 T11799 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1623390 T11799 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 1623392 T11799 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1623398 T11799 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1623401 T11799 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1623402 T11799 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1623403 T11799
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1623404 T11799
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1623404 T11799 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1623404 T11799
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1623405 T11799
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1623405 T11799 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1623405 T11799 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1623405 T11799 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty1/
[junit4] 2> 1623406 T11799 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@33d76a9c
[junit4] 2> 1623406 T11799 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty1
[junit4] 2> 1623407 T11799 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty1/index/
[junit4] 2> 1623407 T11799 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 1623407 T11799 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty1/index
[junit4] 2> 1623407 T11799 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=20, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=72.2568359375,
floorSegmentMB=0.4794921875, forceMergeDeletesPctAllowed=8.938239608141156,
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1623408 T11799 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@406ca297
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e6c7e53),segFN=segments_1,generation=1}
[junit4] 2> 1623408 T11799 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1623411 T11799 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1623411 T11799 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1623412 T11799 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 1623412 T11799 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1623412 T11799 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1623412 T11799 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1623412 T11799 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1623413 T11799 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1623413 T11799 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1623413 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1623414 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1623414 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1623415 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1623415 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1623415 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1623416 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1623416 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1623417 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1623417 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1623418 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1623418 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1623419 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1623419 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1623419 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1623420 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1623420 T11799 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1623421 T11799 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1623423 T11799 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1623424 T11799 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1623424 T11799 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1623425 T11799 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1623426 T11799 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1623427 T11799 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1623427 T11799 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1623428 T11799 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=44.529296875,
floorSegmentMB=0.5146484375, forceMergeDeletesPctAllowed=11.338304114761375,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.30765235450636363
[junit4] 2> 1623429 T11799 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@406ca297
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e6c7e53),segFN=segments_1,generation=1}
[junit4] 2> 1623429 T11799 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1623429 T11799 oass.SolrIndexSearcher.<init> Opening
Searcher@7f5ad2c9[collection1] main
[junit4] 2> 1623429 T11799 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1623430 T11799 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1623430 T11799 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 1623431 T11799
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1623431 T11799 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1623431 T11799 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1623432 T11799
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1623432 T11799 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1623432 T11799 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1623432 T11799 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1623433 T11800 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@7f5ad2c9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1623433 T11799 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1623434 T11803 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:50975 collection:collection1
shard:shard2
[junit4] 2> 1623434 T11743 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1623434 T11743 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1623438 T11803 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 1623447 T11803
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard2
[junit4] 2> 1623448 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1623449 T11803
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1623449 T11803
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C1403 name=collection1
org.apache.solr.core.SolrCore@10984c78 url=http://127.0.0.1:50975/collection1
node=127.0.0.1:50975_ C1403_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:50975,
node_name=127.0.0.1:50975_, state=down}
[junit4] 2> 1623449 T11803 C1403 P50975 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:50975/collection1/
[junit4] 2> 1623449 T11770 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> 1623449 T11803 C1403 P50975 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 1623450 T11803 C1403 P50975 oasc.SyncStrategy.syncToMe
http://127.0.0.1:50975/collection1/ has no replicas
[junit4] 2> 1623450 T11803
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:50975/collection1/ shard2
[junit4] 2> 1623450 T11770 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1623450 T11803 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 1623452 T11798 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1623453 T11798 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 1623454 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1623455 T11770 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:50975";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1623456 T11770 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 2
[junit4] 2> 1623458 T11798 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1623458 T11798 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1623458 T11803 oasc.ZkController.register We are
http://127.0.0.1:50975/collection1/ and leader is
http://127.0.0.1:50975/collection1/
[junit4] 2> 1623459 T11803 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:50975
[junit4] 2> 1623459 T11803 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 1623459 T11803 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1623459 T11803 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 1623460 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1623460 T11770 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:50975";,
[junit4] 2> "node_name":"127.0.0.1:50975_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1623461 T11770 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:50975";,
[junit4] 2> "node_name":"127.0.0.1:50975_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1623462 T11770 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 3
[junit4] 2> 1623462 T11798 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1623463 T11798 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1623465 T11803 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1623689 T11743 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 1623690 T11743 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1623691 T11743 oejs.AbstractConnector.doStart Started
[email protected]:37469
[junit4] 2> 1623692 T11743 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 1623692 T11743 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1623693 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-004
[junit4] 2> 1623693 T11743 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-004/'
[junit4] 2> 1623704 T11743 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-004/solr.xml
[junit4] 2> 1623711 T11743 oasc.CoreContainer.<init> New CoreContainer
1940424825
[junit4] 2> 1623711 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-004/]
[junit4] 2> 1623711 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1623712 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1623712 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1623712 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1623712 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1623713 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1623713 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1623713 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1623713 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1623714 T11743 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1623714 T11743 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1623714 T11743 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1623714 T11743 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1623715 T11743 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1623715 T11743 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52619/solr
[junit4] 2> 1623715 T11743 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1623716 T11743
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1623716 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1623717 T11815 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@21c29f46
name:ZooKeeperConnection Watcher:127.0.0.1:52619 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1623718 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1623718 T11743 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1623721 T11743 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 1623722 T11818 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3368fcd0
name:ZooKeeperConnection Watcher:127.0.0.1:52619/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1623722 T11743 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1623726 T11743
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1624729 T11743 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37469_
[junit4] 2> 1624730 T11743 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:37469_
[junit4] 2> 1624732 T11743 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1624736 T11819 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1624736 T11819 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 1624737 T11769 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1624737 T11819 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1624737 T11819 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1624737 T11770 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:37469";,
[junit4] 2> "node_name":"127.0.0.1:37469_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1624738 T11819 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1624738 T11770 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:37469";,
[junit4] 2> "node_name":"127.0.0.1:37469_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1624738 T11770 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1624738 T11770 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1624738 T11819 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1624739 T11819 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1624739 T11770 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 4
[junit4] 2> 1624739 T11818 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1624739 T11798 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1624740 T11818 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1624740 T11798 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1625739 T11819 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 1625739 T11819 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1625740 T11819 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1625740 T11819 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1625740 T11819 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1625740 T11819 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-004/collection1/'
[junit4] 2> 1625741 T11819 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 1625741 T11819 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 1625741 T11819 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%201B783CBC186C8EC9-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 1625754 T11819 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1625757 T11819 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1625762 T11819 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1625767 T11819 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1625777 T11819 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1625778 T11819 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1625784 T11819 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 1625884 T11819 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1625889 T11819 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 1625890 T11819 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1625897 T11819 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1625899 T11819 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1625901 T11819 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1625902 T11819
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1625902 T11819
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1625903 T11819 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1625903 T11819
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1625904 T11819
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1625904 T11819 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1625904 T11819 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1625904 T11819 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty2/
[junit4] 2> 1625904 T11819 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@33d76a9c
[junit4] 2> 1625905 T11819 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty2
[junit4] 2> 1625905 T11819 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty2/index/
[junit4] 2> 1625905 T11819 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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 1625906 T11819 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-001/jetty2/index
[junit4] 2> 1625906 T11819 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=20, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=72.2568359375,
floorSegmentMB=0.4794921875, forceMergeDeletesPctAllowed=8.938239608141156,
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1625907 T11819 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@787ccc86
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2fa3346a),segFN=segments_1,generation=1}
[junit4] 2> 1625907 T11819 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1625909 T11819 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1625909 T11819 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1625909 T11819 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 1625909 T11819 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1625910 T11819 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1625910 T11819 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1625910 T11819 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1625910 T11819 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1625910 T11819 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1625911 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1625911 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1625911 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1625911 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1625912 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1625912 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1625912 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1625912 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1625913 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1625913 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1625914 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1625914 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1625915 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1625915 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1625915 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1625916 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1625916 T11819 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1625916 T11819 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1625918 T11819 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1625918 T11819 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1625919 T11819 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1625920 T11819 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1625920 T11819 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1625921 T11819 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1625921 T11819 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1625922 T11819 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=44.529296875,
floorSegmentMB=0.5146484375, forceMergeDeletesPctAllowed=11.338304114761375,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.30765235450636363
[junit4] 2> 1625923 T11819 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@787ccc86
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2fa3346a),segFN=segments_1,generation=1}
[junit4] 2> 1625923 T11819 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1625923 T11819 oass.SolrIndexSearcher.<init> Opening
Searcher@5a9fdd79[collection1] main
[junit4] 2> 1625923 T11819 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1625924 T11819 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1625924 T11819 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 1625924 T11819
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1625924 T11819 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1625925 T11819 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1625925 T11819
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1625925 T11819 oasr.ManagedResourceStor
[...truncated too long message...]
ux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-006/.system_shard1_replica1/data;done=false>>]
[junit4] 2> 1652235 T11743 oasc.CachingDirectoryFactory.close Closing
directory:
/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.core.TestDynamicLoading
1B783CBC186C8EC9-001/tempDir-006/.system_shard1_replica1/data
[junit4] 2> 1652235 T11743 oasc.Overseer.close Overseer
(id=93107406002257933-127.0.0.1:58911_-n_0000000004) closing
[junit4] 2> 1652236 T11918 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:58911_
[junit4] 2> 1653736 T11904 oascc.ZkStateReader$2.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1653736 T11860 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1653736 T11911 oascc.ZkStateReader$7.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1653758 T11743 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1653949 T11743 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 1653950 T11743 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:52619 52619
[junit4] 2> 1654576 T11744 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:52619 52619
[junit4] 2> 1654578 T11744 oasc.ZkTestServer$ZKServerMain.runFromConfig
WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/configs/.system
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading
-Dtests.method=testDistribSearch -Dtests.seed=1B783CBC186C8EC9
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de
-Dtests.timezone=Pacific/Efate -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 35.5s J0 | TestDynamicLoading.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not successfully
add blob {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":1},
[junit4] > "response":{
[junit4] > "numFound":1,
[junit4] > "start":0,
[junit4] > "docs":[{
[junit4] > "id":"test/1",
[junit4] > "md5":"7e43b99f34490c4c331bdf25a74b6f4d",
[junit4] > "blobName":"test",
[junit4] > "version":1,
[junit4] > "timestamp":"2015-01-08T08:38:03.291Z",
[junit4] > "size":4566}]}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([1B783CBC186C8EC9:9A9EB2A46F33EEF5]:0)
[junit4] > at
org.apache.solr.handler.TestBlobHandler.postAndCheck(TestBlobHandler.java:146)
[junit4] > at
org.apache.solr.core.TestDynamicLoading.dynamicLoading(TestDynamicLoading.java:111)
[junit4] > at
org.apache.solr.core.TestDynamicLoading.doTest(TestDynamicLoading.java:64)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1654603 T11743 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.core.TestDynamicLoading
1B783CBC186C8EC9-001
[junit4] 2> 35463 T11742 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=de,
timezone=Pacific/Efate
[junit4] 2> NOTE: Linux 3.13.0-43-generic amd64/Oracle Corporation
1.8.0_20 (64-bit)/cpus=8,threads=1,free=281664256,total=531103744
[junit4] 2> NOTE: All tests run in this JVM: [TestRecoveryHdfs,
DistributedFacetPivotWhiteBoxTest, LeaderElectionTest, ReplicationFactorTest,
ShardRoutingTest, TestSurroundQueryParser, TestHighlightDedupGrouping,
TestComponentsName, TriLevelCompositeIdRoutingTest, TestExtendedDismaxParser,
DirectSolrConnectionTest, TestDefaultSearchFieldResource, DocValuesTest,
SystemInfoHandlerTest, TestSweetSpotSimilarityFactory,
TestBlendedInfixSuggestions, TestArbitraryIndexDir, TestCSVResponseWriter,
DateFieldTest, TestStressReorder, CursorPagingTest,
OverriddenZkACLAndCredentialsProvidersTest, AlternateDirectoryTest,
HdfsDirectoryTest, BadCopyFieldTest, TestSolrQueryParserResource,
TestBinaryResponseWriter, TestSchemaResource, TestConfigReload,
TestComplexPhraseQParserPlugin, DocValuesMissingTest,
TestDistributedMissingSort, TestShardHandlerFactory, TestCoreContainer,
TestSolrCoreProperties, TestLMDirichletSimilarityFactory, HdfsRecoveryZkTest,
SolrPluginUtilsTest, ZkCLITest, TestReRankQParserPlugin,
TestNonDefinedSimilarityFactory, TestQueryTypes, TestDefaultStatsCache,
TestCopyFieldCollectionResource, TestPartialUpdateDeduplication,
TestHashPartitioner, UUIDFieldTest, DistributedFacetPivotLongTailTest,
SimpleCollectionCreateDeleteTest, TestOmitPositions,
IndexSchemaRuntimeFieldTest, CircularListTest, TestDFRSimilarityFactory,
PreAnalyzedUpdateProcessorTest, ExternalCollectionsTest, TestBinaryField,
DocValuesMultiTest, DistributedQueryElevationComponentTest,
RequestHandlersTest, TestCodecSupport, SolrCoreTest, PluginInfoTest,
SimpleFacetsTest, DateRangeFieldTest, TestCloudPivotFacet, DeleteReplicaTest,
HdfsCollectionsAPIDistributedZkTest, DistributedIntervalFacetingTest,
AssignTest, TestLazyCores, DistributedSpellCheckComponentTest, MultiTermTest,
TestFieldTypeResource, AsyncMigrateRouteKeyTest, SolrIndexConfigTest,
TestAnalyzeInfixSuggestions, TestFastWriter, AnalysisAfterCoreReloadTest,
ParsingFieldUpdateProcessorsTest, TestLRUCache,
FieldAnalysisRequestHandlerTest, XmlUpdateRequestHandlerTest,
VMParamsZkACLAndCredentialsProvidersTest, PathHierarchyTokenizerFactoryTest,
TestCollationField, CursorMarkTest, TestJmxIntegration,
TestDistribDocBasedVersion, TestCloudInspectUtil,
TestCursorMarkWithoutUniqueKey, TestRandomDVFaceting, CacheHeaderTest,
TestAtomicUpdateErrorCases, TestSolrQueryParserDefaultOperatorResource,
OpenExchangeRatesOrgProviderTest, SyncSliceTest, TestFreeTextSuggestions,
DistributedSuggestComponentTest, JSONWriterTest, TestFuzzyAnalyzedSuggestions,
RequiredFieldsTest, SoftAutoCommitTest, SuggesterWFSTTest,
BasicDistributedZkTest, HdfsBasicDistributedZkTest, TestDocumentBuilder,
TestCollapseQParserPlugin, TestManagedSchemaFieldResource,
HdfsChaosMonkeySafeLeaderTest, TestFastOutputStream, ChaosMonkeySafeLeaderTest,
UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, BasicZkTest,
RecoveryZkTest, TestReplicationHandler, TestZkChroot, TestDistributedSearch,
ZkControllerTest, HardAutoCommitTest, DistributedTermsComponentTest,
StatsComponentTest, TestGroupingSearch, TestFiltering, NoCacheHeaderTest,
WordBreakSolrSpellCheckerTest, TestUpdate, TestWordDelimiterFilterFactory,
SortByFunctionTest, TestSolrDeletionPolicy1, LukeRequestHandlerTest,
TestWriterPerf, TermVectorComponentTest, MoreLikeThisHandlerTest,
FastVectorHighlighterTest, UpdateRequestProcessorFactoryTest, QueryParsingTest,
UniqFieldsUpdateProcessorFactoryTest, PingRequestHandlerTest,
CoreContainerCoreInitFailuresTest, SOLR749Test, TestSolrIndexConfig,
ResponseLogComponentTest, TestStressRecovery, CloudExitableDirectoryReaderTest,
DeleteInactiveReplicaTest, DeleteShardTest, HttpPartitionTest,
OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest,
OverseerStatusTest, SSLMigrationTest, SolrXmlInZkTest, TestCollectionAPI,
TestLeaderElectionZkExpiry, TestShortCircuitedRequests, HdfsSyncSliceTest,
HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest,
StressHdfsTest, TestConfigOverlay, TestDynamicLoading]
[junit4] Completed on J0 in 35.73s, 1 test, 1 failure <<< FAILURES!
[...truncated 215 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, 1867 tests, 1 failure, 32 ignored (22
assumptions)
Total time: 64 minutes 48 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_20
-XX:+UseCompressedOops -XX:+UseParallelGC (asserts: true)
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]
