Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4428/
Java: 64bit/jdk1.8.0_20 -XX:-UseCompressedOops -XX:+UseSerialGC (asserts: false)
2 tests failed.
FAILED: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.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([92E7BFB926C2E12A]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([92E7BFB926C2E12A]:0)
Build Log:
[...truncated 9840 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\init-core-data-001
[junit4] 2> 2063359 T8987 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (true)
[junit4] 2> 2063362 T8987
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /fdiy/v
[junit4] 2> 2063365 T8987 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 2063366 T8987 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2063367 T8988 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2063457 T8987 oasc.ZkTestServer.run start zk server on
port:52477
[junit4] 2> 2063457 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2063459 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2063466 T8995 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@585fc6bd
name:ZooKeeperConnection Watcher:127.0.0.1:52477 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2063466 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2063466 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2063466 T8987 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2063476 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2063478 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2063479 T8998 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1ec36e15
name:ZooKeeperConnection Watcher:127.0.0.1:52477/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2063479 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2063479 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2063480 T8987 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 2063484 T8987 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 2063486 T8987 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 2063489 T8987 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 2063493 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 2063493 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 2063498 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 2063499 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 2063504 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2063504 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2063508 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 2063509 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 2063513 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 2063513 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 2063517 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 2063517 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 2063520 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 2063521 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 2063525 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2063525 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 2063528 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2063529 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2063533 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 2063534 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 2063539 T8987 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 2063540 T8987 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 2063544 T8989 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14ab4a7268a0001, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2065060 T8987 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2065063 T8987 oejs.AbstractConnector.doStart Started
[email protected]:52484
[junit4] 2> 2065064 T8987 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 2065064 T8987 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2065064 T8987 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-002
[junit4] 2> 2065064 T8987 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-002\'
[junit4] 2> 2065096 T8987 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-002\solr.xml
[junit4] 2> 2065120 T8987 oasc.CoreContainer.<init> New CoreContainer
2140274420
[junit4] 2> 2065121 T8987 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-002\]
[junit4] 2> 2065122 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2065122 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2065122 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2065123 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2065123 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2065125 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2065125 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2065126 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2065126 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2065127 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2065127 T8987 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2065128 T8987 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2065128 T8987 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2065129 T8987 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2065129 T8987 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52477/solr
[junit4] 2> 2065129 T8987 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2065130 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2065131 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2065134 T9010 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@ecb7fa name:ZooKeeperConnection
Watcher:127.0.0.1:52477 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 2065134 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2065134 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2065138 T8989 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14ab4a7268a0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2065140 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2065141 T9013 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2152907c
name:ZooKeeperConnection Watcher:127.0.0.1:52477/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2065142 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2065144 T8987 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2065148 T8987 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2065152 T8987 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2065156 T8987 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2065160 T8987 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2065164 T8987 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2065167 T8987 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52484_fdiy%2Fv
[junit4] 2> 2065167 T8987 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52484_fdiy%2Fv
[junit4] 2> 2065171 T8987 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2065174 T8987 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2065177 T8987 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2065180 T8987 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:52484_fdiy%2Fv
[junit4] 2> 2065180 T8987 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2065183 T8987 oasc.Overseer.start Overseer
(id=93085372313632771-127.0.0.1:52484_fdiy%2Fv-n_0000000000) starting
[junit4] 2> 2065187 T8987 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2065200 T8987 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 2065201 T9015 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2065202 T8987 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2065206 T8987 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2065208 T8987
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2065214 T9014 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2065218 T9017 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 2065218 T9017 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2065219 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2065219 T9017 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2065224 T9014 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:52484/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52484_fdiy%2Fv",
[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> 2065225 T9014 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:52484/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52484_fdiy%2Fv",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 2065226 T9014 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 2065226 T9014 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 2065227 T9013 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> 2066111 T9017 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2066111 T9017 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 2066112 T9017 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2066112 T9017 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 2066115 T9017 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 2066115 T9017 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-002\collection1\'
[junit4] 2> 2066117 T9017 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 2066117 T9017 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 2066118 T9017 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 2066138 T9017 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 2066141 T9017 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 2066152 T9017 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 2066159 T9017 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 2066169 T9017 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2066172 T9017 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 2066176 T9017 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2066275 T9017 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2066277 T9017 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2066277 T9017 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2066282 T9017 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2066307 T9017 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 2066307 T9017 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 2066308 T9017 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-002\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001/control/data\
[junit4] 2> 2066308 T9017 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7089cb05
[junit4] 2> 2066311 T9017 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\control\data
[junit4] 2> 2066312 T9017 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001/control/data\index/
[junit4] 2> 2066313 T9017 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\control\data\index'
doesn't exist. Creating new index...
[junit4] 2> 2066313 T9017 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\control\data\index
[junit4] 2> 2066314 T9017 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4908142210175681]
[junit4] 2> 2066318 T9017 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2066318 T9017 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2066319 T9017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2066320 T9017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2066320 T9017 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2066320 T9017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2066320 T9017 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2066320 T9017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2066321 T9017 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2066321 T9017 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2066321 T9017 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2066321 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2066322 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2066322 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2066322 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2066322 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 2066323 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 2066323 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 2066323 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 2066325 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 2066325 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 2066326 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 2066326 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 2066326 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 2066326 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 2066326 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 2066326 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 2066327 T9017 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2066327 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2066327 T9017 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2066332 T9017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2066333 T9017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2066333 T9017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2066335 T9017 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2066336 T9017 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2066338 T9017 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2066338 T9017 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2066338 T9017 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=47.0654296875,
floorSegmentMB=1.1181640625, forceMergeDeletesPctAllowed=8.827100473522812,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3699933341104018
[junit4] 2> 2066339 T9017 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2066339 T9017 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2066339 T9017 oass.SolrIndexSearcher.<init> Opening
Searcher@6ce39272[collection1] main
[junit4] 2> 2066339 T9017 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 2066341 T9017 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 2066341 T9017 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2066342 T9017
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2066342 T9017 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2066342 T9017 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2066343 T9017
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2066343 T9017 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2066343 T9017 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2066343 T9017 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 2066345 T9017 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2066345 T9018 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@6ce39272[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2066346 T9021 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:52484/fdiy/v
collection:control_collection shard:shard1
[junit4] 2> 2066346 T8987 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2066346 T8987 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2066347 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2066348 T9021 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2066348 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2066350 T9024 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6906f784
name:ZooKeeperConnection Watcher:127.0.0.1:52477/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2066351 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2066351 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2066353 T8987
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2066354 T9021 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 2066357 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2066358 T9021
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2066358 T9021 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4598 name=collection1
org.apache.solr.core.SolrCore@330739d0
url=http://127.0.0.1:52484/fdiy/v/collection1 node=127.0.0.1:52484_fdiy%2Fv
C4598_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:52484/fdiy/v, node_name=127.0.0.1:52484_fdiy%2Fv,
state=down}
[junit4] 2> 2066358 T9021 C4598 P52484 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:52484/fdiy/v/collection1/
[junit4] 2> 2066359 T9021 C4598 P52484 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2066359 T9021 C4598 P52484 oasc.SyncStrategy.syncToMe
http://127.0.0.1:52484/fdiy/v/collection1/ has no replicas
[junit4] 2> 2066359 T9021 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:52484/fdiy/v/collection1/ shard1
[junit4] 2> 2066359 T9021 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 2066359 T8987 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 2066359 T9014 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> 2066359 T8987 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 2066359 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2066361 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2066364 T9027 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@66bb2412
name:ZooKeeperConnection Watcher:127.0.0.1:52477/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2066364 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2066364 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2066366 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2066367 T8989 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14ab4a7268a0005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2066369 T9014 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:52484/fdiy/v";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 2066373 T9014 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "stateFormat":2} current state version: 1
[junit4] 2> 2066373 T9014 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 2066375 T9013 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> 2066375 T9024 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> 2066386 T9028 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2066388 T9014 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 2066390 T9024 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> 2066413 T9021 oasc.ZkController.register We are
http://127.0.0.1:52484/fdiy/v/collection1/ and leader is
http://127.0.0.1:52484/fdiy/v/collection1/
[junit4] 2> 2066413 T9021 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:52484/fdiy/v
[junit4] 2> 2066413 T9021 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2066413 T9021 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 2066413 T9021 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2066414 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2066418 T9014 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:52484/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52484_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 2066419 T9014 oasco.ReplicaMutator.updateState Update state
numShards=3 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:52484/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52484_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 2066513 T9024 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> 2066514 T9013 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> 2067800 T8987 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 2067800 T8987 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2067804 T8987 oejs.AbstractConnector.doStart Started
[email protected]:52499
[junit4] 2> 2067805 T8987 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 2067805 T8987 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2067805 T8987 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-003
[junit4] 2> 2067805 T8987 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-003\'
[junit4] 2> 2067832 T8987 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-003\solr.xml
[junit4] 2> 2067859 T8987 oasc.CoreContainer.<init> New CoreContainer
1514604377
[junit4] 2> 2067859 T8987 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-003\]
[junit4] 2> 2067862 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2067862 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2067862 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2067862 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2067863 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2067863 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2067863 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2067863 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2067863 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2067863 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2067863 T8987 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2067866 T8987 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2067866 T8987 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2067866 T8987 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2067866 T8987 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52477/solr
[junit4] 2> 2067866 T8987 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2067866 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2067870 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2067873 T9040 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@dabef28 name:ZooKeeperConnection
Watcher:127.0.0.1:52477 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 2067873 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2067873 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2067880 T8989 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14ab4a7268a0006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2067880 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2067882 T9043 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@68c2934d
name:ZooKeeperConnection Watcher:127.0.0.1:52477/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2067883 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2067892 T8987
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2068851 T8987 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52499_fdiy%2Fv
[junit4] 2> 2068854 T8987 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52499_fdiy%2Fv
[junit4] 2> 2068866 T8987 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2068879 T9044 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2068879 T9044 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2068882 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2068884 T9044 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 2068884 T9044 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 2068885 T9014 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:52499/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52499_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 2068885 T9044 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 2068886 T9014 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52499/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52499_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2068887 T9014 oasco.ReplicaMutator.updateState Collection
already exists with numShards=3
[junit4] 2> 2068887 T9014 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 2068888 T9044 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 2068888 T9044 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2068888 T9014 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 2068889 T9043 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2068890 T9043 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 2069869 T9044 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2069869 T9044 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 2069870 T9044 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2069871 T9044 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2069875 T9044 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2069875 T9044 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-003\collection1\'
[junit4] 2> 2069879 T9044 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 2069881 T9044 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 2069882 T9044 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 2069906 T9044 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 2069911 T9044 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 2069922 T9044 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 2069925 T9044 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 2069938 T9044 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2069941 T9044 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 2069945 T9044 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2070071 T9044 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2070073 T9044 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2070073 T9044 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2070079 T9044 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2070114 T9044 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 2070114 T9044 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 2070114 T9044 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-003\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001/jetty1\
[junit4] 2> 2070114 T9044 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7089cb05
[junit4] 2> 2070116 T9044 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty1
[junit4] 2> 2070117 T9044 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001/jetty1\index/
[junit4] 2> 2070118 T9044 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty1\index'
doesn't exist. Creating new index...
[junit4] 2> 2070119 T9044 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty1\index
[junit4] 2> 2070123 T9044 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4908142210175681]
[junit4] 2> 2070174 T9044 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2070174 T9044 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2070178 T9044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2070179 T9044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2070179 T9044 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2070179 T9044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2070180 T9044 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2070180 T9044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2070181 T9044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2070181 T9044 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2070181 T9044 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2070182 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2070182 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2070182 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2070183 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2070183 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 2070183 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 2070186 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 2070186 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 2070186 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 2070187 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 2070189 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 2070189 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 2070190 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 2070190 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 2070190 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 2070191 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 2070191 T9044 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2070191 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2070192 T9044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2070195 T9044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2070198 T9044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2070199 T9044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2070200 T9044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2070200 T9044 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2070203 T9044 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2070203 T9044 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2070203 T9044 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=47.0654296875,
floorSegmentMB=1.1181640625, forceMergeDeletesPctAllowed=8.827100473522812,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3699933341104018
[junit4] 2> 2070203 T9044 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2070203 T9044 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2070204 T9044 oass.SolrIndexSearcher.<init> Opening
Searcher@40b4f9b4[collection1] main
[junit4] 2> 2070204 T9044 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2070206 T9044 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2070206 T9044 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2070207 T9044
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2070207 T9044 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2070207 T9044 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2070208 T9044
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2070208 T9044 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2070208 T9044 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2070208 T9044 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 2070210 T9045 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@40b4f9b4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2070210 T9044 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2070211 T9048 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:52499/fdiy/v collection:collection1
shard:shard2
[junit4] 2> 2070212 T8987 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2070213 T8987 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2070214 T9048 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 2070225 T9048 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 2070227 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2070228 T9048
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2070228 T9048 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4599 name=collection1
org.apache.solr.core.SolrCore@451a0190
url=http://127.0.0.1:52499/fdiy/v/collection1 node=127.0.0.1:52499_fdiy%2Fv
C4599_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:52499/fdiy/v, node_name=127.0.0.1:52499_fdiy%2Fv,
state=down}
[junit4] 2> 2070229 T9048 C4599 P52499 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:52499/fdiy/v/collection1/
[junit4] 2> 2070229 T9048 C4599 P52499 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2070229 T9048 C4599 P52499 oasc.SyncStrategy.syncToMe
http://127.0.0.1:52499/fdiy/v/collection1/ has no replicas
[junit4] 2> 2070229 T9048 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:52499/fdiy/v/collection1/ shard2
[junit4] 2> 2070230 T9048 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 2070248 T9014 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> 2070256 T9014 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 2070258 T9043 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2070260 T9043 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 2070268 T9014 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:52499/fdiy/v";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 2070269 T9014 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 2070270 T9043 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2070271 T9043 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 2070275 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2070316 T9048 oasc.ZkController.register We are
http://127.0.0.1:52499/fdiy/v/collection1/ and leader is
http://127.0.0.1:52499/fdiy/v/collection1/
[junit4] 2> 2070316 T9048 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:52499/fdiy/v
[junit4] 2> 2070316 T9048 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2070316 T9048 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 2070316 T9048 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2070318 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2070320 T9014 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:52499/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52499_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 2070321 T9014 oasco.ReplicaMutator.updateState Update state
numShards=3 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:52499/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52499_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2070324 T9014 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 2070326 T9043 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2070329 T9048 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 2070330 T9043 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 2071843 T8987 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 2071843 T8987 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2071847 T8987 oejs.AbstractConnector.doStart Started
[email protected]:52508
[junit4] 2> 2071847 T8987 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 2071847 T8987 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2071847 T8987 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-004
[junit4] 2> 2071847 T8987 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-004\'
[junit4] 2> 2071890 T8987 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-004\solr.xml
[junit4] 2> 2071911 T8987 oasc.CoreContainer.<init> New CoreContainer
1948836462
[junit4] 2> 2071912 T8987 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-004\]
[junit4] 2> 2071912 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2071912 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2071913 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2071913 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2071913 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2071914 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2071914 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2071914 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2071915 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2071915 T8987 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2071915 T8987 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2071916 T8987 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2071916 T8987 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2071916 T8987 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2071917 T8987 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52477/solr
[junit4] 2> 2071917 T8987 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2071917 T8987
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2071919 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2071922 T9062 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6494b39b
name:ZooKeeperConnection Watcher:127.0.0.1:52477 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2071922 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2071922 T8987 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2071927 T8987 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2071930 T9065 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3c3a34f8
name:ZooKeeperConnection Watcher:127.0.0.1:52477/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2071930 T8987 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2071939 T8987
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2072925 T8987 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52508_fdiy%2Fv
[junit4] 2> 2072928 T8987 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52508_fdiy%2Fv
[junit4] 2> 2072939 T8987 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2072952 T9066 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2072952 T9066 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2072955 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2072959 T9066 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 2072959 T9066 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 2072959 T9014 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:52508/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52508_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 2072961 T9066 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 2072962 T9014 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52508/fdiy/v";,
[junit4] 2> "node_name":"127.0.0.1:52508_fdiy%2Fv",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2072962 T9014 oasco.ReplicaMutator.updateState Collection
already exists with numShards=3
[junit4] 2> 2072962 T9014 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard3
[junit4] 2> 2072966 T9014 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 2072967 T9066 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 2072967 T9066 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2072968 T9065 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2072968 T9043 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2072970 T9065 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 2072970 T9043 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 2073947 T9066 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2073948 T9066 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 2073950 T9066 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2073951 T9066 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2073953 T9066 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2073953 T9066 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-004\collection1\'
[junit4] 2> 2073959 T9066 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 2073963 T9066 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 2073966 T9066 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 2073989 T9066 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 2073994 T9066 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 2074004 T9066 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 2074010 T9066 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 2074021 T9066 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2074025 T9066 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 2074031 T9066 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2074160 T9066 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2074160 T9066 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2074160 T9066 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2074166 T9066 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2074194 T9066 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 2074194 T9066 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 2074194 T9066 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-004\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001/jetty2\
[junit4] 2> 2074194 T9066 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7089cb05
[junit4] 2> 2074194 T9066 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty2
[junit4] 2> 2074197 T9066 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001/jetty2\index/
[junit4] 2> 2074197 T9066 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty2\index'
doesn't exist. Creating new index...
[junit4] 2> 2074197 T9066 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty2\index
[junit4] 2> 2074198 T9066 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4908142210175681]
[junit4] 2> 2074211 T9066 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2074211 T9066 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2074214 T9066 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2074214 T9066 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2074214 T9066 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2074214 T9066 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2074215 T9066 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2074215 T9066 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2074216 T9066 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2074216 T9066 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2074216 T9066 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2074216 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2074217 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2074217 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2074217 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2074219 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 2074219 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 2074220 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 2074221 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 2074221 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 2074222 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 2074223 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 2074223 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 2074223 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 2074224 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 2074224 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 2074225 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 2074225 T9066 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2074226 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2074226 T9066 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2074234 T9066 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2074237 T9066 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2074239 T9066 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2074241 T9066 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2074241 T9066 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2074243 T9066 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2074243 T9066 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2074245 T9066 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=42, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=47.0654296875,
floorSegmentMB=1.1181640625, forceMergeDeletesPctAllowed=8.827100473522812,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3699933341104018
[junit4] 2> 2074248 T9066 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-92E7BFB926C2E12A-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2074248 T9066 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2074248 T9066 oass.SolrIndexSearcher.<init> Opening
Searcher@6ec07dd0[collection1] main
[junit4] 2> 2074248 T9066 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2074251 T9066 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2074251 T9066 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2074252 T9066
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2074252 T9066 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2074252 T9066 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2074254 T9066
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2074254 T9066 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2074254 T9066 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2074254 T9066 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 2074255 T9066 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2074256 T9067 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@6ec07dd0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2074258 T8987 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2074258 T8987 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2074268 T9070 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:52508/fdiy/v collection:collection1
shard:shard3
[junit4] 2> 2074330 T9070 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard3/election
[junit4] 2> 2074691 T9070 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard3
[junit4] 2> 2074708 T9013 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2074709 T9070
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2074709 T9070 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4600 name=collection1
org.apache.solr.core.SolrCore@15719297
url=http://127.0.0.1:52508/fdiy/v/collection1 node=127.0.0.1:52508_fdiy%2Fv
C4600_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:52508/fdiy/v, node_name=127.0.0.1:52508_fdiy%2Fv,
state=down}
[junit4] 2> 2074709 T9070 C4600 P52508 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:52508/fdiy/v/collection1/
[junit4] 2> 2074709 T9070 C4600 P52508 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2074709 T9070 C4600 P52508 oasc.SyncStrategy.syncToMe
http://127.0.0.1:52508/fdiy/v/collection1/ has no replicas
[junit4] 2> 2074709 T9070 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:52508/fdiy/v/collection1/ shard3
[junit4] 2> 2074710 T9070 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard3
[junit4] 2> 2074712 T9014 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 2074716 T9014 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 5
[j
[...truncated too long message...]
a:569)
[junit4] 2> at
org.eclipse.jetty.io.nio.SelectorManager$1.run(SelectorManager.java:290)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 36) Thread[id=9041,
name=TEST-ChaosMonkeySafeLeaderTest.testDistribSearch-seed#[92E7BFB926C2E12A]-SendThread(127.0.0.1:52477),
state=RUNNABLE, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at
sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method)
[junit4] 2> at
sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296)
[junit4] 2> at
sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278)
[junit4] 2> at
sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159)
[junit4] 2> at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 37) Thread[id=9090,
name=coreZkRegister-4720-thread-1, state=WAITING,
group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{rnd_b=PostingsFormat(name=Asserting), _version_=BlockTreeOrds(blocksize=128),
a_t=PostingsFormat(name=Memory doPackFST= true),
a_i=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting)},
docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {},
locale=sr_CS, timezone=America/Caracas
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_20
(64-bit)/cpus=2,threads=63,free=215113448,total=349282304
[junit4] 2> NOTE: All tests run in this JVM: [CopyFieldTest,
StressHdfsTest, TestJmxMonitoredMap, TestRandomMergePolicy, AnalyticsQueryTest,
TestSolrIndexConfig, BasicFunctionalityTest, TestChildDocTransformer,
TestRandomFaceting, TestPartialUpdateDeduplication,
DistributedFacetPivotLargeTest, DistributedQueueTest, HighlighterTest,
TestSolr4Spatial, SimplePostToolTest, TestArbitraryIndexDir,
StandardRequestHandlerTest, ExitableDirectoryReaderTest,
MoreLikeThisHandlerTest, TestExpandComponent,
TestOverriddenPrefixQueryForCustomFieldType, TestCloudPivotFacet,
SuggestComponentTest, TestInfoStreamLogging, UUIDFieldTest, DeleteReplicaTest,
TestSolrConfigHandler, LeaderElectionTest, ClusterStateTest,
TestCollationFieldDocValues, FieldAnalysisRequestHandlerTest, SuggesterTest,
TestTrie, TestManagedSchema, SortByFunctionTest, TestFieldSortValues,
DistanceUnitsTest, TermVectorComponentTest, TestManagedSchemaFieldTypeResource,
TestDFRSimilarityFactory, TestImplicitCoreProperties, SolrTestCaseJ4Test,
XmlUpdateRequestHandlerTest, TermsComponentTest, SolrIndexSplitterTest,
DebugComponentTest, TestBulkSchemaConcurrent, EnumFieldTest, TestOmitPositions,
StatsComponentTest, CacheHeaderTest, TestIBSimilarityFactory,
SolrIndexConfigTest, TestComplexPhraseQParserPlugin,
TestCursorMarkWithoutUniqueKey, TestSchemaResource, IndexBasedSpellCheckerTest,
TestExactStatsCache, TestReplicationHandlerBackup, TestInitQParser,
CoreAdminHandlerTest, ReplicationFactorTest, TestSolrQueryParserResource,
TestFuzzyAnalyzedSuggestions, DistributedIntervalFacetingTest,
SpellPossibilityIteratorTest, DeleteShardTest, ModifyConfFileTest,
SliceStateTest, TestWriterPerf, TestStandardQParsers,
DistributedExpandComponentTest, TestNonNRTOpen, SpellCheckComponentTest,
QueryEqualityTest, SignatureUpdateProcessorFactoryTest, TestStressRecovery,
ExternalCollectionsTest, PreAnalyzedUpdateProcessorTest,
CoreAdminRequestStatusTest, SolrCmdDistributorTest, TestQuerySenderNoQuery,
TestShardHandlerFactory, LegacyHTMLStripCharFilterTest,
DistributedSpellCheckComponentTest, TestFiltering, TestCustomSort,
TestDynamicFieldResource, TestSolrConfigHandlerConcurrent, TestLRUCache,
SampleTest, TestSystemIdResolver, URLClassifyProcessorTest, TestConfigReload,
TestClusterStateMutator, TestAtomicUpdateErrorCases,
TestFieldCollectionResource, MergeStrategyTest,
SimpleCollectionCreateDeleteTest, SharedFSAutoReplicaFailoverTest,
ClusterStateUpdateTest, TestExactSharedStatsCache, ShardSplitTest,
TestConfigOverlay, DocumentAnalysisRequestHandlerTest, TestDefaultStatsCache,
TestDocSet, CursorMarkTest, TestLFUCache, TestSurroundQueryParser,
TestConfigSets, PluginInfoTest, HdfsChaosMonkeySafeLeaderTest, DateFieldTest,
TestCollectionAPI, ResourceLoaderTest, TimeZoneUtilsTest, SSLMigrationTest,
TestDynamicLoading, BasicZkTest, TestCoreDiscovery, TestFoldingMultitermQuery,
TestSort, MigrateRouteKeyTest, TestRawResponseWriter, AlternateDirectoryTest,
SystemInfoHandlerTest, TestSolrXmlPersistor, AtomicUpdatesTest,
RegexBoostProcessorTest, ZkSolrClientTest,
DistributedQueryComponentCustomSortTest, TestBinaryField, PeerSyncTest,
DocExpirationUpdateProcessorFactoryTest, RankQueryTest,
LeaderElectionIntegrationTest, NumericFieldsTest, HighlighterMaxOffsetTest,
TestPHPSerializedResponseWriter, TestDistribDocBasedVersion,
TestSolrConfigHandlerCloud, TestFaceting, DirectUpdateHandlerTest,
TestLuceneMatchVersion, TestSolrCoreProperties, TestStressUserVersions,
DistributedFacetPivotWhiteBoxTest, MultiThreadedOCPTest, TestMissingGroups,
ZkCLITest, TestCodecSupport, ExternalFileFieldSortTest, DocValuesMissingTest,
ZkStateWriterTest, BlockCacheTest, TestRTGBase, TestGroupingSearch,
TestSearchPerf, HardAutoCommitTest, SimpleFacetsTest,
SchemaVersionSpecificBehaviorTest, TestHighFrequencyDictionaryFactory,
TestConfig, CoreContainerCoreInitFailuresTest,
TestReversedWildcardFilterFactory, TestMergePolicyConfig, BadCopyFieldTest,
BasicDistributedZkTest, TestReloadAndDeleteDocs,
StatelessScriptUpdateProcessorFactoryTest, TestMiniSolrCloudCluster,
TestCollationField, DocValuesTest, JSONWriterTest,
CollectionsAPIAsyncDistributedZkTest, PingRequestHandlerTest,
MinimalSchemaTest, TestXIncludeConfig, TestRandomDVFaceting, HdfsDirectoryTest,
OverseerCollectionProcessorTest, UniqFieldsUpdateProcessorFactoryTest,
AddSchemaFieldsUpdateProcessorFactoryTest, FastVectorHighlighterTest,
TestZkChroot, MBeansHandlerTest, CSVRequestHandlerTest,
TestUniqueKeyFieldResource, OpenCloseCoreStressTest, DistribCursorPagingTest,
LeaderInitiatedRecoveryOnCommitTest, AnalysisErrorHandlingTest,
FacetPivotSmallTest, OverseerStatusTest, TestQueryUtils, TestStressReorder,
HdfsBasicDistributedZk2Test, TestCloudSchemaless, RecoveryZkTest,
TestNoOpRegenerator, DistributedFacetPivotLongTailTest, TestFastLRUCache,
TestHashPartitioner, TestRemoteStreaming, TestSchemaNameResource,
HdfsCollectionsAPIDistributedZkTest, DateMathParserTest, SuggesterTSTTest,
TestBadConfig, SolrXmlInZkTest, QueryParsingTest, JsonLoaderTest,
ReturnFieldsTest, TestCSVResponseWriter, TestNRTOpen,
FullSolrCloudDistribCmdsTest, ChaosMonkeySafeLeaderTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ChaosMonkeySafeLeaderTest -Dtests.seed=92E7BFB926C2E12A
-Dtests.slow=true -Dtests.locale=sr_CS -Dtests.timezone=America/Caracas
-Dtests.asserts=false -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.00s | ChaosMonkeySafeLeaderTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([92E7BFB926C2E12A]:0)
[junit4] Completed in 7342.12s, 1 test, 2 errors <<< FAILURES!
[...truncated 720 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:529: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:477: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:190: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:508:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1363:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:970:
There were test failures: 460 suites, 1869 tests, 1 suite-level error, 1
error, 841 ignored (26 assumptions)
Total time: 193 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_20
-XX:-UseCompressedOops -XX:+UseSerialGC (asserts: false)
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]
