Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11687/
Java: 32bit/jdk1.9.0-ea-b34 -server -XX:+UseParallelGC (asserts: true)
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([42F3DF5D51540B5F]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest
Error Message:
Captured an uncaught exception in thread: Thread[id=9912,
name=SocketProxy-Response-40892:34708, state=RUNNABLE,
group=TGRP-HttpPartitionTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9912, name=SocketProxy-Response-40892:34708,
state=RUNNABLE, group=TGRP-HttpPartitionTest]
Caused by: java.lang.RuntimeException: java.net.SocketException: Socket is
closed
at __randomizedtesting.SeedInfo.seed([42F3DF5D51540B5F]:0)
at
org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:344)
Caused by: java.net.SocketException: Socket is closed
at java.net.Socket.setSoTimeout(Socket.java:1139)
at
org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:341)
FAILED: org.apache.solr.cloud.HttpPartitionTest.testDistribSearch
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([42F3DF5D51540B5F]:0)
Build Log:
[...truncated 10605 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/init-core-data-001
[junit4] 2> 1423230 T9527
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 1423233 T9527 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 1423234 T9527 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1423241 T9528 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1423341 T9527 oasc.ZkTestServer.run start zk server on
port:34940
[junit4] 2> 1423342 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1423343 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1423345 T9535 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5004bc name:ZooKeeperConnection
Watcher:127.0.0.1:34940 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1423345 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1423346 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1423346 T9527 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1423350 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1423351 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1423352 T9538 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@ec9ab7 name:ZooKeeperConnection
Watcher:127.0.0.1:34940/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1423352 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1423353 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1423353 T9527 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1423355 T9527 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1423357 T9527 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1423358 T9527 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1423360 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1423360 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1423363 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 1423364 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1423366 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1423367 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1423368 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1423369 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1423371 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1423372 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1423374 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1423375 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1423376 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1423377 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1423378 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1423379 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1423381 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1423382 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1423384 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1423384 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1423386 T9527 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1423387 T9527 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1423654 T9527 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1423665 T9527 oejs.AbstractConnector.doStart Started
[email protected]:34313
[junit4] 2> 1423666 T9527 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 1423666 T9527 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1423667 T9527 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-002
[junit4] 2> 1423667 T9527 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-002/'
[junit4] 2> 1423679 T9527 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-002/solr.xml
[junit4] 2> 1423686 T9527 oasc.CoreContainer.<init> New CoreContainer
11523856
[junit4] 2> 1423687 T9527 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-002/]
[junit4] 2> 1423688 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1423688 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1423688 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1423689 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1423689 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1423689 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1423690 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1423690 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1423690 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1423691 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1423691 T9527 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1423692 T9527 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1423692 T9527 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1423692 T9527 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1423693 T9527 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:34940/solr
[junit4] 2> 1423693 T9527 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1423693 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1423694 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1423695 T9550 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@56b5c4 name:ZooKeeperConnection
Watcher:127.0.0.1:34940 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1423695 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1423696 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1423698 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1423699 T9553 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@176ebeb name:ZooKeeperConnection
Watcher:127.0.0.1:34940/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1423699 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1423700 T9527 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 1423702 T9527 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 1423703 T9527 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 1423705 T9527 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 1423706 T9527 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 1423707 T9527 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1423708 T9527 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51267_
[junit4] 2> 1423709 T9527 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:51267_
[junit4] 2> 1423710 T9527 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 1423711 T9527 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 1423712 T9527 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1423712 T9527 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:51267_
[junit4] 2> 1423713 T9527 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 1423714 T9527 oasc.Overseer.start Overseer
(id=93096122052706307-127.0.0.1:51267_-n_0000000000) starting
[junit4] 2> 1423716 T9527 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 1423723 T9527 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1423724 T9555 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 1423725 T9527 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 1423729 T9527 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 1423731 T9527
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1423735 T9554 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 1423738 T9557 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 1423739 T9557 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1423740 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1423740 T9557 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1423744 T9554 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:51267";,
[junit4] 2> "node_name":"127.0.0.1:51267_",
[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> 1423745 T9554 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:51267";,
[junit4] 2> "node_name":"127.0.0.1:51267_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1423746 T9554 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 1423746 T9554 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1423748 T9553 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> 1424741 T9557 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1424741 T9557 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 1424741 T9557 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1424742 T9557 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1424742 T9557 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1424743 T9557 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-002/collection1/'
[junit4] 2> 1424743 T9557 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 1424743 T9557 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 1424744 T9557 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 1424753 T9557 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1424754 T9557 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1424758 T9557 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1424775 T9557 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1424784 T9557 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1424785 T9557 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1424789 T9557 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1424872 T9557 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1424875 T9557 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1424876 T9557 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1424884 T9557 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1424885 T9557 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1424886 T9557 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1424887 T9557
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1424887 T9557
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1424887 T9557 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1424888 T9557
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1424888 T9557
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1424888 T9557 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 1424888 T9557 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1424889 T9557 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/control/data/
[junit4] 2> 1424889 T9557 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ac042e
[junit4] 2> 1424889 T9557 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/control/data
[junit4] 2> 1424890 T9557 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/control/data/index/
[junit4] 2> 1424890 T9557 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 1424890 T9557 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/control/data/index
[junit4] 2> 1424890 T9557 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=919877551,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1424891 T9557 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@ca1f61
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12b125c),segFN=segments_1,generation=1}
[junit4] 2> 1424891 T9557 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1424893 T9557 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1424893 T9557 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1424893 T9557 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1424893 T9557 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1424893 T9557 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1424894 T9557 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1424894 T9557 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1424894 T9557 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1424894 T9557 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1424895 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424895 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424895 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424895 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1424896 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1424896 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1424896 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1424896 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1424897 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1424897 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1424897 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1424898 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1424898 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1424898 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1424899 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1424899 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1424899 T9557 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1424899 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1424900 T9557 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1424901 T9557 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424902 T9557 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424903 T9557 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424903 T9557 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1424904 T9557 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1424905 T9557 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1424905 T9557 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1424905 T9557 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 1424906 T9557 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@ca1f61
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12b125c),segFN=segments_1,generation=1}
[junit4] 2> 1424906 T9557 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1424906 T9557 oass.SolrIndexSearcher.<init> Opening
Searcher@4ec243[collection1] main
[junit4] 2> 1424906 T9557 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1424907 T9557 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1424908 T9557 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1424908 T9557
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1424908 T9557 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1424908 T9557 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1424909 T9557
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1424909 T9557 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1424909 T9557 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1424909 T9557 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1424910 T9557 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1424910 T9558 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@4ec243[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1424910 T9561 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:51267 collection:control_collection
shard:shard1
[junit4] 2> 1424911 T9527 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1424911 T9527 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1424911 T9561 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1424912 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1424913 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1424913 T9565 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1ee445 name:ZooKeeperConnection
Watcher:127.0.0.1:34940/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1424914 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1424914 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1424915 T9561 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1424915 T9527
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1424915 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1424916 T9561
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1424916 T9561 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 1424916 T9554 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> ASYNC NEW_CORE C3124 name=collection1
org.apache.solr.core.SolrCore@1b86e01 url=http://127.0.0.1:51267/collection1
node=127.0.0.1:51267_ C3124_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:51267,
node_name=127.0.0.1:51267_, state=down}
[junit4] 2> 1424916 T9561 C3124 P51267 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:51267/collection1/
[junit4] 2> 1424916 T9561 C3124 P51267 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1424917 T9561 C3124 P51267 oasc.SyncStrategy.syncToMe
http://127.0.0.1:51267/collection1/ has no replicas
[junit4] 2> 1424917 T9527 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 1424917 T9561 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:51267/collection1/ shard1
[junit4] 2> 1424917 T9527 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 1424917 T9561 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1424918 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1424919 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1424919 T9568 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@47d917 name:ZooKeeperConnection
Watcher:127.0.0.1:34940/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1424919 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1424920 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1424920 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1424921 T9554 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:51267";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1424922 T9554 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> 1424922 T9554 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 1424923 T9553 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> 1424925 T9569 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1424925 T9554 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 1424926 T9565 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> 1424971 T9561 oasc.ZkController.register We are
http://127.0.0.1:51267/collection1/ and leader is
http://127.0.0.1:51267/collection1/
[junit4] 2> 1424971 T9561 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:51267
[junit4] 2> 1424971 T9561 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1424971 T9561 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 1424971 T9561 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1424972 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1424974 T9554 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:51267";,
[junit4] 2> "node_name":"127.0.0.1:51267_",
[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> 1424974 T9554 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:51267";,
[junit4] 2> "node_name":"127.0.0.1:51267_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1425076 T9553 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> 1425076 T9565 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> 1425183 T9527 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 1425184 T9527 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1425185 T9527 oejs.AbstractConnector.doStart Started
[email protected]:58089
[junit4] 2> 1425186 T9527 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 1425186 T9527 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1425187 T9527 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-003
[junit4] 2> 1425187 T9527 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-003/'
[junit4] 2> 1425198 T9527 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-003/solr.xml
[junit4] 2> 1425204 T9527 oasc.CoreContainer.<init> New CoreContainer
12238755
[junit4] 2> 1425204 T9527 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-003/]
[junit4] 2> 1425205 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1425205 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1425205 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1425206 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1425206 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1425206 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1425207 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1425207 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1425207 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1425208 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1425208 T9527 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1425208 T9527 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1425209 T9527 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1425209 T9527 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1425209 T9527 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:34940/solr
[junit4] 2> 1425210 T9527 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1425210 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1425211 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1425213 T9581 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@115691f name:ZooKeeperConnection
Watcher:127.0.0.1:34940 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1425213 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1425214 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1425215 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1425216 T9584 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1c5247c name:ZooKeeperConnection
Watcher:127.0.0.1:34940/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1425216 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1425219 T9527
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1426223 T9527 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56988_
[junit4] 2> 1426224 T9527 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:56988_
[junit4] 2> 1426229 T9527 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1426235 T9585 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1426236 T9585 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1426238 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1426239 T9585 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1426239 T9585 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1426240 T9554 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:56988";,
[junit4] 2> "node_name":"127.0.0.1:56988_",
[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> 1426240 T9585 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1426240 T9554 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:56988";,
[junit4] 2> "node_name":"127.0.0.1:56988_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1426241 T9554 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1426241 T9554 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 1426242 T9585 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 1426242 T9585 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1426345 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1426346 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1426347 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 1427242 T9585 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1427243 T9585 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1427243 T9585 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1427244 T9585 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1427244 T9585 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1427244 T9585 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-003/collection1/'
[junit4] 2> 1427245 T9585 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 1427245 T9585 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 1427246 T9585 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 1427255 T9585 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1427257 T9585 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1427260 T9585 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1427264 T9585 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1427272 T9585 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1427273 T9585 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1427277 T9585 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1427375 T9585 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1427380 T9585 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1427381 T9585 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1427388 T9585 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1427390 T9585 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1427392 T9585 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1427393 T9585
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1427393 T9585
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1427393 T9585 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1427394 T9585
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1427394 T9585
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1427394 T9585 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1427395 T9585 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1427395 T9585 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty1/
[junit4] 2> 1427395 T9585 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ac042e
[junit4] 2> 1427396 T9585 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty1
[junit4] 2> 1427396 T9585 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty1/index/
[junit4] 2> 1427396 T9585 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 1427397 T9585 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty1/index
[junit4] 2> 1427398 T9585 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=919877551,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1427398 T9585 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@197eff8
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f3d6f5),segFN=segments_1,generation=1}
[junit4] 2> 1427399 T9585 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1427401 T9585 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1427402 T9585 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1427402 T9585 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1427402 T9585 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1427402 T9585 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1427403 T9585 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1427403 T9585 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1427403 T9585 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1427403 T9585 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1427404 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1427404 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1427405 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1427405 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1427406 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1427406 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1427407 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1427407 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1427407 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1427408 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1427408 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1427409 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1427409 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1427410 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1427410 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1427411 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1427411 T9585 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1427411 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1427412 T9585 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1427414 T9585 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1427415 T9585 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1427416 T9585 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1427416 T9585 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1427417 T9585 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1427418 T9585 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1427418 T9585 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1427419 T9585 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 1427419 T9585 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@197eff8
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f3d6f5),segFN=segments_1,generation=1}
[junit4] 2> 1427419 T9585 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1427420 T9585 oass.SolrIndexSearcher.<init> Opening
Searcher@14fedd9[collection1] main
[junit4] 2> 1427420 T9585 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1427421 T9585 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1427421 T9585 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1427421 T9585
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1427422 T9585 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1427422 T9585 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1427422 T9585
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1427422 T9585 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1427423 T9585 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1427423 T9585 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1427423 T9586 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@14fedd9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1427424 T9585 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1427427 T9527 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1427427 T9527 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1427428 T9589 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:56988 collection:collection1
shard:shard2
[junit4] 2> 1427429 T9589 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 1427433 T9589 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 1427434 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1427435 T9589
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1427435 T9554 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> 1427435 T9589 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3125 name=collection1
org.apache.solr.core.SolrCore@10234f5 url=http://127.0.0.1:56988/collection1
node=127.0.0.1:56988_ C3125_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:56988,
node_name=127.0.0.1:56988_, state=down}
[junit4] 2> 1427435 T9589 C3125 P56988 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:56988/collection1/
[junit4] 2> 1427435 T9589 C3125 P56988 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1427435 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1427436 T9589 C3125 P56988 oasc.SyncStrategy.syncToMe
http://127.0.0.1:56988/collection1/ has no replicas
[junit4] 2> 1427436 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1427436 T9589 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:56988/collection1/ shard2
[junit4] 2> 1427437 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 1427437 T9589 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 1427442 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1427443 T9554 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:56988";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1427444 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 1427445 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1427445 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1427490 T9589 oasc.ZkController.register We are
http://127.0.0.1:56988/collection1/ and leader is
http://127.0.0.1:56988/collection1/
[junit4] 2> 1427490 T9589 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:56988
[junit4] 2> 1427490 T9589 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1427491 T9589 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1427491 T9589 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1427492 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1427493 T9554 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:56988";,
[junit4] 2> "node_name":"127.0.0.1:56988_",
[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> 1427493 T9554 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:56988";,
[junit4] 2> "node_name":"127.0.0.1:56988_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1427494 T9589 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1427494 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 1427495 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1427495 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1427682 T9527 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 1427682 T9527 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1427684 T9527 oejs.AbstractConnector.doStart Started
[email protected]:41310
[junit4] 2> 1427685 T9527 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 1427685 T9527 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1427685 T9527 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-004
[junit4] 2> 1427686 T9527 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-004/'
[junit4] 2> 1427696 T9527 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-004/solr.xml
[junit4] 2> 1427702 T9527 oasc.CoreContainer.<init> New CoreContainer
131154
[junit4] 2> 1427703 T9527 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-004/]
[junit4] 2> 1427703 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1427704 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1427704 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1427704 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1427704 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1427705 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1427705 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1427705 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1427706 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1427706 T9527 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1427706 T9527 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1427707 T9527 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1427707 T9527 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1427707 T9527 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1427708 T9527 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:34940/solr
[junit4] 2> 1427708 T9527 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1427708 T9527
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1427709 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1427710 T9602 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1066484 name:ZooKeeperConnection
Watcher:127.0.0.1:34940 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1427710 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1427711 T9527 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1427712 T9527 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1427713 T9605 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@981df3 name:ZooKeeperConnection
Watcher:127.0.0.1:34940/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1427713 T9527 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1427716 T9527
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1428719 T9527 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45413_
[junit4] 2> 1428720 T9527 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:45413_
[junit4] 2> 1428724 T9527 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1428728 T9606 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1428728 T9606 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1428730 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1428731 T9554 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:45413";,
[junit4] 2> "node_name":"127.0.0.1:45413_",
[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> 1428731 T9606 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1428732 T9606 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1428732 T9554 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:45413";,
[junit4] 2> "node_name":"127.0.0.1:45413_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1428732 T9554 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1428732 T9554 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1428733 T9606 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1428733 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 1428734 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1428734 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1428736 T9605 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1428737 T9606 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1428737 T9606 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1428738 T9606 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1428738 T9605 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1428738 T9606 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1428739 T9606 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1428739 T9606 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1428739 T9606 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1428740 T9606 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-004/collection1/'
[junit4] 2> 1428740 T9606 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 1428741 T9606 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 1428741 T9606 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest%2042F3DF5D51540B5F-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 1428756 T9606 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1428758 T9606 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1428763 T9606 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1428768 T9606 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1428778 T9606 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1428779 T9606 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1428784 T9606 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1428882 T9606 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1428886 T9606 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1428887 T9606 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1428892 T9606 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1428894 T9606 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1428895 T9606 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1428896 T9606
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1428896 T9606
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1428896 T9606 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1428897 T9606
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1428897 T9606
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1428897 T9606 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1428898 T9606 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1428898 T9606 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty2/
[junit4] 2> 1428898 T9606 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@ac042e
[junit4] 2> 1428899 T9606 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty2
[junit4] 2> 1428899 T9606 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty2/index/
[junit4] 2> 1428899 T9606 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 1428899 T9606 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
42F3DF5D51540B5F-001/tempDir-001/jetty2/index
[junit4] 2> 1428899 T9606 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=919877551,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1428900 T9606 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@12c04bd
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1245922),segFN=segments_1,generation=1}
[junit4] 2> 1428900 T9606 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1428902 T9606 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1428902 T9606 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1428903 T9606 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1428903 T9606 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1428903 T9606 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1428903 T9606 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1428903 T9606 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1428904 T9606 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1428904 T9606 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1428904 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1428904 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1428905 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1428905 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1428905 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1428906 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1428906 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1428906 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1428907 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1428908 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1428908 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1428908 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1428909 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1428909 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1428909 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1428910 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1428910 T9606 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1428910 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1428911 T9606 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1428912 T9606 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1428913 T9606 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1428913 T9606 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1428914 T9606 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1428914 T9606 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1428915 T9606 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1428915 T9606 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1428916 T9606 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 1428916 T9606 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@12c04bd
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1245922),segFN=segments_1,generation=1}
[junit4] 2> 1428917 T9606 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1428917 T9606 oass.SolrIndexSearcher.<init> Opening
Searcher@1583164[collection1] main
[junit4] 2> 1428917 T9606 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1428918 T9606 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1428918 T9606 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1428918 T9606
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1428919 T9606 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1428919 T9606 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1428919 T9606
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1428919 T9606 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1428920 T9606 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1428920 T9606 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1428920 T9607 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1583164[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1428921 T9606 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1428921 T9610 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:45413 collection:collection1
shard:shard1
[junit4] 2> 1428921 T9527 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1428922 T9527 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1428922 T9610 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 1428924 T9610 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1428925 T9553 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1428925 T9610
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1428925 T9610 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C3126 name=collection1
org.apache.solr.core.SolrCore@19c70f5 url=http://127.0.0.1:45413/collection1
node=127.0.0.1:45413_ C3126_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:45413,
node_name=127.0.0.1:45413_, state=down}
[junit4] 2> 1428925 T9610 C3126 P45413 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:45413/collection1/
[junit4] 2> 1428926 T9554 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> 1428926 T9610 C3126 P45413 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1428926 T9610 C3126 P45413 oasc.SyncStrategy.syncToMe
http://127.0.0.1:45413/collection1/ has no replicas
[junit4] 2> 1428926 T9610 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:45413/collection1/ shard1
[junit4] 2> 1428926 T9610 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 1428926 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 5
[junit4] 2> 1428927 T9605 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1428927 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1428928 T9605 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 1428928 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 1428929 T9554 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:45413";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1428930 T9554 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 6
[junit4] 2> 1428930 T9605 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1428930 T9584 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1428931 T9584 oascc.ZkStateReader.updateWatchedCollection
Updating
[...truncated too long message...]
:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 8646734 T9527 oasc.SocketProxy.close WARN Closing 31
connections to: http://127.0.0.1:45413/
[junit4] 2> 8646735 T9527 oasc.SocketProxy.close WARN Closing 11
connections to: http://127.0.0.1:48845/
[junit4] 2> 8646736 T9527 oasc.SocketProxy.close WARN Closing 7
connections to: http://127.0.0.1:56988/
[junit4] 2> 8646736 T9527 oasc.SocketProxy.close WARN Closing 17
connections to: http://127.0.0.1:51267/
[junit4] 2> 8646740 T9527 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{_version_=PostingsFormat(name=Asserting),
multiDefault=PostingsFormat(name=Memory doPackFST= true),
a_t=PostingsFormat(name=Asserting), intDefault=PostingsFormat(name=Asserting),
id=PostingsFormat(name=Direct), text=BlockTreeOrds(blocksize=128),
range_facet_l=PostingsFormat(name=Asserting),
timestamp=PostingsFormat(name=Asserting)},
docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity,
locale=zh_HK, timezone=America/Lower_Princes
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.9.0-ea
(32-bit)/cpus=8,threads=2,free=103352312,total=404488192
[junit4] 2> NOTE: All tests run in this JVM:
[TestDynamicFieldCollectionResource, DeleteReplicaTest,
PathHierarchyTokenizerFactoryTest, ResourceLoaderTest, TestGroupingSearch,
TestCloudManagedSchemaConcurrent, OpenExchangeRatesOrgProviderTest,
HdfsCollectionsAPIDistributedZkTest, UniqFieldsUpdateProcessorFactoryTest,
PrimUtilsTest, HdfsChaosMonkeySafeLeaderTest, CircularListTest,
TestIndexingPerformance, TestFreeTextSuggestions, TestDistributedSearch,
SimpleFacetsTest, DateRangeFieldTest, TestLMDirichletSimilarityFactory,
TestSolrConfigHandlerCloud, TestStressUserVersions,
TestCopyFieldCollectionResource, AnalyticsMergeStrategyTest,
HdfsLockFactoryTest, TestLeaderElectionZkExpiry, TestIBSimilarityFactory,
TestCustomSort, FieldAnalysisRequestHandlerTest, TestTolerantSearch,
MinimalSchemaTest, TestSort, BadCopyFieldTest, TestNonDefinedSimilarityFactory,
QueryResultKeyTest, LukeRequestHandlerTest, TestLazyCores,
OutOfBoxZkACLAndCredentialsProvidersTest, FacetPivotSmallTest,
TestCollapseQParserPlugin, TestSweetSpotSimilarityFactory, TestCodecSupport,
TestSolrCoreProperties, SliceStateTest, ConnectionManagerTest,
SolrIndexConfigTest, PeerSyncTest, BlockDirectoryTest,
HdfsBasicDistributedZkTest, TestFastOutputStream, TestQuerySenderNoQuery,
OutputWriterTest, TestWordDelimiterFilterFactory, TestLRUStatsCache,
TestModifyConfFiles, PreAnalyzedUpdateProcessorTest, DocValuesMissingTest,
TestFuzzyAnalyzedSuggestions, SSLMigrationTest, TestSolrDeletionPolicy2,
ZkStateWriterTest, TestManagedSchema, TestFunctionQuery, RequestHandlersTest,
TestWriterPerf, XmlUpdateRequestHandlerTest, DistanceFunctionTest,
TestRandomDVFaceting, TestDynamicLoading,
DistributedQueryComponentCustomSortTest, JSONWriterTest, TestStressLucene,
TestExtendedDismaxParser, DistributedFacetPivotWhiteBoxTest,
TestDefaultSearchFieldResource, TestUpdate, TestSchemaSimilarityResource,
TestMissingGroups, DistributedFacetPivotSmallTest, UnloadDistributedZkTest,
ModifyConfFileTest, TestDFRSimilarityFactory, TestFastWriter,
TestHighlightDedupGrouping, CursorPagingTest, SuggesterFSTTest,
TestReplicationHandler, LeaderInitiatedRecoveryOnCommitTest, SolrXmlInZkTest,
BlockCacheTest, TestFieldTypeResource, TestIntervalFaceting,
TestXIncludeConfig, TestSchemaResource, TriLevelCompositeIdRoutingTest,
TestCollationField, TestCSVLoader, LeaderElectionTest, SolrCoreTest,
PreAnalyzedFieldTest, UUIDFieldTest, TestStressRecovery,
AsyncMigrateRouteKeyTest, AnalysisAfterCoreReloadTest, DocValuesMultiTest,
LegacyHTMLStripCharFilterTest, DistributedSuggestComponentTest,
TestQuerySenderListener, CoreContainerCoreInitFailuresTest,
TestSerializedLuceneMatchVersion, ReplicationFactorTest,
TestRequestStatusCollectionAPI, DistributedSpellCheckComponentTest,
DOMUtilTest, LeaderElectionIntegrationTest, RecoveryZkTest, ZkSolrClientTest,
AutoCommitTest, TestFaceting, TestRecovery, ZkControllerTest,
TestStressReorder, TestStressVersions, HardAutoCommitTest,
DistributedTermsComponentTest, TestCoreContainer, SpellCheckComponentTest,
TestFiltering, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest,
SolrIndexSplitterTest, SpellCheckCollatorTest, SolrRequestParserTest,
DocValuesTest, SuggesterTest, SpatialFilterTest, NoCacheHeaderTest,
SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases, SortByFunctionTest,
SolrInfoMBeanTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest,
StandardRequestHandlerTest, TestQueryTypes, FileBasedSpellCheckerTest,
TestSolrQueryParser, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest,
TestCSVResponseWriter, TestAnalyzedSuggestions, CSVRequestHandlerTest,
TestBinaryResponseWriter, UpdateParamsTest, CopyFieldTest,
TestMergePolicyConfig, TestBinaryField, TestElisionMultitermQuery,
ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest,
SpellPossibilityIteratorTest, TimeZoneUtilsTest, ScriptEngineTest,
SpellingQueryConverterTest, ClusterStateTest, TestSolrJ, TestDocumentBuilder,
DistributedIntervalFacetingTest, CustomCollectionTest, DeleteShardTest,
DistributedQueueTest, ExternalCollectionsTest, HttpPartitionTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionTest
-Dtests.seed=42F3DF5D51540B5F -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=zh_HK -Dtests.timezone=America/Lower_Princes
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | HttpPartitionTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([42F3DF5D51540B5F]:0)Throwable #2:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=9912, name=SocketProxy-Response-40892:34708,
state=RUNNABLE, group=TGRP-HttpPartitionTest]
[junit4] > Caused by: java.lang.RuntimeException:
java.net.SocketException: Socket is closed
[junit4] > at
__randomizedtesting.SeedInfo.seed([42F3DF5D51540B5F]:0)
[junit4] > at
org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:344)
[junit4] > Caused by: java.net.SocketException: Socket is closed
[junit4] > at java.net.Socket.setSoTimeout(Socket.java:1139)
[junit4] > at
org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:341)
[junit4] Completed on J0 in 7223.54s, 1 test, 3 errors <<< FAILURES!
[...truncated 11 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970:
There were test failures: 460 suites, 1870 tests, 2 suite-level errors, 1
error, 32 ignored (22 assumptions)
Total time: 178 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.9.0-ea-b34 -server
-XX:+UseParallelGC (asserts: true)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
