Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11349/
Java: 64bit/jdk1.7.0_67 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION:
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.testDistribSearch
Error Message:
no exception matching expected: 400: Request took too long during query
expansion. Terminating request.
Stack Trace:
java.lang.AssertionError: no exception matching expected: 400: Request took too
long during query expansion. Terminating request.
at
__randomizedtesting.SeedInfo.seed([19D3E66939E34CC4:983568714EBC2CF8]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertFail(CloudExitableDirectoryReaderTest.java:101)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:75)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTest(CloudExitableDirectoryReaderTest.java:54)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.GeneratedMethodAccessor51.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:43)
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 11734 lines...]
[junit4] Suite: org.apache.solr.cloud.CloudExitableDirectoryReaderTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/init-core-data-001
[junit4] 2> 2312229 T6598 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 2312229 T6598
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 2312232 T6598 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 2312232 T6598 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2312233 T6599 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2312333 T6598 oasc.ZkTestServer.run start zk server on
port:41961
[junit4] 2> 2312333 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2312334 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2312336 T6605 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@78a50424
name:ZooKeeperConnection Watcher:127.0.0.1:41961 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2312336 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2312337 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2312337 T6598 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2312340 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2312341 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2312342 T6607 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@43dcfed0
name:ZooKeeperConnection Watcher:127.0.0.1:41961/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2312342 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2312342 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2312342 T6598 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 2312344 T6598 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 2312346 T6598 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 2312347 T6598 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 2312348 T6598 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> 2312348 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 2312350 T6598 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> 2312350 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 2312351 T6598 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> 2312352 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2312353 T6598 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> 2312353 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 2312354 T6598 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> 2312354 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 2312355 T6598 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> 2312356 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 2312357 T6598 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> 2312357 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 2312358 T6598 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> 2312358 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 2312359 T6598 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> 2312359 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2312360 T6598 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> 2312361 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 2312362 T6598 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> 2312362 T6598 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 2312584 T6598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2312587 T6598 oejs.AbstractConnector.doStart Started
[email protected]:43031
[junit4] 2> 2312588 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2312588 T6598 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2312588 T6598 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002
[junit4] 2> 2312589 T6598 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/'
[junit4] 2> 2312600 T6598 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/solr.xml
[junit4] 2> 2312633 T6598 oasc.CoreContainer.<init> New CoreContainer
1374006271
[junit4] 2> 2312633 T6598 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/]
[junit4] 2> 2312634 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2312634 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2312634 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2312634 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2312635 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2312635 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2312635 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2312635 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2312635 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2312636 T6598 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2312636 T6598 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2312636 T6598 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2312636 T6598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2312637 T6598 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41961/solr
[junit4] 2> 2312637 T6598 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2312637 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2312638 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2312639 T6618 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3d7f1c5a
name:ZooKeeperConnection Watcher:127.0.0.1:41961 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2312639 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2312639 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2312641 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2312642 T6620 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3e5ebc8e
name:ZooKeeperConnection Watcher:127.0.0.1:41961/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2312642 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2312644 T6598 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2312648 T6598 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2312650 T6598 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2312652 T6598 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2312653 T6598 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2312654 T6598 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2312655 T6598 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43031_
[junit4] 2> 2312656 T6598 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:43031_
[junit4] 2> 2312657 T6598 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2312658 T6598 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2312658 T6598 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2312659 T6598 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:43031_
[junit4] 2> 2312660 T6598 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2312660 T6598 oasc.Overseer.start Overseer
(id=92536876425674755-127.0.0.1:43031_-n_0000000000) starting
[junit4] 2> 2312662 T6598 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2312666 T6598 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 2312666 T6598 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2312666 T6622 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2312668 T6598 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2312670 T6598
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2312672 T6621 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2312675 T6624 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 2312675 T6624 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2312676 T6624 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2312676 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2312679 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "base_url":"http://127.0.0.1:43031";,
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:43031_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection"}
[junit4] 2> 2312680 T6621
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
control_collection with shards [shard1]
[junit4] 2> 2312680 T6621
oasc.Overseer$ClusterStateUpdater.createCollection state version
control_collection 1
[junit4] 2> 2312680 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2312683 T6625 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> 2313677 T6624 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2313677 T6624 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 2313678 T6624 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2313678 T6624 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 2313679 T6624 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 2313680 T6624 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/collection1/'
[junit4] 2> 2313680 T6624 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 2313681 T6624 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 2313681 T6624 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 2313718 T6624 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2313743 T6624 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2313746 T6624 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 2313752 T6624 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2313987 T6624 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2313991 T6624 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2313992 T6624 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2313999 T6624 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2314001 T6624 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2314003 T6624 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2314004 T6624
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 2314004 T6624
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 2314004 T6624 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2314005 T6624
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 2314005 T6624
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 2314005 T6624 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 2314005 T6624 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2314005 T6624 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/control/data/
[junit4] 2> 2314005 T6624 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16e0f6ab
[junit4] 2> 2314006 T6624 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/control/data
[junit4] 2> 2314006 T6624 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/control/data/index/
[junit4] 2> 2314006 T6624 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/control/data/index'
doesn't exist. Creating new index...
[junit4] 2> 2314006 T6624 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/control/data/index
[junit4] 2> 2314007 T6624 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 2314007 T6624 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-2e5bd3e)),segFN=segments_1,generation=1}
[junit4] 2> 2314007 T6624 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2314010 T6624 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2314010 T6624 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2314010 T6624 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2314010 T6624 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2314010 T6624 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2314011 T6624 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2314011 T6624 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2314011 T6624 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2314011 T6624 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2314012 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2314012 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2314012 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2314013 T6624 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2314013 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 2314013 T6624 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2314013 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 2314014 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 2314014 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2314014 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2314015 T6624 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2314017 T6624 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2314018 T6624 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2314019 T6624 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2314020 T6624 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2314022 T6624 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2314022 T6624 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2314023 T6624 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=25, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=19.7548828125,
floorSegmentMB=1.4580078125, forceMergeDeletesPctAllowed=17.616635709406662,
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.20298112688796532
[junit4] 2> 2314023 T6624 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-2e5bd3e)),segFN=segments_1,generation=1}
[junit4] 2> 2314023 T6624 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2314024 T6624 oass.SolrIndexSearcher.<init> Opening
Searcher@65138a8b[collection1] main
[junit4] 2> 2314024 T6624 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 2314025 T6624 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 2314025 T6624 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2314026 T6624
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2314026 T6624 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2314026 T6624 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2314027 T6624
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2314027 T6624 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2314027 T6624 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 2314029 T6624
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 2314029 T6624 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2314029 T6624 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2314031 T6626 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@65138a8b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2314032 T6624 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2314033 T6629 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:43031 collection:control_collection
shard:shard1
[junit4] 2> 2314033 T6598 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2314034 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2314034 T6629 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2314034 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2314035 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2314036 T6631 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@49c9c229
name:ZooKeeperConnection Watcher:127.0.0.1:41961/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2314036 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2314037 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2314037 T6598
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2314038 T6629 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 2314039 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2314040 T6598 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 2314040 T6629
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2314040 T6629 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3013 name=collection1
org.apache.solr.core.SolrCore@2b291673 url=http://127.0.0.1:43031/collection1
node=127.0.0.1:43031_ C3013_STATE=coll:control_collection core:collection1
props:{base_url=http://127.0.0.1:43031, core=collection1, state=down,
node_name=127.0.0.1:43031_}
[junit4] 2> 2314040 T6629 C3013 P43031 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:43031/collection1/
[junit4] 2> 2314040 T6629 C3013 P43031 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2314041 T6629 C3013 P43031 oasc.SyncStrategy.syncToMe
http://127.0.0.1:43031/collection1/ has no replicas
[junit4] 2> 2314041 T6629 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:43031/collection1/ shard1
[junit4] 2> 2314041 T6629 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 2314044 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2314147 T6625 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> 2314147 T6632 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> 2314195 T6629 oasc.ZkController.register We are
http://127.0.0.1:43031/collection1/ and leader is
http://127.0.0.1:43031/collection1/
[junit4] 2> 2314195 T6629 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:43031
[junit4] 2> 2314195 T6629 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2314195 T6629 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 2314195 T6629 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2314197 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2314199 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "base_url":"http://127.0.0.1:43031";,
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:43031_",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"}
[junit4] 2> 2314262 T6598 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 2314262 T6598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2314264 T6598 oejs.AbstractConnector.doStart Started
[email protected]:39033
[junit4] 2> 2314265 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2314265 T6598 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2314265 T6598 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003
[junit4] 2> 2314265 T6598 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/'
[junit4] 2> 2314276 T6598 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/solr.xml
[junit4] 2> 2314305 T6632 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> 2314305 T6625 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> 2314315 T6598 oasc.CoreContainer.<init> New CoreContainer
16828809
[junit4] 2> 2314316 T6598 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/]
[junit4] 2> 2314316 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2314317 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2314317 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2314317 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2314317 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2314318 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2314318 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2314318 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2314318 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2314319 T6598 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2314319 T6598 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2314319 T6598 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2314320 T6598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2314320 T6598 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41961/solr
[junit4] 2> 2314320 T6598 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2314321 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2314321 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2314322 T6643 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5aaf41e2
name:ZooKeeperConnection Watcher:127.0.0.1:41961 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2314323 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2314323 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2314325 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2314327 T6645 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3bee5520
name:ZooKeeperConnection Watcher:127.0.0.1:41961/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2314327 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2314334 T6598
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2315337 T6598 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39033_
[junit4] 2> 2315339 T6598 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:39033_
[junit4] 2> 2315352 T6598 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2315358 T6647 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2315359 T6647 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2315360 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2315360 T6647 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2315362 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "base_url":"http://127.0.0.1:39033";,
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39033_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1"}
[junit4] 2> 2315363 T6621
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 2315363 T6621
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 1
[junit4] 2> 2315363 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 2315466 T6625 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> 2315466 T6646 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> 2315466 T6632 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> 2316360 T6647 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2316361 T6647 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 2316361 T6647 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2316362 T6647 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2316363 T6647 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2316363 T6647 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/collection1/'
[junit4] 2> 2316364 T6647 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 2316364 T6647 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 2316364 T6647 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 2316396 T6647 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2316416 T6647 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2316417 T6647 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 2316422 T6647 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2316638 T6647 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2316642 T6647 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2316644 T6647 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2316652 T6647 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2316655 T6647 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2316657 T6647 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2316657 T6647
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 2316658 T6647
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 2316658 T6647 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2316658 T6647
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 2316658 T6647
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 2316659 T6647 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 2316659 T6647 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2316659 T6647 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty1/
[junit4] 2> 2316659 T6647 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16e0f6ab
[junit4] 2> 2316660 T6647 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty1
[junit4] 2> 2316660 T6647 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty1/index/
[junit4] 2> 2316660 T6647 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty1/index'
doesn't exist. Creating new index...
[junit4] 2> 2316661 T6647 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty1/index
[junit4] 2> 2316661 T6647 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 2316662 T6647 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-7538d3e2)),segFN=segments_1,generation=1}
[junit4] 2> 2316662 T6647 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2316665 T6647 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2316666 T6647 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2316666 T6647 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2316666 T6647 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2316666 T6647 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2316666 T6647 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2316667 T6647 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2316667 T6647 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2316667 T6647 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2316668 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2316668 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2316668 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2316669 T6647 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2316669 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 2316669 T6647 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2316670 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 2316670 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 2316670 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2316671 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2316671 T6647 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2316674 T6647 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2316675 T6647 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2316676 T6647 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2316677 T6647 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2316679 T6647 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2316680 T6647 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2316680 T6647 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=25, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=19.7548828125,
floorSegmentMB=1.4580078125, forceMergeDeletesPctAllowed=17.616635709406662,
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.20298112688796532
[junit4] 2> 2316681 T6647 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-7538d3e2)),segFN=segments_1,generation=1}
[junit4] 2> 2316681 T6647 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2316681 T6647 oass.SolrIndexSearcher.<init> Opening
Searcher@28da9344[collection1] main
[junit4] 2> 2316681 T6647 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2316682 T6647 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2316682 T6647 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2316683 T6647
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2316683 T6647 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2316683 T6647 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2316684 T6647
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 2316684 T6647 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2316684 T6647 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 2316684 T6647 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2316688 T6648 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@28da9344[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2316691 T6647 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2316692 T6651 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:39033 collection:collection1
shard:shard2
[junit4] 2> 2316692 T6598 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2316693 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2316693 T6651 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 2316698 T6651 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 2316700 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2316700 T6651
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2316700 T6651 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3014 name=collection1
org.apache.solr.core.SolrCore@58e2e69f url=http://127.0.0.1:39033/collection1
node=127.0.0.1:39033_ C3014_STATE=coll:collection1 core:collection1
props:{base_url=http://127.0.0.1:39033, core=collection1, state=down,
node_name=127.0.0.1:39033_}
[junit4] 2> 2316700 T6651 C3014 P39033 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:39033/collection1/
[junit4] 2> 2316701 T6651 C3014 P39033 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2316701 T6651 C3014 P39033 oasc.SyncStrategy.syncToMe
http://127.0.0.1:39033/collection1/ has no replicas
[junit4] 2> 2316701 T6651 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:39033/collection1/ shard2
[junit4] 2> 2316701 T6651 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 2316704 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2316806 T6625 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> 2316806 T6632 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> 2316806 T6646 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> 2316855 T6651 oasc.ZkController.register We are
http://127.0.0.1:39033/collection1/ and leader is
http://127.0.0.1:39033/collection1/
[junit4] 2> 2316855 T6651 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:39033
[junit4] 2> 2316855 T6651 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2316855 T6651 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 2316855 T6651 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2316856 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2316858 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "base_url":"http://127.0.0.1:39033";,
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:39033_",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"}
[junit4] 2> 2316918 T6598 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 2316919 T6598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2316921 T6598 oejs.AbstractConnector.doStart Started
[email protected]:54360
[junit4] 2> 2316923 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2316923 T6598 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2316923 T6598 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004
[junit4] 2> 2316923 T6598 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/'
[junit4] 2> 2316933 T6598 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/solr.xml
[junit4] 2> 2316960 T6646 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> 2316960 T6632 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> 2316960 T6625 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> 2316974 T6598 oasc.CoreContainer.<init> New CoreContainer
203455862
[junit4] 2> 2316974 T6598 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/]
[junit4] 2> 2316975 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2316975 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2316976 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2316976 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2316976 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2316977 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2316977 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2316977 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2316978 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2316978 T6598 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2316979 T6598 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2316979 T6598 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2316980 T6598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2316980 T6598 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41961/solr
[junit4] 2> 2316980 T6598 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2316981 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2316982 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2316984 T6662 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@290cd432
name:ZooKeeperConnection Watcher:127.0.0.1:41961 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2316984 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2316984 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2316987 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2316988 T6664 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@66c85e55
name:ZooKeeperConnection Watcher:127.0.0.1:41961/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2316988 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2316995 T6598
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2317998 T6598 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54360_
[junit4] 2> 2317999 T6598 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:54360_
[junit4] 2> 2318003 T6598 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2318009 T6666 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2318009 T6666 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2318010 T6666 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2318010 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2318014 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "base_url":"http://127.0.0.1:54360";,
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:54360_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1"}
[junit4] 2> 2318015 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 2318015 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2318118 T6632 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> 2318118 T6646 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> 2318118 T6625 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> 2318118 T6665 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> 2319011 T6666 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2319011 T6666 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 2319012 T6666 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2319012 T6666 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2319013 T6666 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2319014 T6666 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/collection1/'
[junit4] 2> 2319015 T6666 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 2319015 T6666 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 2319016 T6666 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 2319044 T6666 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2319085 T6666 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2319087 T6666 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 2319095 T6666 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2319278 T6666 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2319282 T6666 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2319283 T6666 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2319292 T6666 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2319294 T6666 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2319297 T6666 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2319298 T6666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 2319298 T6666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 2319298 T6666 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2319299 T6666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 2319299 T6666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 2319299 T6666 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 2319299 T6666 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2319300 T6666 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty2/
[junit4] 2> 2319300 T6666 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16e0f6ab
[junit4] 2> 2319301 T6666 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty2
[junit4] 2> 2319301 T6666 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty2/index/
[junit4] 2> 2319301 T6666 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty2/index'
doesn't exist. Creating new index...
[junit4] 2> 2319301 T6666 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty2/index
[junit4] 2> 2319302 T6666 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=19, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 2319302 T6666 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-51b00c42)),segFN=segments_1,generation=1}
[junit4] 2> 2319302 T6666 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2319305 T6666 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2319305 T6666 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2319306 T6666 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2319306 T6666 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2319306 T6666 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2319306 T6666 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2319307 T6666 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2319307 T6666 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2319307 T6666 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2319307 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2319308 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2319308 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2319309 T6666 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2319309 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 2319309 T6666 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2319309 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 2319310 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 2319310 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2319310 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2319311 T6666 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2319313 T6666 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2319314 T6666 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2319315 T6666 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2319316 T6666 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2319317 T6666 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2319317 T6666 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2319318 T6666 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=25, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=19.7548828125,
floorSegmentMB=1.4580078125, forceMergeDeletesPctAllowed=17.616635709406662,
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.20298112688796532
[junit4] 2> 2319318 T6666 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-51b00c42)),segFN=segments_1,generation=1}
[junit4] 2> 2319318 T6666 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2319319 T6666 oass.SolrIndexSearcher.<init> Opening
Searcher@27a7c7ae[collection1] main
[junit4] 2> 2319319 T6666 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2319320 T6666 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2319320 T6666 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2319321 T6666
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2319321 T6666 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2319321 T6666 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2319321 T6666
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 2319322 T6666 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2319322 T6666 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 2319322 T6666 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2319324 T6667 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@27a7c7ae[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2319326 T6666 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2319327 T6670 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:54360 collection:collection1
shard:shard1
[junit4] 2> 2319327 T6598 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 2319327 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2319328 T6670 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2319331 T6670 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 2319331 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2319332 T6670
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2319332 T6670 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3015 name=collection1
org.apache.solr.core.SolrCore@502d69ba url=http://127.0.0.1:54360/collection1
node=127.0.0.1:54360_ C3015_STATE=coll:collection1 core:collection1
props:{base_url=http://127.0.0.1:54360, core=collection1, state=down,
node_name=127.0.0.1:54360_}
[junit4] 2> 2319332 T6670 C3015 P54360 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:54360/collection1/
[junit4] 2> 2319332 T6670 C3015 P54360 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2319332 T6670 C3015 P54360 oasc.SyncStrategy.syncToMe
http://127.0.0.1:54360/collection1/ has no replicas
[junit4] 2> 2319332 T6670 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:54360/collection1/ shard1
[junit4] 2> 2319333 T6670 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 2319335 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2319437 T6665 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> 2319437 T6632 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> 2319437 T6625 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> 2319437 T6646 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> 2319486 T6670 oasc.ZkController.register We are
http://127.0.0.1:54360/collection1/ and leader is
http://127.0.0.1:54360/collection1/
[junit4] 2> 2319486 T6670 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:54360
[junit4] 2> 2319486 T6670 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2319486 T6670 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 2319486 T6670 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2319488 T6625 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2319490 T6621 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "base_url":"http://127.0.0.1:54360";,
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1:54360_",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"}
[junit4] 2> 2319572 T6598 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 2319573 T6598 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2319575 T6598 oejs.AbstractConnector.doStart Started
[email protected]:55891
[junit4] 2> 2319576 T6598 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2319576 T6598 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2319576 T6598 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-005
[junit4] 2> 2319577 T6598 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-005/'
[junit4] 2> 2319587 T6598 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-005/solr.xml
[junit4] 2> 2319607 T6646 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> 2319607 T6625 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> 2319607 T6665 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> 2319607 T6632 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> 2319609 T6598 oasc.CoreContainer.<init> New CoreContainer
1755637414
[junit4] 2> 2319609 T6598 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-005/]
[junit4] 2> 2319610 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2319610 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2319610 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2319611 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2319611 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2319611 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2319611 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2319611 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2319612 T6598 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2319612 T6598 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2319612 T6598 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2319612 T6598 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2319613 T6598 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2319613 T6598 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:41961/solr
[junit4] 2> 2319613 T6598 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2319613 T6598
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2319614 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2319616 T6681 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1c067efc
name:ZooKeeperConnection Watcher:127.0.0.1:41961 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2319616 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2319616 T6598 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2319618 T6598 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2319620 T6683 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5b5b5d28
name:ZooKeeperConnection Watcher:127.0.0.1:41961/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2319620 T6598 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2319626 T6598
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2320629 T6598 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55891_
[junit4] 2> 2320630 T6598 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:55891_
[junit4] 2> 2320635 T6598 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2320643 T6685 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2320643 T6685 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2320644 T6685 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2320644 T6625 oasc.DistributedQueue$LatchChildWatcher.p
[...truncated too long message...]
tory.close Closing directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001/tempDir-001/jetty4/index
[junit4] 2> 2396527 T6598 oasc.Overseer.close Overseer
(id=92536876425674764-127.0.0.1:48545_-n_0000000004) closing
[junit4] 2> 2396527 T6742 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:48545_
[junit4] 2> 2398032 T6737 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2398057 T6598 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 2403046 T6598 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 2403048 T6598 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:41961 41961
[junit4] 2> 2403468 T6599 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:41961 41961
[junit4] 2> 2403470 T6599 oasc.ZkTestServer$ZKServerMain.runFromConfig
WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CloudExitableDirectoryReaderTest -Dtests.method=testDistribSearch
-Dtests.seed=19D3E66939E34CC4 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=en_IE -Dtests.timezone=ART -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 91.3s J1 |
CloudExitableDirectoryReaderTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: no exception matching
expected: 400: Request took too long during query expansion. Terminating
request.
[junit4] > at
__randomizedtesting.SeedInfo.seed([19D3E66939E34CC4:983568714EBC2CF8]:0)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertFail(CloudExitableDirectoryReaderTest.java:101)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:75)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTest(CloudExitableDirectoryReaderTest.java:54)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2403490 T6598 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/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-19D3E66939E34CC4-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50),
sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=en_IE,
timezone=ART
[junit4] 2> NOTE: Linux 3.13.0-36-generic amd64/Oracle Corporation
1.7.0_67 (64-bit)/cpus=8,threads=1,free=54871448,total=222527488
[junit4] 2> NOTE: All tests run in this JVM: [OutputWriterTest,
ScriptEngineTest, TestPHPSerializedResponseWriter,
SchemaVersionSpecificBehaviorTest, TestNoOpRegenerator, TestJoin,
DistributedQueryComponentCustomSortTest, SuggestComponentTest, CursorMarkTest,
HdfsSyncSliceTest, DeleteInactiveReplicaTest, StressHdfsTest,
TestInfoStreamLogging, SolrCoreTest, TestNRTOpen,
CoreContainerCoreInitFailuresTest, ResponseHeaderTest, TestPseudoReturnFields,
TestRecovery, HdfsDirectoryTest, TestManagedResourceStorage,
TestCopyFieldCollectionResource, AnalyticsQueryTest, TestFiltering,
TestIBSimilarityFactory, DistribDocExpirationUpdateProcessorTest,
TestCollationField, BadCopyFieldTest, SpatialFilterTest,
ChaosMonkeySafeLeaderTest, SharedFSAutoReplicaFailoverTest,
TestDistributedGrouping, TestAtomicUpdateErrorCases, ModifyConfFileTest,
TestRealTimeGet, LoggingHandlerTest, ShardRoutingCustomTest,
StatsComponentTest, TestLazyCores, TestCSVLoader, AlternateDirectoryTest,
PreAnalyzedFieldTest, FullSolrCloudDistribCmdsTest, TestRestManager,
CopyFieldTest, SimplePostToolTest, UniqFieldsUpdateProcessorFactoryTest,
ZkSolrClientTest, TestFieldSortValues, SolrIndexConfigTest,
TestSolrXmlPersistence, ChangedSchemaMergeTest,
CoreMergeIndexesAdminHandlerTest, OpenCloseCoreStressTest, RollingRestartTest,
TestPhraseSuggestions, DistributedQueryComponentOptimizationTest,
TestReRankQParserPlugin, SoftAutoCommitTest, TestMultiCoreConfBootstrap,
AssignTest, TestFieldTypeCollectionResource, MigrateRouteKeyTest,
AnalyticsMergeStrategyTest, EnumFieldTest, TestComplexPhraseQParserPlugin,
TestOmitPositions, DistanceFunctionTest, CurrencyFieldXmlFileTest,
UUIDFieldTest, TermVectorComponentTest, TestJmxMonitoredMap,
DateMathParserTest, TestFieldCollectionResource, SOLR749Test,
UpdateRequestProcessorFactoryTest, TestQueryUtils, TestBinaryField,
TestZkChroot, TestTolerantSearch, SearchHandlerTest, TestElisionMultitermQuery,
SuggesterFSTTest, TestFieldResource, SolrXmlInZkTest,
DefaultValueUpdateProcessorTest, TestNonNRTOpen, TestExtendedDismaxParser,
HardAutoCommitTest, TestManagedSchemaDynamicFieldResource,
DirectUpdateHandlerOptimizeTest, TestHighlightDedupGrouping,
LukeRequestHandlerTest, LeaderElectionIntegrationTest, TestInitParams,
MultiTermTest, ResourceLoaderTest, TestSolrQueryParser, TestSchemaNameResource,
TestCharFilters, FieldAnalysisRequestHandlerTest,
HdfsWriteToMultipleCollectionsTest, SolrRequestParserTest,
TestMergePolicyConfig, BasicDistributedZk2Test, UnloadDistributedZkTest,
CollectionsAPIDistributedZkTest, SyncSliceTest, BasicZkTest, RecoveryZkTest,
TestRandomFaceting, ZkCLITest, TestDistributedSearch, AutoCommitTest,
TestHashPartitioner, DistributedSpellCheckComponentTest,
TermVectorComponentDistributedTest, TestStressReorder,
DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer,
SimpleFacetsTest, TestGroupingSearch, QueryElevationComponentTest,
ConvertedLegacyTest, TestSort, TestFunctionQuery, BasicFunctionalityTest,
TestBadConfig, TestIndexSearcher, HighlighterTest,
DistributedQueryElevationComponentTest, SolrIndexSplitterTest,
TestCoreDiscovery, AnalysisAfterCoreReloadTest,
SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, DocValuesTest,
SuggesterTSTTest, TestStressLucene, PolyFieldTest,
WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, TestUpdate,
FieldMutatingUpdateProcessorTest, QueryEqualityTest,
StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest,
TestRemoteStreaming, DocValuesMultiTest, TestSolrDeletionPolicy1,
SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest,
CacheHeaderTest, DisMaxRequestHandlerTest, TestWriterPerf,
TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest,
TestQueryTypes, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest,
RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache,
TestIndexingPerformance, RequiredFieldsTest, TestArbitraryIndexDir,
FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest,
RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest,
ReturnFieldsTest, JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest,
QueryParsingTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest,
PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName,
TestBinaryResponseWriter, TestLFUCache, HighlighterConfigTest,
UpdateParamsTest, TestSolrIndexConfig, TestQuerySenderNoQuery,
TestSolrDeletionPolicy2, SampleTest, TestDocSet, TestSearchPerf,
NumericFieldsTest, MinimalSchemaTest, TestConfig, TestPostingsSolrHighlighter,
DirectSolrConnectionTest, SpellPossibilityIteratorTest, SynonymTokenizerTest,
TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory,
TestPerFieldSimilarity, TestLMDirichletSimilarityFactory,
TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory,
TestFastWriter, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest,
URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, TestSystemIdResolver,
PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest,
RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestUtils,
TestDocumentBuilder, SliceStateTest, FileUtilsTest, CircularListTest,
TestRTGBase, DistributedIntervalFacetingTest, TestDistributedMissingSort,
AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest]
[junit4] Completed on J1 in 91.28s, 1 test, 1 failure <<< FAILURES!
[...truncated 297 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:524: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:472: 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:189: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1359:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:961:
There were test failures: 432 suites, 1778 tests, 1 failure, 44 ignored (22
assumptions)
Total time: 96 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0_67
-XX:+UseCompressedOops -XX:+UseSerialGC
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]
