Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11615/
Java: 64bit/jdk1.9.0-ea-b44 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionTest.testDistribSearch
Error Message:
Captured an uncaught exception in thread: Thread[id=9172,
name=OverseerStateUpdate-93153374336712716-127.0.0.1:43758_-n_0000000004,
state=RUNNABLE, group=Overseer state updater.]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9172,
name=OverseerStateUpdate-93153374336712716-127.0.0.1:43758_-n_0000000004,
state=RUNNABLE, group=Overseer state updater.]
Caused by: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([9A8FDA743E8D7463]:0)
at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:534)
at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:258)
at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:233)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9692 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/init-core-data-001
[junit4] 2> 1417871 T8730
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 1417873 T8730 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 1417874 T8730 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1417874 T8731 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1417974 T8730 oasc.ZkTestServer.run start zk server on
port:50688
[junit4] 2> 1417975 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1417975 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1417977 T8738 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3d13fb06
name:ZooKeeperConnection Watcher:127.0.0.1:50688 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1417978 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1417978 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1417978 T8730 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1417980 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1417981 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1417981 T8741 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@9542a54 name:ZooKeeperConnection
Watcher:127.0.0.1:50688/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1417982 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1417982 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1417982 T8730 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1417984 T8730 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1417985 T8730 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1417986 T8730 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1417986 T8730 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> 1417987 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1417988 T8730 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> 1417989 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1417990 T8730 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> 1417990 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1417992 T8730 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> 1417993 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1417994 T8730 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> 1417994 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1417995 T8730 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> 1417996 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1417997 T8730 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> 1417998 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1418000 T8730 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> 1418000 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1418002 T8730 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> 1418002 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1418003 T8730 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> 1418003 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1418005 T8730 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> 1418005 T8730 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1418253 T8730 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1418255 T8730 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@7174ad95{/,null,AVAILABLE}
[junit4] 2> 1418255 T8730 oejs.AbstractConnector.doStart Started
ServerConnector@643daf2a{HTTP/1.1}{127.0.0.1:57210}
[junit4] 2> 1418256 T8730 oejs.Server.doStart Started @1419297ms
[junit4] 2> 1418256 T8730 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@58d25a40
[junit4] 2> 1418257 T8730 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1418257 T8730 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-002
[junit4] 2> 1418257 T8730 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-002/'
[junit4] 2> 1418271 T8730 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-002/solr.xml
[junit4] 2> 1418278 T8730 oasc.CoreContainer.<init> New CoreContainer
1111087727
[junit4] 2> 1418278 T8730 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-002/]
[junit4] 2> 1418278 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1418279 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1418279 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1418279 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1418279 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1418279 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1418280 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1418280 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1418280 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1418280 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1418280 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 1418281 T8730 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1418281 T8730 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1418281 T8730 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1418282 T8730 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1418282 T8730 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:50688/solr
[junit4] 2> 1418282 T8730 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1418282 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1418283 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1418284 T8755 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@48055513
name:ZooKeeperConnection Watcher:127.0.0.1:50688 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1418284 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1418284 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1418285 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1418286 T8758 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@282d06d6
name:ZooKeeperConnection Watcher:127.0.0.1:50688/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1418286 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1418287 T8730 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 1418289 T8730 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 1418290 T8730 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 1418291 T8730 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 1418293 T8730 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 1418294 T8730 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1418295 T8730 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39412_
[junit4] 2> 1418295 T8730 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:39412_
[junit4] 2> 1418296 T8730 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 1418297 T8730 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 1418298 T8730 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1418298 T8730 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:39412_
[junit4] 2> 1418299 T8730 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 1418300 T8730 oasc.Overseer.start Overseer
(id=93153374336712707-127.0.0.1:39412_-n_0000000000) starting
[junit4] 2> 1418301 T8730 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 1418304 T8730 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1418304 T8760 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 1418305 T8730 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 1418306 T8730 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 1418306 T8730
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1418308 T8759 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 1418309 T8762 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 1418309 T8762 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1418310 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1418310 T8762 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1418312 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39412";,
[junit4] 2> "node_name":"127.0.0.1:39412_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1418312 T8759 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39412";,
[junit4] 2> "node_name":"127.0.0.1:39412_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1418312 T8759 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 1418312 T8759 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1418313 T8758 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> 1419311 T8762 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1419311 T8762 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 1419311 T8762 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1419312 T8762 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1419313 T8762 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1419313 T8762 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-002/collection1/'
[junit4] 2> 1419313 T8762 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 1419313 T8762 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 1419314 T8762 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 1419323 T8762 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1419325 T8762 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1419329 T8762 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1419338 T8762 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 1419348 T8762 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1419349 T8762 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1419353 T8762 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1419437 T8762 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1419440 T8762 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1419441 T8762 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1419446 T8762 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1419448 T8762 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1419449 T8762 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1419450 T8762
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1419450 T8762
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1419450 T8762 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1419451 T8762
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1419451 T8762
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1419451 T8762 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 1419451 T8762 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1419451 T8762 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/control/data/
[junit4] 2> 1419452 T8762 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f34c716
[junit4] 2> 1419452 T8762 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/control/data
[junit4] 2> 1419452 T8762 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/control/data/index/
[junit4] 2> 1419452 T8762 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 1419452 T8762 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/control/data/index
[junit4] 2> 1419453 T8762 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1419453 T8762 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4ad8063c
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@626624f7),segFN=segments_1,generation=1}
[junit4] 2> 1419453 T8762 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1419455 T8762 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1419455 T8762 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1419455 T8762 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1419455 T8762 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1419455 T8762 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1419456 T8762 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1419456 T8762 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1419456 T8762 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1419456 T8762 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1419456 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1419457 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1419457 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1419457 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1419457 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1419457 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1419458 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1419458 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1419458 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1419458 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1419459 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1419459 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1419459 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1419459 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1419460 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1419460 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1419460 T8762 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1419460 T8762 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1419462 T8762 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1419462 T8762 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1419463 T8762 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1419463 T8762 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1419464 T8762 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1419464 T8762 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1419465 T8762 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1419465 T8762 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=43, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=18.9052734375,
floorSegmentMB=1.9033203125, forceMergeDeletesPctAllowed=18.081659211030434,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8116281600196091
[junit4] 2> 1419465 T8762 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4ad8063c
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@626624f7),segFN=segments_1,generation=1}
[junit4] 2> 1419465 T8762 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1419466 T8762 oass.SolrIndexSearcher.<init> Opening
Searcher@55a6f749[collection1] main
[junit4] 2> 1419466 T8762 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1419467 T8762 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1419467 T8762 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1419467 T8762
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1419467 T8762 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1419467 T8762 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1419468 T8762
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1419468 T8762 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1419468 T8762 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1419468 T8762 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1419468 T8762 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1419469 T8763 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@55a6f749[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1419469 T8766 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:39412 collection:control_collection
shard:shard1
[junit4] 2> 1419469 T8730 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1419469 T8730 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1419470 T8766 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1419471 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1419472 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1419472 T8766 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1419472 T8770 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@584a1499
name:ZooKeeperConnection Watcher:127.0.0.1:50688/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1419473 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1419473 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1419473 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1419473 T8766
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1419473 T8766 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 1419473 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 1419474 T8730
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> ASYNC NEW_CORE C4643 name=collection1
org.apache.solr.core.SolrCore@49f4794a url=http://127.0.0.1:39412/collection1
node=127.0.0.1:39412_ C4643_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:39412,
node_name=127.0.0.1:39412_, state=down}
[junit4] 2> 1419474 T8766 C4643 P39412 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:39412/collection1/
[junit4] 2> 1419474 T8766 C4643 P39412 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1419474 T8766 C4643 P39412 oasc.SyncStrategy.syncToMe
http://127.0.0.1:39412/collection1/ has no replicas
[junit4] 2> 1419475 T8766 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:39412/collection1/ shard1
[junit4] 2> 1419475 T8766 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1419475 T8730 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 1419476 T8730 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 1419476 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1419476 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1419477 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1419477 T8773 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7aa23e69
name:ZooKeeperConnection Watcher:127.0.0.1:50688/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1419477 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1419477 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:39412";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1419477 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1419482 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 1
[junit4] 2> 1419482 T8759 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 1419483 T8758 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> 1419483 T8770 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> 1419485 T8774 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1419485 T8759 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 1419486 T8770 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> 1419527 T8766 oasc.ZkController.register We are
http://127.0.0.1:39412/collection1/ and leader is
http://127.0.0.1:39412/collection1/
[junit4] 2> 1419528 T8766 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:39412
[junit4] 2> 1419528 T8766 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1419528 T8766 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 1419528 T8766 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1419529 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1419530 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39412";,
[junit4] 2> "node_name":"127.0.0.1:39412_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1419530 T8759 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39412";,
[junit4] 2> "node_name":"127.0.0.1:39412_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1419632 T8758 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> 1419632 T8770 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> 1419726 T8730 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 1419727 T8730 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1419729 T8730 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@6f7c9f26{/,null,AVAILABLE}
[junit4] 2> 1419729 T8730 oejs.AbstractConnector.doStart Started
ServerConnector@642e49f1{HTTP/1.1}{127.0.0.1:53458}
[junit4] 2> 1419729 T8730 oejs.Server.doStart Started @1420771ms
[junit4] 2> 1419730 T8730 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@58d25a40
[junit4] 2> 1419730 T8730 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1419730 T8730 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-003
[junit4] 2> 1419730 T8730 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-003/'
[junit4] 2> 1419741 T8730 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-003/solr.xml
[junit4] 2> 1419747 T8730 oasc.CoreContainer.<init> New CoreContainer
1208983481
[junit4] 2> 1419747 T8730 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-003/]
[junit4] 2> 1419748 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1419748 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1419748 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1419748 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1419749 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1419749 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1419749 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1419749 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1419749 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1419750 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1419750 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 1419750 T8730 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1419751 T8730 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1419751 T8730 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1419751 T8730 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1419751 T8730 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:50688/solr
[junit4] 2> 1419751 T8730 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1419752 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1419752 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1419753 T8788 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@54112f7e
name:ZooKeeperConnection Watcher:127.0.0.1:50688 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1419753 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1419754 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1419755 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1419756 T8791 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@36c7b07a
name:ZooKeeperConnection Watcher:127.0.0.1:50688/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1419756 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1419758 T8730
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1420761 T8730 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40892_
[junit4] 2> 1420762 T8730 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:40892_
[junit4] 2> 1420764 T8730 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1420767 T8792 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1420767 T8792 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1420768 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1420768 T8792 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1420768 T8792 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1420768 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40892";,
[junit4] 2> "node_name":"127.0.0.1:40892_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1420768 T8792 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1420769 T8759 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40892";,
[junit4] 2> "node_name":"127.0.0.1:40892_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1420769 T8759 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1420769 T8759 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 1420770 T8792 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 1420770 T8792 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1420872 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1420873 T8791 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1420874 T8791 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 1421771 T8792 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1421771 T8792 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1421771 T8792 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1421772 T8792 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1421772 T8792 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1421772 T8792 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-003/collection1/'
[junit4] 2> 1421773 T8792 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 1421774 T8792 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 1421774 T8792 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 1421788 T8792 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1421789 T8792 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1421794 T8792 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1421804 T8792 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 1421812 T8792 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1421814 T8792 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1421818 T8792 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1421905 T8792 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1421908 T8792 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1421909 T8792 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1421917 T8792 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1421919 T8792 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1421920 T8792 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1421920 T8792
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1421921 T8792
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1421921 T8792 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1421921 T8792
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1421922 T8792
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1421922 T8792 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1421922 T8792 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1421922 T8792 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty1/
[junit4] 2> 1421922 T8792 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f34c716
[junit4] 2> 1421923 T8792 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty1
[junit4] 2> 1421923 T8792 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty1/index/
[junit4] 2> 1421923 T8792 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 1421923 T8792 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty1/index
[junit4] 2> 1421923 T8792 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1421924 T8792 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@118b918a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f5abf08),segFN=segments_1,generation=1}
[junit4] 2> 1421924 T8792 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1421926 T8792 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1421926 T8792 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1421926 T8792 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1421926 T8792 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1421926 T8792 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1421926 T8792 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1421927 T8792 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1421927 T8792 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1421927 T8792 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1421927 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1421928 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1421928 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1421928 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1421928 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1421929 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1421929 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1421929 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1421929 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1421930 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1421930 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1421931 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1421931 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1421931 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1421931 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1421932 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1421932 T8792 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1421932 T8792 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1421934 T8792 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1421934 T8792 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1421935 T8792 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1421935 T8792 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1421936 T8792 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1421936 T8792 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1421936 T8792 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1421937 T8792 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=43, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=18.9052734375,
floorSegmentMB=1.9033203125, forceMergeDeletesPctAllowed=18.081659211030434,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8116281600196091
[junit4] 2> 1421937 T8792 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@118b918a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2f5abf08),segFN=segments_1,generation=1}
[junit4] 2> 1421937 T8792 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1421937 T8792 oass.SolrIndexSearcher.<init> Opening
Searcher@731241da[collection1] main
[junit4] 2> 1421937 T8792 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1421938 T8792 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1421938 T8792 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1421939 T8792
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1421939 T8792 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1421939 T8792 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1421939 T8792
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1421939 T8792 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1421940 T8792 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1421940 T8792 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1421940 T8793 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@731241da[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1421940 T8792 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1421941 T8796 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:40892 collection:collection1
shard:shard2
[junit4] 2> 1421941 T8730 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1421941 T8730 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1421942 T8796 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 1421944 T8796 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 1421945 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1421945 T8796
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1421945 T8796 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4644 name=collection1
org.apache.solr.core.SolrCore@108630bc url=http://127.0.0.1:40892/collection1
node=127.0.0.1:40892_ C4644_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:40892,
node_name=127.0.0.1:40892_, state=down}
[junit4] 2> 1421946 T8796 C4644 P40892 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:40892/collection1/
[junit4] 2> 1421946 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 1421946 T8796 C4644 P40892 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1421946 T8796 C4644 P40892 oasc.SyncStrategy.syncToMe
http://127.0.0.1:40892/collection1/ has no replicas
[junit4] 2> 1421946 T8796 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:40892/collection1/ shard2
[junit4] 2> 1421947 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1421947 T8796 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 1421947 T8791 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1421947 T8791 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 1421949 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1421950 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:40892";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1421950 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 1421950 T8791 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1421951 T8791 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1422000 T8796 oasc.ZkController.register We are
http://127.0.0.1:40892/collection1/ and leader is
http://127.0.0.1:40892/collection1/
[junit4] 2> 1422000 T8796 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:40892
[junit4] 2> 1422000 T8796 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1422000 T8796 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1422000 T8796 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1422001 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1422002 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40892";,
[junit4] 2> "node_name":"127.0.0.1:40892_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1422002 T8759 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40892";,
[junit4] 2> "node_name":"127.0.0.1:40892_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1422003 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 1422003 T8796 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1422003 T8791 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1422004 T8791 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1422188 T8730 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 1422188 T8730 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1422190 T8730 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@28803350{/,null,AVAILABLE}
[junit4] 2> 1422190 T8730 oejs.AbstractConnector.doStart Started
ServerConnector@20be93e8{HTTP/1.1}{127.0.0.1:35351}
[junit4] 2> 1422191 T8730 oejs.Server.doStart Started @1423232ms
[junit4] 2> 1422191 T8730 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@58d25a40
[junit4] 2> 1422191 T8730 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1422192 T8730 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-004
[junit4] 2> 1422192 T8730 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-004/'
[junit4] 2> 1422202 T8730 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-004/solr.xml
[junit4] 2> 1422208 T8730 oasc.CoreContainer.<init> New CoreContainer
1667030443
[junit4] 2> 1422209 T8730 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-004/]
[junit4] 2> 1422209 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1422209 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1422209 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1422210 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1422210 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1422210 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1422210 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1422211 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1422211 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1422211 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1422211 T8730 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 1422212 T8730 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1422212 T8730 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1422212 T8730 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1422212 T8730 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1422213 T8730 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:50688/solr
[junit4] 2> 1422213 T8730 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1422213 T8730
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1422214 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1422215 T8811 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2c7c5def
name:ZooKeeperConnection Watcher:127.0.0.1:50688 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1422215 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1422215 T8730 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1422216 T8730 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1422217 T8814 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1ac518be
name:ZooKeeperConnection Watcher:127.0.0.1:50688/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1422217 T8730 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1422222 T8730
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1423226 T8730 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44076_
[junit4] 2> 1423226 T8730 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44076_
[junit4] 2> 1423228 T8730 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1423231 T8815 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1423231 T8815 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1423232 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1423232 T8815 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1423232 T8815 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1423232 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:44076";,
[junit4] 2> "node_name":"127.0.0.1:44076_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1423232 T8815 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1423233 T8759 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:44076";,
[junit4] 2> "node_name":"127.0.0.1:44076_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1423233 T8759 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1423233 T8759 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1423233 T8815 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1423233 T8815 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1423234 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 1423235 T8814 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1423235 T8814 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1423236 T8791 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1423236 T8791 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1424233 T8815 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1424234 T8815 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1424234 T8815 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1424234 T8815 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1424235 T8815 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1424235 T8815 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-004/collection1/'
[junit4] 2> 1424236 T8815 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 1424236 T8815 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 1424236 T8815 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%209A8FDA743E8D7463-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 1424248 T8815 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1424249 T8815 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1424264 T8815 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1424269 T8815 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 1424280 T8815 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1424281 T8815 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1424286 T8815 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1424406 T8815 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1424410 T8815 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1424412 T8815 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1424418 T8815 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1424420 T8815 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1424421 T8815 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1424422 T8815
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1424422 T8815
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1424422 T8815 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1424423 T8815
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1424423 T8815
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1424423 T8815 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1424423 T8815 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1424424 T8815 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty2/
[junit4] 2> 1424424 T8815 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f34c716
[junit4] 2> 1424424 T8815 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty2
[junit4] 2> 1424424 T8815 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty2/index/
[junit4] 2> 1424425 T8815 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.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 1424425 T8815 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
9A8FDA743E8D7463-001/tempDir-001/jetty2/index
[junit4] 2> 1424425 T8815 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1424426 T8815 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@75af6d25
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41cc7257),segFN=segments_1,generation=1}
[junit4] 2> 1424426 T8815 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1424428 T8815 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1424428 T8815 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1424428 T8815 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1424429 T8815 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1424429 T8815 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1424429 T8815 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1424429 T8815 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1424429 T8815 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1424430 T8815 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1424430 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424430 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424431 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424431 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424431 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1424431 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1424432 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1424432 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1424432 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1424433 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1424434 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1424434 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1424434 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1424435 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1424435 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1424435 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1424436 T8815 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1424436 T8815 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1424438 T8815 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424438 T8815 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424439 T8815 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424440 T8815 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424440 T8815 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1424441 T8815 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1424441 T8815 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1424441 T8815 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=43, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=18.9052734375,
floorSegmentMB=1.9033203125, forceMergeDeletesPctAllowed=18.081659211030434,
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8116281600196091
[junit4] 2> 1424442 T8815 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@75af6d25
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41cc7257),segFN=segments_1,generation=1}
[junit4] 2> 1424442 T8815 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1424442 T8815 oass.SolrIndexSearcher.<init> Opening
Searcher@5ac0312f[collection1] main
[junit4] 2> 1424442 T8815 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1424443 T8815 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1424443 T8815 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1424443 T8815
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1424443 T8815 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1424444 T8815 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1424444 T8815
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1424444 T8815 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1424444 T8815 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1424444 T8815 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1424445 T8816 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5ac0312f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1424445 T8815 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1424446 T8819 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:44076 collection:collection1
shard:shard1
[junit4] 2> 1424446 T8730 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1424446 T8730 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1424446 T8819 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 1424449 T8819 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1424450 T8758 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1424450 T8819
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1424450 T8819 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4645 name=collection1
org.apache.solr.core.SolrCore@5fecee81 url=http://127.0.0.1:44076/collection1
node=127.0.0.1:44076_ C4645_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:44076,
node_name=127.0.0.1:44076_, state=down}
[junit4] 2> 1424450 T8819 C4645 P44076 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:44076/collection1/
[junit4] 2> 1424450 T8819 C4645 P44076 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1424451 T8819 C4645 P44076 oasc.SyncStrategy.syncToMe
http://127.0.0.1:44076/collection1/ has no replicas
[junit4] 2> 1424451 T8819 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:44076/collection1/ shard1
[junit4] 2> 1424451 T8819 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 1424451 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 1424452 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 5
[junit4] 2> 1424452 T8791 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1424453 T8814 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1424453 T8791 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 1424453 T8814 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 1424459 T8759 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:44076";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1424460 T8759 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json
[...truncated too long message...]
FDA743E8D7463]:0)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:534)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:258)
[junit4] > at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:233)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #31:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9292,
name=OverseerStateUpdate-93153374336712716-127.0.0.1:43758_-n_0000000034,
state=RUNNABLE, group=Overseer state updater.]
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([9A8FDA743E8D7463]:0)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:534)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:258)
[junit4] > at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:233)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #32:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9296,
name=OverseerStateUpdate-93153374336712716-127.0.0.1:43758_-n_0000000035,
state=RUNNABLE, group=Overseer state updater.]
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([9A8FDA743E8D7463]:0)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:534)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:258)
[junit4] > at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:233)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #33:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9300,
name=OverseerStateUpdate-93153374336712716-127.0.0.1:43758_-n_0000000036,
state=RUNNABLE, group=Overseer state updater.]
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([9A8FDA743E8D7463]:0)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:534)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:258)
[junit4] > at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:233)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #34:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9305,
name=OverseerStateUpdate-93153374336712716-127.0.0.1:43758_-n_0000000037,
state=RUNNABLE, group=Overseer state updater.]
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([9A8FDA743E8D7463]:0)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:534)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:258)
[junit4] > at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:233)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1523246 T8730 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{_version_=PostingsFormat(name=Memory doPackFST= false),
multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval),
a_t=PostingsFormat(name=Memory doPackFST= false),
intDefault=PostingsFormat(name=Memory doPackFST= false),
id=PostingsFormat(name=LuceneVarGapFixedInterval),
text=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Memory
doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false)},
docValues:{timestamp=DocValuesFormat(name=Asserting)},
sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=en_US,
timezone=Europe/Warsaw
[junit4] 2> NOTE: Linux 3.13.0-43-generic amd64/Oracle Corporation
1.9.0-ea (64-bit)/cpus=8,threads=1,free=97142696,total=259731456
[junit4] 2> NOTE: All tests run in this JVM: [TestLeaderElectionZkExpiry,
RemoteQueryErrorTest, AnalysisAfterCoreReloadTest, TestInitParams,
TestDFRSimilarityFactory, MinimalSchemaTest, TestIBSimilarityFactory,
SolrIndexSplitterTest, DOMUtilTest, AddBlockUpdateTest, PreAnalyzedFieldTest,
TestComplexPhraseQParserPlugin, OpenExchangeRatesOrgProviderTest,
TestSearcherReuse, SimpleCollectionCreateDeleteTest, ZkControllerTest,
TestNonDefinedSimilarityFactory, TestSimpleQParserPlugin, TestCoreContainer,
SuggesterWFSTTest, TestInfoStreamLogging, RequestHandlersTest, TestUpdate,
TestSolrConfigHandlerCloud, TestFastOutputStream,
DefaultValueUpdateProcessorTest, TestSolrQueryParserDefaultOperatorResource,
TestSerializedLuceneMatchVersion, TestQuerySenderListener, TestCodecSupport,
LegacyHTMLStripCharFilterTest, TestShortCircuitedRequests,
TestQuerySenderNoQuery, HdfsChaosMonkeySafeLeaderTest,
DocExpirationUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest,
QueryElevationComponentTest, TestSortingResponseWriter,
TestObjectReleaseTracker, TestSolrQueryParserResource,
CoreContainerCoreInitFailuresTest, SolrPluginUtilsTest, HdfsRecoveryZkTest,
TestArbitraryIndexDir, FieldAnalysisRequestHandlerTest,
TestSurroundQueryParser, ExitableDirectoryReaderTest, TestStressUserVersions,
AsyncMigrateRouteKeyTest, SolrTestCaseJ4Test, HdfsSyncSliceTest,
NoCacheHeaderTest, HdfsDirectoryFactoryTest, TestStressReorder,
HardAutoCommitTest, SyncSliceTest, TestInitQParser, TestSchemaVersionResource,
TestFaceting, DeleteInactiveReplicaTest, DistanceFunctionTest,
TestOmitPositions, TestLMDirichletSimilarityFactory, DirectUpdateHandlerTest,
CoreAdminCreateDiscoverTest, TestFastWriter, TestSweetSpotSimilarityFactory,
TestFieldTypeResource, DistributedFacetPivotSmallTest,
DistributedFacetPivotLongTailTest, JSONWriterTest, TestBinaryResponseWriter,
ClusterStateTest, CloudExitableDirectoryReaderTest, AlternateDirectoryTest,
TestClassNameShortening, OverseerTest, BasicDistributedZkTest, SolrCoreTest,
MultiTermTest, TestStressRecovery, DistributedDebugComponentTest,
TestExtendedDismaxParser, TestCollapseQParserPlugin,
TestPartialUpdateDeduplication, TestUniqueKeyFieldResource,
TestImplicitCoreProperties, TestStressLucene, CircularListTest,
CacheHeaderTest, TestSchemaSimilarityResource, TestCustomSort,
ShardRoutingTest, TestLazyCores, ZkCLITest, TestGroupingSearch,
HdfsWriteToMultipleCollectionsTest, DistributedFacetPivotSmallAdvancedTest,
TestAnalyzedSuggestions, SliceStateTest, DistributedSpellCheckComponentTest,
TestCursorMarkWithoutUniqueKey, DocValuesMissingTest, TestCollationField,
TestClusterStateMutator, LeaderElectionTest, HdfsUnloadDistributedZkTest,
DateMathParserTest, TestMissingGroups, RecoveryZkTest,
TestManagedResourceStorage, IndexSchemaRuntimeFieldTest, BlockCacheTest,
ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest,
FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest,
TestRandomFaceting, TestRandomDVFaceting, AutoCommitTest, TestRealTimeGet,
TestReload, TestStressVersions, PeerSyncTest, BadIndexSchemaTest,
TestFunctionQuery, TestIndexSearcher, SoftAutoCommitTest, SuggesterFSTTest,
SolrRequestParserTest, DocValuesTest, SuggesterTest, SpatialFilterTest,
TestCSVLoader, SolrCoreCheckLockOnStartupTest,
FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory,
DirectUpdateHandlerOptimizeTest, SortByFunctionTest, DocValuesMultiTest,
LukeRequestHandlerTest, TestQueryUtils, TestWriterPerf, TestQueryTypes,
FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest,
PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, RequiredFieldsTest,
RegexBoostProcessorTest, TestCSVResponseWriter,
UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter,
PingRequestHandlerTest, TestComponentsName, HighlighterConfigTest,
TestSolrIndexConfig, ResponseLogComponentTest, SolrIndexConfigTest,
TestMergePolicyConfig, TestBinaryField, TestConfig,
TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, TestPhraseSuggestions,
TestXIncludeConfig, TestJmxMonitoredMap, TimeZoneUtilsTest, PluginInfoTest,
ChaosMonkeyNothingIsSafeTest, TestDistributedMissingSort,
TestHighlightDedupGrouping, TestTolerantSearch, AliasIntegrationTest,
ConnectionManagerTest, CustomCollectionTest, HttpPartitionTest]
[junit4] Completed on J0 in 105.72s, 1 test, 1 error <<< FAILURES!
[...truncated 289 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:187: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1348:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:956:
There were test failures: 463 suites, 1871 tests, 1 error, 47 ignored (21
assumptions)
Total time: 62 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.9.0-ea-b44
-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
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]
