Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11333/
Java: 64bit/jdk1.9.0-ea-b28 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED:
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([1B77DB753592D726:9A91556D42CDB71A]: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:81)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTest(CloudExitableDirectoryReaderTest.java:54)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:484)
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 11716 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/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/init-core-data-001
[junit4] 2> 1916233 T5212 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (true)
[junit4] 2> 1916233 T5212
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /_/gq
[junit4] 2> 1916236 T5212 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 1916236 T5212 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1916237 T5213 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1916337 T5212 oasc.ZkTestServer.run start zk server on
port:59745
[junit4] 2> 1916337 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1916338 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1916341 T5219 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5abc5218
name:ZooKeeperConnection Watcher:127.0.0.1:59745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1916342 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1916342 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1916343 T5212 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1916347 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1916348 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1916349 T5221 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@fd9048e name:ZooKeeperConnection
Watcher:127.0.0.1:59745/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1916350 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1916350 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1916350 T5212 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1916353 T5212 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1916356 T5212 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1916358 T5212 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1916361 T5212 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> 1916362 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1916366 T5212 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> 1916367 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1916369 T5212 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> 1916369 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1916371 T5212 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> 1916371 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1916372 T5212 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> 1916372 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1916373 T5212 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> 1916373 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1916374 T5212 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> 1916374 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1916375 T5212 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> 1916376 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1916377 T5212 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> 1916377 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1916378 T5212 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> 1916378 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1916379 T5212 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> 1916379 T5212 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1916380 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1916381 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1916381 T5223 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4f594b04
name:ZooKeeperConnection Watcher:127.0.0.1:59745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1916382 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1916382 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1916624 T5212 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1916626 T5212 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1916628 T5212 oejs.AbstractConnector.doStart Started
[email protected]:57166
[junit4] 2> 1916629 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 1916629 T5212 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1916629 T5212 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002
[junit4] 2> 1916629 T5212 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/'
[junit4] 2> 1916640 T5212 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/solr.xml
[junit4] 2> 1916676 T5212 oasc.CoreContainer.<init> New CoreContainer
1784509355
[junit4] 2> 1916676 T5212 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/]
[junit4] 2> 1916677 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1916677 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1916678 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1916678 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1916678 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1916679 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1916679 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1916680 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1916680 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1916682 T5212 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1916683 T5212 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1916684 T5212 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1916684 T5212 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1916685 T5212 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59745/solr
[junit4] 2> 1916685 T5212 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1916686 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1916687 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1916688 T5234 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@34ad62e6
name:ZooKeeperConnection Watcher:127.0.0.1:59745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1916688 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1916688 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1916689 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1916690 T5236 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@691ce239
name:ZooKeeperConnection Watcher:127.0.0.1:59745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1916690 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1916691 T5212 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 1916694 T5212 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 1916696 T5212 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 1916697 T5212 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 1916698 T5212 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 1916700 T5212 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1916700 T5212 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57166__%2Fgq
[junit4] 2> 1916701 T5212 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:57166__%2Fgq
[junit4] 2> 1916702 T5212 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 1916703 T5212 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 1916704 T5212 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1916705 T5212 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:57166__%2Fgq
[junit4] 2> 1916705 T5212 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 1916706 T5212 oasc.Overseer.start Overseer
(id=92519858154242052-127.0.0.1:57166__%2Fgq-n_0000000000) starting
[junit4] 2> 1916707 T5212 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 1916714 T5212 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1916715 T5238 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 1916716 T5237 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 1916717 T5212 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 1916719 T5212 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 1916721 T5212
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1916729 T5240 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 1916729 T5240 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1916730 T5240 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1916731 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1916734 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:57166/_/gq";,
[junit4] 2> "node_name":"127.0.0.1:57166__%2Fgq",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1916735 T5237
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
control_collection with shards [shard1]
[junit4] 2> 1916735 T5237
oasc.Overseer$ClusterStateUpdater.createCollection state version
control_collection 1
[junit4] 2> 1916735 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 1916739 T5241 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> 1917731 T5240 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1917731 T5240 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 1917732 T5240 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1917732 T5240 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1917734 T5240 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1917734 T5240 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/collection1/'
[junit4] 2> 1917735 T5240 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 1917735 T5240 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 1917736 T5240 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 1917761 T5240 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 1917769 T5240 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1917770 T5240 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 1917774 T5240 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1917857 T5240 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1917860 T5240 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1917861 T5240 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1917869 T5240 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1917870 T5240 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1917872 T5240 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1917873 T5240
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1917873 T5240
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1917873 T5240 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1917874 T5240
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1917875 T5240
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1917875 T5240 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 1917875 T5240 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1917875 T5240 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/control/data/
[junit4] 2> 1917876 T5240 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6ba9d877
[junit4] 2> 1917876 T5240 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/control/data
[junit4] 2> 1917876 T5240 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/control/data/index/
[junit4] 2> 1917877 T5240 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/control/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1917877 T5240 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/control/data/index
[junit4] 2> 1917877 T5240 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=56.84765625,
floorSegmentMB=0.4931640625, forceMergeDeletesPctAllowed=23.323399678070498,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1917878 T5240 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-7bf0b9b3)),segFN=segments_1,generation=1}
[junit4] 2> 1917878 T5240 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1917881 T5240 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1917882 T5240 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1917882 T5240 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1917882 T5240 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1917882 T5240 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1917882 T5240 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1917883 T5240 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1917883 T5240 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1917883 T5240 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1917884 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1917884 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1917885 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1917885 T5240 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1917886 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 1917886 T5240 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1917886 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 1917886 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 1917887 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1917887 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1917887 T5240 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1917889 T5240 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1917889 T5240 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1917890 T5240 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1917890 T5240 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1917892 T5240 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1917892 T5240 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1917892 T5240 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.1845703125,
floorSegmentMB=1.9814453125, forceMergeDeletesPctAllowed=24.43116583593486,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1917893 T5240 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-7bf0b9b3)),segFN=segments_1,generation=1}
[junit4] 2> 1917893 T5240 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1917893 T5240 oass.SolrIndexSearcher.<init> Opening
Searcher@2fa1b396[collection1] main
[junit4] 2> 1917893 T5240 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1917894 T5240 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1917894 T5240 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1917894 T5240
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1917895 T5240 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1917895 T5240 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1917895 T5240
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1917896 T5240 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1917896 T5240 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 1917899 T5240
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 1917899 T5240 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1917899 T5240 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1917900 T5242 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@2fa1b396[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1917901 T5240 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1917902 T5245 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:57166/_/gq
collection:control_collection shard:shard1
[junit4] 2> 1917902 T5212 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1917902 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1917903 T5245 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1917904 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1917905 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1917906 T5247 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23dc0370
name:ZooKeeperConnection Watcher:127.0.0.1:59745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1917906 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1917906 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1917907 T5212
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1917907 T5245 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1917908 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1917909 T5245
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1917909 T5245 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1854 name=collection1
org.apache.solr.core.SolrCore@22a9990f
url=https://127.0.0.1:57166/_/gq/collection1 node=127.0.0.1:57166__%2Fgq
C1854_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:57166/_/gq, node_name=127.0.0.1:57166__%2Fgq,
state=down}
[junit4] 2> 1917909 T5245 C1854 P57166 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:57166/_/gq/collection1/
[junit4] 2> 1917909 T5245 C1854 P57166 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1917909 T5212 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 1917909 T5245 C1854 P57166 oasc.SyncStrategy.syncToMe
https://127.0.0.1:57166/_/gq/collection1/ has no replicas
[junit4] 2> 1917910 T5212 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 1917910 T5245 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:57166/_/gq/collection1/ shard1
[junit4] 2> 1917910 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1917910 T5245 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1917911 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1917912 T5249 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6b494a51
name:ZooKeeperConnection Watcher:127.0.0.1:59745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1917912 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1917913 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1917914 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1917916 T5237
oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection:
collection1
[junit4] 2> 1917917 T5237
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 1917917 T5237
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 1917918 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1917918 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 1917919 T5241 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> 1917920 T5250 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> 1917965 T5245 oasc.ZkController.register We are
https://127.0.0.1:57166/_/gq/collection1/ and leader is
https://127.0.0.1:57166/_/gq/collection1/
[junit4] 2> 1917965 T5245 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:57166/_/gq
[junit4] 2> 1917965 T5245 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1917965 T5245 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 1917965 T5245 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1917966 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1917968 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:57166/_/gq";,
[junit4] 2> "node_name":"127.0.0.1:57166__%2Fgq",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1918070 T5250 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> 1918070 T5241 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> 1918165 T5212 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 1918166 T5212 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1918169 T5212 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1918169 T5212 oejs.AbstractConnector.doStart Started
[email protected]:42758
[junit4] 2> 1918171 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 1918171 T5212 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1918171 T5212 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003
[junit4] 2> 1918171 T5212 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/'
[junit4] 2> 1918183 T5212 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/solr.xml
[junit4] 2> 1918189 T5212 oasc.CoreContainer.<init> New CoreContainer
489221771
[junit4] 2> 1918189 T5212 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/]
[junit4] 2> 1918190 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1918190 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1918190 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1918190 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1918190 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1918191 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1918191 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1918191 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1918191 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1918192 T5212 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1918193 T5212 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1918193 T5212 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1918193 T5212 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1918194 T5212 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59745/solr
[junit4] 2> 1918194 T5212 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1918194 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1918195 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1918196 T5261 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@25b29e4f
name:ZooKeeperConnection Watcher:127.0.0.1:59745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1918196 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1918196 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1918201 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1918202 T5263 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2d20f20a
name:ZooKeeperConnection Watcher:127.0.0.1:59745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1918202 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1918206 T5212
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1919209 T5212 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42758__%2Fgq
[junit4] 2> 1919210 T5212 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:42758__%2Fgq
[junit4] 2> 1919212 T5212 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1919216 T5266 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1919216 T5266 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1919217 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1919217 T5266 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1919217 T5266 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1919218 T5266 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1919218 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42758/_/gq";,
[junit4] 2> "node_name":"127.0.0.1:42758__%2Fgq",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1919218 T5266 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 1919219 T5266 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1919220 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 1919220 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 1919223 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1919223 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1919224 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 1920219 T5266 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1920219 T5266 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1920220 T5266 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1920221 T5266 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1920222 T5266 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1920222 T5266 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/collection1/'
[junit4] 2> 1920223 T5266 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 1920223 T5266 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 1920223 T5266 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 1920245 T5266 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 1920253 T5266 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1920254 T5266 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 1920258 T5266 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1920341 T5266 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1920344 T5266 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1920345 T5266 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1920353 T5266 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1920354 T5266 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1920356 T5266 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1920356 T5266
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1920356 T5266
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1920357 T5266 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1920357 T5266
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1920357 T5266
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1920357 T5266 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1920357 T5266 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1920358 T5266 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty1/
[junit4] 2> 1920358 T5266 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6ba9d877
[junit4] 2> 1920358 T5266 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty1
[junit4] 2> 1920358 T5266 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty1/index/
[junit4] 2> 1920358 T5266 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty1/index'
doesn't exist. Creating new index...
[junit4] 2> 1920359 T5266 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty1/index
[junit4] 2> 1920359 T5266 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=56.84765625,
floorSegmentMB=0.4931640625, forceMergeDeletesPctAllowed=23.323399678070498,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1920359 T5266 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-5151a9df)),segFN=segments_1,generation=1}
[junit4] 2> 1920359 T5266 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1920361 T5266 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1920362 T5266 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1920363 T5266 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1920363 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1920363 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1920363 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1920364 T5266 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1920364 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 1920364 T5266 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1920364 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 1920364 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 1920364 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1920365 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1920365 T5266 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1920366 T5266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1920367 T5266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1920367 T5266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1920368 T5266 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1920369 T5266 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1920369 T5266 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1920370 T5266 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.1845703125,
floorSegmentMB=1.9814453125, forceMergeDeletesPctAllowed=24.43116583593486,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1920370 T5266 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-5151a9df)),segFN=segments_1,generation=1}
[junit4] 2> 1920370 T5266 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1920370 T5266 oass.SolrIndexSearcher.<init> Opening
Searcher@6f81eeef[collection1] main
[junit4] 2> 1920371 T5266 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1920371 T5266 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1920372 T5266 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1920372 T5266
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1920372 T5266 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1920372 T5266 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1920373 T5266
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 1920373 T5266 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1920374 T5266 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 1920374 T5266 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1920375 T5267 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@6f81eeef[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1920377 T5266 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1920378 T5270 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:42758/_/gq collection:collection1
shard:shard2
[junit4] 2> 1920378 T5212 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1920378 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1920378 T5270 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 1920381 T5270 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 1920382 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1920382 T5270
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1920383 T5270 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1855 name=collection1
org.apache.solr.core.SolrCore@59ffd925
url=https://127.0.0.1:42758/_/gq/collection1 node=127.0.0.1:42758__%2Fgq
C1855_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:42758/_/gq, node_name=127.0.0.1:42758__%2Fgq,
state=down}
[junit4] 2> 1920383 T5270 C1855 P42758 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:42758/_/gq/collection1/
[junit4] 2> 1920383 T5270 C1855 P42758 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1920383 T5270 C1855 P42758 oasc.SyncStrategy.syncToMe
https://127.0.0.1:42758/_/gq/collection1/ has no replicas
[junit4] 2> 1920383 T5270 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:42758/_/gq/collection1/ shard2
[junit4] 2> 1920383 T5270 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 1920385 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1920386 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1920386 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 1920389 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1920390 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1920390 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1920391 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1920436 T5270 oasc.ZkController.register We are
https://127.0.0.1:42758/_/gq/collection1/ and leader is
https://127.0.0.1:42758/_/gq/collection1/
[junit4] 2> 1920437 T5270 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:42758/_/gq
[junit4] 2> 1920437 T5270 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1920437 T5270 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1920437 T5270 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1920438 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1920440 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42758/_/gq";,
[junit4] 2> "node_name":"127.0.0.1:42758__%2Fgq",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1920440 T5270 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1920442 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1920443 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1920443 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1920623 T5212 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 1920624 T5212 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1920626 T5212 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1920626 T5212 oejs.AbstractConnector.doStart Started
[email protected]:46711
[junit4] 2> 1920628 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 1920628 T5212 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1920628 T5212 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004
[junit4] 2> 1920628 T5212 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/'
[junit4] 2> 1920640 T5212 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/solr.xml
[junit4] 2> 1920671 T5212 oasc.CoreContainer.<init> New CoreContainer
293034789
[junit4] 2> 1920671 T5212 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/]
[junit4] 2> 1920672 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1920672 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1920672 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1920672 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1920673 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1920673 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1920673 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1920673 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1920674 T5212 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1920675 T5212 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1920676 T5212 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1920676 T5212 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1920676 T5212 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1920677 T5212 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59745/solr
[junit4] 2> 1920677 T5212 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1920677 T5212
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1920678 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1920679 T5281 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6b7be079
name:ZooKeeperConnection Watcher:127.0.0.1:59745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1920680 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1920680 T5212 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1920682 T5212 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1920683 T5283 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1136ed75
name:ZooKeeperConnection Watcher:127.0.0.1:59745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1920683 T5212 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1920687 T5212
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1921690 T5212 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46711__%2Fgq
[junit4] 2> 1921691 T5212 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:46711__%2Fgq
[junit4] 2> 1921697 T5212 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1921703 T5285 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1921703 T5285 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1921705 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1921706 T5285 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1921706 T5285 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1921707 T5285 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1921708 T5285 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1921708 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:46711/_/gq";,
[junit4] 2> "node_name":"127.0.0.1:46711__%2Fgq",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1921708 T5285 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1921711 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 1921712 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 1921717 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1921718 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1921718 T5284 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1921719 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1921719 T5284 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1922709 T5285 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1922709 T5285 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1922710 T5285 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1922710 T5285 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1922711 T5285 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1922711 T5285 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/collection1/'
[junit4] 2> 1922712 T5285 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 1922712 T5285 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 1922713 T5285 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 1922733 T5285 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 1922741 T5285 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1922742 T5285 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 1922745 T5285 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1922840 T5285 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1922844 T5285 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1922845 T5285 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1922856 T5285 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1922858 T5285 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1922860 T5285 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1922861 T5285
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1922861 T5285
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1922861 T5285 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1922862 T5285
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1922862 T5285
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1922862 T5285 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1922863 T5285 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1922863 T5285 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty2/
[junit4] 2> 1922863 T5285 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6ba9d877
[junit4] 2> 1922863 T5285 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty2
[junit4] 2> 1922864 T5285 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty2/index/
[junit4] 2> 1922864 T5285 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty2/index'
doesn't exist. Creating new index...
[junit4] 2> 1922864 T5285 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty2/index
[junit4] 2> 1922864 T5285 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=56.84765625,
floorSegmentMB=0.4931640625, forceMergeDeletesPctAllowed=23.323399678070498,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1922865 T5285 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-73397ad7)),segFN=segments_1,generation=1}
[junit4] 2> 1922865 T5285 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1922867 T5285 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1922867 T5285 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1922867 T5285 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1922868 T5285 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1922868 T5285 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1922868 T5285 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1922869 T5285 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1922869 T5285 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1922869 T5285 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1922870 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1922870 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1922871 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1922871 T5285 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1922872 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 1922872 T5285 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1922873 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 1922873 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 1922874 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1922874 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1922875 T5285 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1922877 T5285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1922878 T5285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1922879 T5285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1922880 T5285 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1922882 T5285 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1922883 T5285 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1922883 T5285 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=43.1845703125,
floorSegmentMB=1.9814453125, forceMergeDeletesPctAllowed=24.43116583593486,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1922884 T5285 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-73397ad7)),segFN=segments_1,generation=1}
[junit4] 2> 1922884 T5285 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1922885 T5285 oass.SolrIndexSearcher.<init> Opening
Searcher@d9a7707[collection1] main
[junit4] 2> 1922885 T5285 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1922886 T5285 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1922886 T5285 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1922887 T5285
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1922887 T5285 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1922887 T5285 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1922889 T5285
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 1922889 T5285 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1922889 T5285 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 1922889 T5285 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1922892 T5286 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@d9a7707[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1922893 T5285 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1922894 T5289 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:46711/_/gq collection:collection1
shard:shard1
[junit4] 2> 1922894 T5212 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1922895 T5289 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 1922895 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1922900 T5289 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1922902 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1922902 T5289
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1922902 T5289 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1856 name=collection1
org.apache.solr.core.SolrCore@16a1d6cf
url=https://127.0.0.1:46711/_/gq/collection1 node=127.0.0.1:46711__%2Fgq
C1856_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:46711/_/gq, node_name=127.0.0.1:46711__%2Fgq,
state=down}
[junit4] 2> 1922903 T5289 C1856 P46711 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:46711/_/gq/collection1/
[junit4] 2> 1922903 T5289 C1856 P46711 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1922903 T5289 C1856 P46711 oasc.SyncStrategy.syncToMe
https://127.0.0.1:46711/_/gq/collection1/ has no replicas
[junit4] 2> 1922903 T5289 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:46711/_/gq/collection1/ shard1
[junit4] 2> 1922903 T5289 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 1922905 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1922906 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1922906 T5284 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1922906 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 1922906 T5284 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 1922909 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1922910 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1922910 T5284 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1922911 T5284 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 7
[junit4] 2> 1922911 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1922911 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 7
[junit4] 2> 1922956 T5289 oasc.ZkController.register We are
https://127.0.0.1:46711/_/gq/collection1/ and leader is
https://127.0.0.1:46711/_/gq/collection1/
[junit4] 2> 1922956 T5289 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:46711/_/gq
[junit4] 2> 1922956 T5289 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1922956 T5289 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1922956 T5289 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1922957 T5241 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1922959 T5289 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 7
[junit4] 2> 1922959 T5237 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:46711/_/gq";,
[junit4] 2> "node_name":"127.0.0.1:46711__%2Fgq",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1922962 T5237 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 1922962 T5265 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1922962 T5284 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1922963 T5284 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 8
[junit4] 2> 1922963 T5265 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 8
[junit4] 2> 1923148 T5212 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 1923148 T5212 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1923151 T5212 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1923151 T5212 oejs.AbstractConnector.doStart Started
[email protected]:43725
[junit4] 2> 1923153 T5212 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 1923153 T5212 oasc.SolrRe
[...truncated too long message...]
ace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty4;done=false>>]
[junit4] 2> 2021580 T5212 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty4
[junit4] 2> 2021580 T5212 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty4/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty4/index;done=false>>]
[junit4] 2> 2021580 T5212 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001/tempDir-001/jetty4/index
[junit4] 2> 2021581 T5212 oasc.Overseer.close Overseer
(id=92519858154242062-127.0.0.1:49092__%2Fgq-n_0000000004) closing
[junit4] 2> 2021582 T5400 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:49092__%2Fgq
[junit4] 2> 2021583 T5395 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2021606 T5212 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/_/gq,null}
[junit4] 2> 2026596 T5212 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 2026597 T5212 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:59745 59745
[junit4] 2> 2027288 T5213 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:59745 59745
[junit4] 2> 2027290 T5213 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> 4 /solr/collections/collection1/state.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=1B77DB753592D726 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=mk_MK -Dtests.timezone=Indian/Chagos
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 111s J0 |
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([1B77DB753592D726:9A91556D42CDB71A]:0)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertFail(CloudExitableDirectoryReaderTest.java:101)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:81)
[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> 2027307 T5212 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest-1B77DB753592D726-001
[junit4] 2> 111078 T5211 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{_version_=PostingsFormat(name=Direct),
multiDefault=PostingsFormat(name=MockRandom),
name=PostingsFormat(name=Lucene41VarGapFixedInterval),
intDefault=PostingsFormat(name=Direct),
id=PostingsFormat(name=Lucene41VarGapFixedInterval),
range_facet_l=PostingsFormat(name=MockRandom),
timestamp=PostingsFormat(name=MockRandom)},
docValues:{timestamp=DocValuesFormat(name=Lucene410)},
sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=mk_MK,
timezone=Indian/Chagos
[junit4] 2> NOTE: Linux 3.13.0-36-generic amd64/Oracle Corporation
1.9.0-ea (64-bit)/cpus=8,threads=2,free=216627528,total=507260928
[junit4] 2> NOTE: All tests run in this JVM: [TestSort,
DistributedFacetPivotLargeTest, TestFieldTypeResource,
TestManagedStopFilterFactory, TestBinaryResponseWriter,
OutOfBoxZkACLAndCredentialsProvidersTest, FileUtilsTest, ReturnFieldsTest,
PathHierarchyTokenizerFactoryTest, ZkCLITest, FastVectorHighlighterTest,
EnumFieldTest, TestNonDefinedSimilarityFactory, TestTrie,
TestShardHandlerFactory, SignatureUpdateProcessorFactoryTest,
TestSchemaResource, ShardRoutingTest, TestFaceting, ClusterStateUpdateTest,
DistributedSuggestComponentTest, TestReplicationHandler,
TestLMJelinekMercerSimilarityFactory, OpenExchangeRatesOrgProviderTest,
TestSolr4Spatial, SyncSliceTest, TestSerializedLuceneMatchVersion,
TestCloudInspectUtil, TestStressReorder, DistributedExpandComponentTest,
HdfsUnloadDistributedZkTest, TestFastLRUCache, TestFuzzyAnalyzedSuggestions,
MoreLikeThisHandlerTest, TestSimpleQParserPlugin, NotRequiredUniqueKeyTest,
DocValuesMultiTest, BasicFunctionalityTest, TestDocumentBuilder,
TestExceedMaxTermLength, TestCollapseQParserPlugin, DistribCursorPagingTest,
SpellPossibilityIteratorTest, TestReRankQParserPlugin, TestRemoteStreaming,
PrimitiveFieldTypeTest, TestSolrQueryParserDefaultOperatorResource,
TestDefaultSimilarityFactory, TestStressUserVersions, NoCacheHeaderTest,
TestWordDelimiterFilterFactory, SolrIndexSplitterTest, SuggesterTSTTest,
CachingDirectoryFactoryTest, TestCollationFieldDocValues,
BasicDistributedZkTest, LeaderElectionTest, AtomicUpdatesTest, PeerSyncTest,
TestSuggestSpellingConverter, TestFoldingMultitermQuery,
RegexBoostProcessorTest, StatelessScriptUpdateProcessorFactoryTest,
JSONWriterTest, EchoParamsTest, OverseerStatusTest, TestDocSet, BlockCacheTest,
TestSolrQueryParserResource, HdfsBasicDistributedZkTest,
TestSolrDeletionPolicy2, SpellCheckCollatorTest, ChangedSchemaMergeTest,
TestSystemIdResolver, SpellCheckComponentTest, HighlighterTest, TestLRUCache,
TestConfigSets, UpdateParamsTest, DocumentBuilderTest, QueryEqualityTest,
XsltUpdateRequestHandlerTest, SolrCmdDistributorTest,
TestLMDirichletSimilarityFactory, ShowFileRequestHandlerTest,
TestIntervalFaceting, DirectSolrSpellCheckerTest, TestSolrCoreProperties,
DisMaxRequestHandlerTest, DistribDocExpirationUpdateProcessorTest,
TestRawResponseWriter, InfoHandlerTest, TestSolrIndexConfig,
DocValuesMissingTest, DeleteReplicaTest, ExternalCollectionsTest,
RollingRestartTest, TestSolrXmlPersistence, RequiredFieldsTest,
HdfsWriteToMultipleCollectionsTest, TestManagedResource, BadIndexSchemaTest,
SimpleFacetsTest, HdfsBasicDistributedZk2Test, AutoCommitTest,
TestHashPartitioner, TermVectorComponentDistributedTest, TestStressVersions,
TestRangeQuery, TestCoreContainer, SolrCoreTest, QueryElevationComponentTest,
ConvertedLegacyTest, TestFunctionQuery, TestLazyCores, DirectUpdateHandlerTest,
OverseerCollectionProcessorTest, SoftAutoCommitTest,
CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest,
SolrCoreCheckLockOnStartupTest, TestUpdate, DirectUpdateHandlerOptimizeTest,
SortByFunctionTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest,
DocumentAnalysisRequestHandlerTest, XmlUpdateRequestHandlerTest,
TermVectorComponentTest, TestArbitraryIndexDir, IndexSchemaRuntimeFieldTest,
SolrPluginUtilsTest, TestJmxIntegration, MBeansHandlerTest, JsonLoaderTest,
TestPartialUpdateDeduplication, PingRequestHandlerTest, SearchHandlerTest,
SOLR749Test, ResponseLogComponentTest, BadComponentTest, TestStressRecovery,
SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, TestSolrJ,
ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, CircularListTest,
TestRTGBase, CursorPagingTest, AliasIntegrationTest,
CloudExitableDirectoryReaderTest]
[junit4] Completed on J0 in 111.61s, 1 test, 1 failure <<< FAILURES!
[...truncated 289 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: 95 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.9.0-ea-b28
-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]
