Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11857/
Java: 64bit/jdk1.8.0_20 -XX:-UseCompressedOops -XX:+UseG1GC (asserts: true)
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDistribSearch
Error Message:
New version of class is not loaded { "responseHeader":{ "status":404,
"QTime":3}, "error":{ "msg":"no such blob or version available: test/2",
"code":404}}
Stack Trace:
java.lang.AssertionError: New version of class is not loaded {
"responseHeader":{
"status":404,
"QTime":3},
"error":{
"msg":"no such blob or version available: test/2",
"code":404}}
at
__randomizedtesting.SeedInfo.seed([6223322BA4A8DD2D:E3C5BC33D3F7BD11]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.core.TestDynamicLoading.dynamicLoading(TestDynamicLoading.java:154)
at
org.apache.solr.core.TestDynamicLoading.doTest(TestDynamicLoading.java:64)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java: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 9364 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/init-core-data-001
[junit4] 2> 668006 T5960 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 668006 T5960 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 668011 T5960 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 668011 T5960 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 668012 T5961 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 668112 T5960 oasc.ZkTestServer.run start zk server on
port:41903
[junit4] 2> 668113 T5960
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 668113 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 668117 T5968 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5153725c
name:ZooKeeperConnection Watcher:127.0.0.1:41903 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 668117 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 668117 T5960 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 668117 T5960 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 668121 T5960
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 668124 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 668124 T5971 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3aeec4b4
name:ZooKeeperConnection Watcher:127.0.0.1:41903/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 668125 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 668125 T5960 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 668126 T5960 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 668137 T5960 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 668139 T5960 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 668141 T5960 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 668143 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 668143 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 668155 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 668156 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 668165 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 668166 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 668168 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 668169 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 668171 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 668172 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 668174 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 668174 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 668176 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 668176 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 668178 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 668178 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 668201 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 668201 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 668203 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 668203 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 668205 T5960 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 668205 T5960 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 668588 T5960 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 668589 T5960 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@402c60ad{/,null,AVAILABLE}
[junit4] 2> 668590 T5960 oejs.AbstractConnector.doStart Started
ServerConnector@2266afaa{HTTP/1.1}{127.0.0.1:49095}
[junit4] 2> 668591 T5960 oejs.Server.doStart Started @670004ms
[junit4] 2> 668592 T5960 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 668592 T5960 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 668592 T5960 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-002
[junit4] 2> 668593 T5960 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-002/'
[junit4] 2> 668609 T5960 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-002/solr.xml
[junit4] 2> 668621 T5960 oasc.CoreContainer.<init> New CoreContainer
1433739834
[junit4] 2> 668621 T5960 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-002/]
[junit4] 2> 668622 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 668622 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 668622 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 668623 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 668623 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 668624 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 668624 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 668624 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 668625 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 668625 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 668626 T5960 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 668626 T5960 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 668626 T5960 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 668627 T5960 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 668627 T5960 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41903/solr
[junit4] 2> 668627 T5960 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 668628 T5960
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 668629 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 668630 T5985 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3c93fe15
name:ZooKeeperConnection Watcher:127.0.0.1:41903 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 668630 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 668631 T5960 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 668632 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 668634 T5988 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2df45286
name:ZooKeeperConnection Watcher:127.0.0.1:41903/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 668634 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 668635 T5960 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 668637 T5960 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 668639 T5960 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 668641 T5960 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 668643 T5960 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 668646 T5960 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 668647 T5960 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49095_
[junit4] 2> 668647 T5960 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:49095_
[junit4] 2> 668649 T5960 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 668650 T5960 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 668651 T5960 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 668653 T5960 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:49095_
[junit4] 2> 668653 T5960 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 668654 T5960 oasc.Overseer.start Overseer
(id=93097895377240067-127.0.0.1:49095_-n_0000000000) starting
[junit4] 2> 668656 T5960 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 668660 T5960 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 668661 T5990 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 668662 T5960 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 668664 T5960 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 668665 T5960
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 668667 T5989 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 668669 T5992 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 668669 T5992 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 668670 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 668670 T5992 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 668672 T5989 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:49095";,
[junit4] 2> "node_name":"127.0.0.1:49095_",
[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> 668672 T5989 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:49095";,
[junit4] 2> "node_name":"127.0.0.1:49095_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 668672 T5989 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 668673 T5989 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 668674 T5988 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> 669670 T5992 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 669671 T5992 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 669671 T5992 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 669671 T5992 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 669672 T5992 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 669672 T5992 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-002/collection1/'
[junit4] 2> 669673 T5992 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 669673 T5992 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 669673 T5992 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 669731 T5992 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 669733 T5992 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 669738 T5992 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 669744 T5992 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 669757 T5992 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 669758 T5992 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 669765 T5992 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 669883 T5992 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 669889 T5992 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 669891 T5992 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 669898 T5992 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 669901 T5992 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 669903 T5992 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 669904 T5992
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 669904 T5992
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 669905 T5992 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 669905 T5992
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 669905 T5992
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 669906 T5992 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 669906 T5992 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 669906 T5992 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/control/data/
[junit4] 2> 669907 T5992 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@50be54f9
[junit4] 2> 669907 T5992 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/control/data
[junit4] 2> 669907 T5992 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/control/data/index/
[junit4] 2> 669908 T5992 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 669908 T5992 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/control/data/index
[junit4] 2> 669908 T5992 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=49, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=89.6318359375,
floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=15.947784979465425,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 669909 T5992 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@570c4f81
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3ac6969f),segFN=segments_1,generation=1}
[junit4] 2> 669909 T5992 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 669911 T5992 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 669912 T5992 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 669912 T5992 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 669912 T5992 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 669913 T5992 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 669913 T5992 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 669913 T5992 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 669913 T5992 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 669913 T5992 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 669914 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 669914 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 669915 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 669915 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 669915 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 669915 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 669916 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 669916 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 669917 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 669918 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 669918 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 669919 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 669919 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 669919 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 669920 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 669920 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 669920 T5992 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 669921 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 669921 T5992 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 669923 T5992 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 669924 T5992 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 669925 T5992 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 669926 T5992 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 669927 T5992 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 669928 T5992 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 669928 T5992 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 669929 T5992 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=28, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=22.8876953125,
floorSegmentMB=0.392578125, forceMergeDeletesPctAllowed=5.6423269800298765,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 669929 T5992 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@570c4f81
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3ac6969f),segFN=segments_1,generation=1}
[junit4] 2> 669929 T5992 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 669930 T5992 oass.SolrIndexSearcher.<init> Opening
Searcher@7b08e83b[collection1] main
[junit4] 2> 669930 T5992 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 669930 T5992 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 669931 T5992 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 669931 T5992
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 669931 T5992 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 669931 T5992 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 669932 T5992
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 669932 T5992 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 669932 T5992 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 669932 T5992 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 669933 T5993 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@7b08e83b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 669933 T5992 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 669934 T5996 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:49095 collection:control_collection
shard:shard1
[junit4] 2> 669934 T5960 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 669935 T5960 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 669935 T5960
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 669936 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 669937 T5996 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 669937 T5999 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@440a59a9
name:ZooKeeperConnection Watcher:127.0.0.1:41903/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 669937 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 669938 T5960 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 669938 T5960
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 669941 T5960 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 669942 T5996 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 669943 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 669943 T5996
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 669944 T5996 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C471 name=collection1
org.apache.solr.core.SolrCore@511bb1c7 url=http://127.0.0.1:49095/collection1
node=127.0.0.1:49095_ C471_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:49095,
node_name=127.0.0.1:49095_, state=down}
[junit4] 2> 669944 T5996 C471 P49095 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:49095/collection1/
[junit4] 2> 669944 T5996 C471 P49095 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 669944 T5996 C471 P49095 oasc.SyncStrategy.syncToMe
http://127.0.0.1:49095/collection1/ has no replicas
[junit4] 2> 669944 T5996 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:49095/collection1/ shard1
[junit4] 2> 669945 T5989 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> 669947 T5996 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 669949 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 669950 T5989 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:49095";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 670052 T5999 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> 670054 T5988 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> 670100 T5996 oasc.ZkController.register We are
http://127.0.0.1:49095/collection1/ and leader is
http://127.0.0.1:49095/collection1/
[junit4] 2> 670101 T5996 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:49095
[junit4] 2> 670101 T5996 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 670101 T5996 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 670101 T5996 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 670102 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 670104 T5989 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:49095";,
[junit4] 2> "node_name":"127.0.0.1:49095_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 670105 T5989 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:49095";,
[junit4] 2> "node_name":"127.0.0.1:49095_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 670207 T5999 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> 670207 T5988 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> 670268 T5960 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 670269 T5960 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 670323 T5960 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@3972f357{/,null,AVAILABLE}
[junit4] 2> 670325 T5960 oejs.AbstractConnector.doStart Started
ServerConnector@48022ad0{HTTP/1.1}{127.0.0.1:39853}
[junit4] 2> 670325 T5960 oejs.Server.doStart Started @671738ms
[junit4] 2> 670326 T5960 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 670327 T5960 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 670327 T5960 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-003
[junit4] 2> 670328 T5960 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-003/'
[junit4] 2> 670345 T5960 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-003/solr.xml
[junit4] 2> 670356 T5960 oasc.CoreContainer.<init> New CoreContainer
709962846
[junit4] 2> 670357 T5960 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-003/]
[junit4] 2> 670357 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 670358 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 670358 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 670358 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 670359 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 670359 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 670359 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 670360 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 670360 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 670360 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 670361 T5960 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 670361 T5960 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 670362 T5960 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 670362 T5960 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 670362 T5960 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41903/solr
[junit4] 2> 670363 T5960 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 670363 T5960
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 670364 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 670366 T6013 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5fd34c49
name:ZooKeeperConnection Watcher:127.0.0.1:41903 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 670366 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 670366 T5960 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 670368 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 670369 T6016 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@31d59909
name:ZooKeeperConnection Watcher:127.0.0.1:41903/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 670369 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 670373 T5960
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 671376 T5960 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39853_
[junit4] 2> 671377 T5960 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:39853_
[junit4] 2> 671380 T5960 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 671383 T6017 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 671383 T6017 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 671384 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 671384 T6017 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 671384 T5989 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:39853";,
[junit4] 2> "node_name":"127.0.0.1:39853_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 671385 T5989 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:39853";,
[junit4] 2> "node_name":"127.0.0.1:39853_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 671385 T5989 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 671385 T5989 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 671488 T6016 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 671488 T5988 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 671488 T5999 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 672384 T6017 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 672384 T6017 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 672385 T6017 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 672385 T6017 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 672386 T6017 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 672386 T6017 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-003/collection1/'
[junit4] 2> 672387 T6017 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 672387 T6017 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 672387 T6017 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 672399 T6017 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 672406 T6017 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 672413 T6017 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 672420 T6017 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 672438 T6017 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 672440 T6017 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 672448 T6017 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 672620 T6017 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 672625 T6017 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 672626 T6017 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 672634 T6017 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 672636 T6017 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 672639 T6017 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 672640 T6017
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 672640 T6017
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 672641 T6017 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 672641 T6017
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 672642 T6017
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 672642 T6017 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 672642 T6017 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 672642 T6017 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty1/
[junit4] 2> 672643 T6017 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@50be54f9
[junit4] 2> 672643 T6017 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty1
[junit4] 2> 672643 T6017 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty1/index/
[junit4] 2> 672644 T6017 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 672644 T6017 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty1/index
[junit4] 2> 672644 T6017 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=49, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=89.6318359375,
floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=15.947784979465425,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 672645 T6017 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@17ce7506
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cc07e8c),segFN=segments_1,generation=1}
[junit4] 2> 672645 T6017 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 672649 T6017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 672649 T6017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 672649 T6017 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 672649 T6017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 672650 T6017 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 672650 T6017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 672650 T6017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 672650 T6017 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 672651 T6017 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 672651 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 672652 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 672652 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 672652 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 672653 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 672653 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 672654 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 672654 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 672655 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 672655 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 672656 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 672656 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 672657 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 672657 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 672658 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 672658 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 672659 T6017 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 672659 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 672660 T6017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 672662 T6017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 672663 T6017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 672664 T6017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 672665 T6017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 672666 T6017 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 672667 T6017 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 672667 T6017 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 672668 T6017 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=28, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=22.8876953125,
floorSegmentMB=0.392578125, forceMergeDeletesPctAllowed=5.6423269800298765,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 672669 T6017 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@17ce7506
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cc07e8c),segFN=segments_1,generation=1}
[junit4] 2> 672669 T6017 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 672669 T6017 oass.SolrIndexSearcher.<init> Opening
Searcher@3ff8a4d6[collection1] main
[junit4] 2> 672669 T6017 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 672670 T6017 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 672670 T6017 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 672671 T6017
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 672671 T6017 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 672671 T6017 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 672672 T6017
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 672672 T6017 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 672672 T6017 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 672673 T6017 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 672673 T6018 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@3ff8a4d6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 672674 T6017 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 672674 T6021 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:39853 collection:collection1
shard:shard2
[junit4] 2> 672675 T5960 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 672675 T5960 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 672675 T6021 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 672681 T6021 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 672682 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 672683 T6021
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 672683 T6021 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 672683 T5989 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> ASYNC NEW_CORE C472 name=collection1
org.apache.solr.core.SolrCore@19a7dd19 url=http://127.0.0.1:39853/collection1
node=127.0.0.1:39853_ C472_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:39853,
node_name=127.0.0.1:39853_, state=down}
[junit4] 2> 672683 T6021 C472 P39853 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:39853/collection1/
[junit4] 2> 672684 T6021 C472 P39853 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 672684 T6021 C472 P39853 oasc.SyncStrategy.syncToMe
http://127.0.0.1:39853/collection1/ has no replicas
[junit4] 2> 672684 T6021 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:39853/collection1/ shard2
[junit4] 2> 672685 T6021 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 672688 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 672689 T5989 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:39853";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 672791 T5999 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 672791 T6016 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 672791 T5988 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 672839 T6021 oasc.ZkController.register We are
http://127.0.0.1:39853/collection1/ and leader is
http://127.0.0.1:39853/collection1/
[junit4] 2> 672839 T6021 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:39853
[junit4] 2> 672840 T6021 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 672840 T6021 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 672840 T6021 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 672841 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 672842 T5989 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:39853";,
[junit4] 2> "node_name":"127.0.0.1:39853_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 672842 T5989 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:39853";,
[junit4] 2> "node_name":"127.0.0.1:39853_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 672944 T6016 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 672944 T5999 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 672944 T5988 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 673015 T5960 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 673015 T5960 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 673017 T5960 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@7cfda1cf{/,null,AVAILABLE}
[junit4] 2> 673018 T5960 oejs.AbstractConnector.doStart Started
ServerConnector@18471507{HTTP/1.1}{127.0.0.1:32950}
[junit4] 2> 673018 T5960 oejs.Server.doStart Started @674431ms
[junit4] 2> 673019 T5960 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 673019 T5960 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 673019 T5960 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-004
[junit4] 2> 673020 T5960 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-004/'
[junit4] 2> 673029 T5960 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-004/solr.xml
[junit4] 2> 673038 T5960 oasc.CoreContainer.<init> New CoreContainer
1138584257
[junit4] 2> 673038 T5960 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-004/]
[junit4] 2> 673039 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 673039 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 673039 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 673039 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 673040 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 673040 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 673040 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 673040 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 673040 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 673041 T5960 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 673041 T5960 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 673041 T5960 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 673042 T5960 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 673042 T5960 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 673042 T5960 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41903/solr
[junit4] 2> 673042 T5960 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 673043 T5960
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 673043 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 673044 T6035 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1511629e
name:ZooKeeperConnection Watcher:127.0.0.1:41903 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 673045 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 673045 T5960 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 673052 T5960 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 673052 T6038 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1a55994e
name:ZooKeeperConnection Watcher:127.0.0.1:41903/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 673053 T5960 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 673061 T5960
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 674065 T5960 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32950_
[junit4] 2> 674065 T5960 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:32950_
[junit4] 2> 674069 T5960 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 674073 T6039 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 674073 T6039 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 674074 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 674074 T6039 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 674074 T5989 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:32950";,
[junit4] 2> "node_name":"127.0.0.1:32950_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 674075 T5989 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:32950";,
[junit4] 2> "node_name":"127.0.0.1:32950_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 674075 T5989 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 674075 T5989 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 674178 T6016 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 674178 T5988 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 674178 T6038 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 674178 T5999 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 675074 T6039 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 675074 T6039 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 675075 T6039 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 675075 T6039 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 675076 T6039 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 675076 T6039 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-004/collection1/'
[junit4] 2> 675076 T6039 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 675076 T6039 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 675077 T6039 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%206223322BA4A8DD2D-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 675087 T6039 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 675088 T6039 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 675092 T6039 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 675097 T6039 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 675108 T6039 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 675109 T6039 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 675114 T6039 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 675249 T6039 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 675254 T6039 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 675256 T6039 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 675264 T6039 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 675267 T6039 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 675269 T6039 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 675270 T6039
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 675270 T6039
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 675271 T6039 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 675271 T6039
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 675272 T6039
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 675272 T6039 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 675272 T6039 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 675272 T6039 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty2/
[junit4] 2> 675273 T6039 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@50be54f9
[junit4] 2> 675274 T6039 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty2
[junit4] 2> 675274 T6039 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty2/index/
[junit4] 2> 675274 T6039 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 675275 T6039 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty2/index
[junit4] 2> 675275 T6039 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=49, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=89.6318359375,
floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=15.947784979465425,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 675276 T6039 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1e93c520
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c8ce1a7),segFN=segments_1,generation=1}
[junit4] 2> 675276 T6039 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 675279 T6039 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 675279 T6039 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 675279 T6039 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 675280 T6039 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 675280 T6039 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 675280 T6039 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 675280 T6039 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 675281 T6039 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 675281 T6039 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 675281 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 675282 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 675282 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 675282 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 675283 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 675283 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 675284 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 675284 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 675285 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 675286 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 675286 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 675287 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 675287 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 675288 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 675288 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 675288 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 675289 T6039 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 675289 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 675290 T6039 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 675292 T6039 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 675293 T6039 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 675294 T6039 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 675295 T6039 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 675295 T6039 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 675296 T6039 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 675297 T6039 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 675298 T6039 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=28, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=22.8876953125,
floorSegmentMB=0.392578125, forceMergeDeletesPctAllowed=5.6423269800298765,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 675299 T6039 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1e93c520
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c8ce1a7),segFN=segments_1,generation=1}
[junit4] 2> 675299 T6039 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 675300 T6039 oass.SolrIndexSearcher.<init> Opening
Searcher@1eb5a8dc[collection1] main
[junit4] 2> 675300 T6039 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 675303 T6039 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 675304 T6039 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 675304 T6039
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 675305 T6039 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 675305 T6039 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 675306 T6039
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 675306 T6039 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 675306 T6039 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 675306 T6039 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 675307 T6040 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1eb5a8dc[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 675308 T6039 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 675308 T6043 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:32950 collection:collection1
shard:shard1
[junit4] 2> 675309 T5960 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 675309 T5960 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 675310 T6043 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 675317 T6043 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 675318 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 675318 T6043
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 675319 T6043 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C473 name=collection1
org.apache.solr.core.SolrCore@39795ec url=http://127.0.0.1:32950/collection1
node=127.0.0.1:32950_ C473_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:32950,
node_name=127.0.0.1:32950_, state=down}
[junit4] 2> 675319 T6043 C473 P32950 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:32950/collection1/
[junit4] 2> 675319 T6043 C473 P32950 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 675319 T6043 C473 P32950 oasc.SyncStrategy.syncToMe
http://127.0.0.1:32950/collection1/ has no replicas
[junit4] 2> 675319 T5989 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 7
[junit4] 2> 675319 T6043 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:32950/collection1/ shard1
[junit4] 2> 675320 T6043 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 675323 T5988 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 675324 T5989 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:32950";,
[junit4] 2> "core":"coll
[...truncated too long message...]
essage = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:33338";,
[junit4] 2> "node_name":"127.0.0.1:33338_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 31
[junit4] 2> 711198 T5960 oasu.SolrCoreState.decrefSolrCoreState Closing
SolrCoreState
[junit4] 2> 711198 T6165 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:33338";,
[junit4] 2> "node_name":"127.0.0.1:33338_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 711198 T5960 oasu.DefaultSolrCoreState.closeIndexWriter
SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 711199 T5960 oasu.DefaultSolrCoreState.closeIndexWriter
closing IndexWriter with IndexWriterCloser
[junit4] 2> 711199 T5960 oasc.SolrCore.closeSearcher [collection1] Closing
main searcher on request.
[junit4] 2> 711203 T5960 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 711203 T5960 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty4
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty4;done=false>>]
[junit4] 2> 711203 T5960 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty4
[junit4] 2> 711203 T5960 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty4/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty4/index;done=false>>]
[junit4] 2> 711204 T5960 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001/tempDir-001/jetty4/index
[junit4] 2> 711204 T5960 oasc.Overseer.close Overseer
(id=93097895377240076-127.0.0.1:33338_-n_0000000004) closing
[junit4] 2> 711205 T6165 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:33338_
[junit4] 2> 711206 T6158 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 711209 T5960 oejs.AbstractConnector.doStop Stopped
ServerConnector@7db37cbe{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 711209 T5960 oejsh.ContextHandler.doStop Stopped
o.e.j.s.ServletContextHandler@14060341{/,null,UNAVAILABLE}
[junit4] 2> 711210 T5960 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 711211 T5960 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:41903 41903
[junit4] 2> 711767 T5961 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:41903 41903
[junit4] 2> 711767 T5961 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN
Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.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/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading
-Dtests.method=testDistribSearch -Dtests.seed=6223322BA4A8DD2D
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de_CH
-Dtests.timezone=Asia/Oral -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 43.8s J0 | TestDynamicLoading.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: New version of class
is not loaded {
[junit4] > "responseHeader":{
[junit4] > "status":404,
[junit4] > "QTime":3},
[junit4] > "error":{
[junit4] > "msg":"no such blob or version available: test/2",
[junit4] > "code":404}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([6223322BA4A8DD2D:E3C5BC33D3F7BD11]:0)
[junit4] > at
org.apache.solr.core.TestDynamicLoading.dynamicLoading(TestDynamicLoading.java:154)
[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> 711779 T5960 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
6223322BA4A8DD2D-001
[junit4] 2> NOTE: test params are:
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=31765, maxDocsPerChunk=628, blockSize=483),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=31765, blockSize=483)), sim=DefaultSimilarity, locale=de_CH,
timezone=Asia/Oral
[junit4] 2> NOTE: Linux 3.13.0-43-generic amd64/Oracle Corporation
1.8.0_20 (64-bit)/cpus=8,threads=1,free=377609936,total=486539264
[junit4] 2> NOTE: All tests run in this JVM: [ClusterStateTest,
TestClusterStateMutator, TestFuzzyAnalyzedSuggestions,
QueryElevationComponentTest, HdfsChaosMonkeySafeLeaderTest,
TestBlendedInfixSuggestions, SolrInfoMBeanTest, DirectSolrConnectionTest,
TestSolrCoreProperties, DirectUpdateHandlerTest,
PathHierarchyTokenizerFactoryTest, SuggesterTest,
IgnoreCommitOptimizeUpdateProcessorFactoryTest, CacheHeaderTest,
TestSchemaResource, SolrIndexConfigTest, OverseerRolesTest,
UnloadDistributedZkTest, TestQueryTypes, MultiTermTest,
TestSweetSpotSimilarityFactory, TestDocumentBuilder,
TestNonDefinedSimilarityFactory, LeaderElectionTest, TestIndexingPerformance,
SuggesterWFSTTest, ConnectionManagerTest, PingRequestHandlerTest,
TestLeaderElectionZkExpiry, SolrXmlInZkTest, LukeRequestHandlerTest,
ZkControllerTest, DistributedFacetPivotWhiteBoxTest, ResourceLoaderTest,
TestAtomicUpdateErrorCases, TestDefaultSearchFieldResource,
TestRequestStatusCollectionAPI, HdfsBasicDistributedZkTest, TestConfigReload,
CoreAdminCreateDiscoverTest, AsyncMigrateRouteKeyTest, HdfsRecoveryZkTest,
HdfsCollectionsAPIDistributedZkTest, TestXIncludeConfig,
TriLevelCompositeIdRoutingTest, TestGroupingSearch, TestCollationField,
TestCSVResponseWriter, TestQueryUtils, OutputWriterTest, TestRecoveryHdfs,
TestCloudPivotFacet, TestSearcherReuse, TestFreeTextSuggestions, DocValuesTest,
TestDistributedSearch, DocValuesMissingTest, NotRequiredUniqueKeyTest,
RollingRestartTest, TestIndexSearcher, DeleteInactiveReplicaTest,
TestReplicationHandler, VMParamsZkACLAndCredentialsProvidersTest,
PluginInfoTest, TestCoreContainer, CircularListTest, TestUpdate,
TestWriterPerf, TestSortingResponseWriter, BlockCacheTest,
DistributedFacetPivotSmallTest, TestStressUserVersions,
LegacyHTMLStripCharFilterTest, TestSort, DeleteReplicaTest,
ExitableDirectoryReaderTest, BadCopyFieldTest, TestFieldResource,
TestCollationFieldDocValues, CoreAdminRequestStatusTest,
TestSimpleQParserPlugin, SortByFunctionTest, TestWordDelimiterFilterFactory,
DistributedDebugComponentTest, TestDynamicLoading]
[junit4] Completed on J0 in 43.80s, 1 test, 1 failure <<< FAILURES!
[...truncated 909 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:187: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1348:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:956:
There were test failures: 461 suites, 1866 tests, 1 failure, 32 ignored (22
assumptions)
Total time: 63 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_20
-XX:-UseCompressedOops -XX:+UseG1GC (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]
