lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0_20-ea-b15) - Build # 10357 - Still Failing!
Date Thu, 05 Jun 2014 05:32:34 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10357/
Java: 64bit/jdk1.8.0_20-ea-b15 -XX:-UseCompressedOops -XX:+UseSerialGC

2 tests failed.
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([EFDBD511048CB648]:0)


REGRESSION:  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([EFDBD511048CB648]:0)




Build Log:
[...truncated 11759 lines...]
   [junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
   [junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/init-core-data-001
   [junit4]   2> 15999 T97 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
   [junit4]   2> 15999 T97 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /vgvl/
   [junit4]   2> 16019 T97 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 16022 T97 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 16035 T98 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 16134 T97 oasc.ZkTestServer.run start zk server on port:53091
   [junit4]   2> 16192 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 16236 T99 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 18210 T104 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74ecc4ad name:ZooKeeperConnection Watcher:127.0.0.1:53091 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 18211 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 18212 T97 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 18235 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 18238 T106 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6edf988b name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 18238 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 18242 T97 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 18249 T97 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 18253 T97 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 18257 T97 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 18262 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 18263 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 18312 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
   [junit4]   2> 18313 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 18318 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 18319 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 18326 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 18327 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 18331 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 18331 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 18335 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 18336 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 18339 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 18340 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 18348 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 18349 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 18353 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 18353 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 18357 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 18358 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 18361 T97 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 18362 T97 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 18388 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 18392 T108 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@739be25 name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 18392 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 18816 T97 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 18843 T97 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 18857 T97 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:๔๘๕๕๕
   [junit4]   2> 18862 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 18862 T97 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 18863 T97 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002
   [junit4]   2> 18863 T97 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/'
   [junit4]   2> 18881 T97 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/solr.xml
   [junit4]   2> 18905 T97 oasc.CoreContainer.<init> New CoreContainer 815859670
   [junit4]   2> 18906 T97 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/]
   [junit4]   2> 18907 T97 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 18908 T97 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 18908 T97 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 18908 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 18909 T97 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 18909 T97 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 18909 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 18910 T97 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 18910 T97 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 18914 T97 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 18914 T97 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 18915 T97 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 18915 T97 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53091/solr
   [junit4]   2> 18927 T97 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 18928 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 18931 T119 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11a8ad4b name:ZooKeeperConnection Watcher:127.0.0.1:53091 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 18932 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 18940 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 18944 T121 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c5b9e1c name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 18944 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 18955 T97 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 18966 T97 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 18975 T97 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 18979 T97 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 18983 T97 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 18999 T97 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 19006 T97 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48555_vgvl
   [junit4]   2> 19014 T97 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48555_vgvl
   [junit4]   2> 19019 T97 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 19021 T97 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 19052 T97 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:48555_vgvl
   [junit4]   2> 19052 T97 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 19055 T97 oasc.Overseer.start Overseer (id=91877480922021892-127.0.0.1:48555_vgvl-n_0000000000) starting
   [junit4]   2> 19064 T97 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 19096 T123 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 19096 T97 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 19105 T97 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 19107 T97 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 19119 T122 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 19123 T124 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
   [junit4]   2> 19123 T124 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 19127 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 19128 T124 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 19130 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 19131 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:48555/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:48555_vgvl",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 19132 T122 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 19140 T122 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 19148 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 19155 T121 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> 20128 T124 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 20129 T124 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 20130 T124 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 20130 T124 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 20131 T124 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 20132 T124 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/collection1/'
   [junit4]   2> 20133 T124 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/collection1/lib/.svn/' to classloader
   [junit4]   2> 20133 T124 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/collection1/lib/classes/' to classloader
   [junit4]   2> 20133 T124 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/collection1/lib/README' to classloader
   [junit4]   2> 20171 T124 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 20201 T124 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 20204 T124 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 20217 T124 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 20428 T124 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 20430 T124 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 20432 T124 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 20437 T124 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 20493 T124 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 20494 T124 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 20506 T124 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-002/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data/
   [junit4]   2> 20506 T124 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b949b12
   [junit4]   2> 20511 T124 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data
   [junit4]   2> 20512 T124 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data/index/
   [junit4]   2> 20512 T124 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 20513 T124 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data/index
   [junit4]   2> 20514 T124 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=78.2958984375, floorSegmentMB=1.412109375, forceMergeDeletesPctAllowed=12.586972635433234, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4610765623177492
   [junit4]   2> 20924 T124 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 20925 T124 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 20932 T124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 20933 T124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 20933 T124 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 20934 T124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 20934 T124 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 20934 T124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 20935 T124 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 20935 T124 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 20935 T124 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 20938 T124 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 20940 T124 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 20940 T124 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 20941 T124 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 20942 T124 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 20942 T124 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 20947 T124 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 20950 T124 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 20955 T124 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 20955 T124 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 20957 T124 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.760289642739635]
   [junit4]   2> 20959 T124 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
   [junit4]   2> 20959 T124 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 20960 T124 oass.SolrIndexSearcher.<init> Opening Searcher@4bd60e57[collection1] main
   [junit4]   2> 20960 T124 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 20962 T124 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 20962 T124 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 20965 T124 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 20965 T124 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 20965 T124 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 20966 T124 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 20966 T124 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 20966 T124 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 20966 T124 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 20982 T125 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4bd60e57[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 20990 T124 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 20992 T128 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:48555/vgvl collection:control_collection shard:shard1
   [junit4]   2> 20992 T97 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 20993 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 21002 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 21005 T130 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f33a5af name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 21006 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 21008 T97 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 21013 T97 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 21014 T128 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 21023 T128 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 21026 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21027 T128 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 21028 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 21028 T128 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C31 name=collection1 org.apache.solr.core.SolrCore@1fea31cb url=https://127.0.0.1:48555/vgvl/collection1 node=127.0.0.1:48555_vgvl C31_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:48555/vgvl, core=collection1, node_name=127.0.0.1:48555_vgvl}
   [junit4]   2> 21029 T128 C31 P48555 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:48555/vgvl/collection1/
   [junit4]   2> 21031 T128 C31 P48555 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 21031 T128 C31 P48555 oasc.SyncStrategy.syncToMe https://127.0.0.1:48555/vgvl/collection1/ has no replicas
   [junit4]   2> 21031 T128 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:48555/vgvl/collection1/ shard1
   [junit4]   2> 21036 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21039 T121 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> 21040 T130 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> 21054 T128 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 21071 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21073 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 21087 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21190 T130 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> 21190 T121 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> 21224 T128 oasc.ZkController.register We are https://127.0.0.1:48555/vgvl/collection1/ and leader is https://127.0.0.1:48555/vgvl/collection1/
   [junit4]   2> 21224 T128 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:48555/vgvl
   [junit4]   2> 21225 T128 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 21225 T128 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
   [junit4]   2> 21225 T128 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 21228 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21229 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21229 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21229 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 21232 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 21234 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:48555/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:48555_vgvl",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 21239 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 21343 T130 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> 21343 T121 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> 21556 T97 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 21557 T97 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 21577 T97 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 21580 T97 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:๔๖๔๑๒
   [junit4]   2> 21582 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 21583 T97 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 21583 T97 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003
   [junit4]   2> 21584 T97 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/'
   [junit4]   2> 21619 T97 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/solr.xml
   [junit4]   2> 21663 T97 oasc.CoreContainer.<init> New CoreContainer 1554323725
   [junit4]   2> 21664 T97 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/]
   [junit4]   2> 21665 T97 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 21665 T97 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 21665 T97 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 21666 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 21666 T97 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 21667 T97 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 21667 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 21667 T97 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 21668 T97 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 21672 T97 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 21672 T97 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 21673 T97 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 21673 T97 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53091/solr
   [junit4]   2> 21674 T97 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 21683 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 21685 T141 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@78f5b1da name:ZooKeeperConnection Watcher:127.0.0.1:53091 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 21686 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 21694 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 21714 T143 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@644bd7d1 name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 21714 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 21725 T97 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 22728 T97 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46412_vgvl
   [junit4]   2> 22730 T97 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46412_vgvl
   [junit4]   2> 22818 T121 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 22818 T143 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 22818 T130 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 23006 T144 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 23007 T144 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 23010 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 23010 T144 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 23010 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 23011 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 23014 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 23015 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:46412/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:46412_vgvl",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 23016 T122 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3]
   [junit4]   2> 23016 T122 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 23019 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 23021 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 23021 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 23021 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24011 T144 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 24011 T144 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 24012 T144 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 24012 T144 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 24013 T144 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 24014 T144 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/collection1/'
   [junit4]   2> 24015 T144 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/collection1/lib/.svn/' to classloader
   [junit4]   2> 24015 T144 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/collection1/lib/classes/' to classloader
   [junit4]   2> 24015 T144 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/collection1/lib/README' to classloader
   [junit4]   2> 24050 T144 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 24069 T144 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 24071 T144 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 24084 T144 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 24284 T144 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 24286 T144 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 24287 T144 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 24293 T144 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 24326 T144 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 24326 T144 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 24327 T144 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-003/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1/
   [junit4]   2> 24327 T144 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b949b12
   [junit4]   2> 24328 T144 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1
   [junit4]   2> 24329 T144 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1/index/
   [junit4]   2> 24329 T144 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 24330 T144 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1/index
   [junit4]   2> 24331 T144 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=78.2958984375, floorSegmentMB=1.412109375, forceMergeDeletesPctAllowed=12.586972635433234, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4610765623177492
   [junit4]   2> 24412 T144 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
   [junit4]   2> 24412 T144 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 24420 T144 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 24421 T144 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 24422 T144 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 24422 T144 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 24422 T144 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 24422 T144 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 24423 T144 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 24423 T144 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 24423 T144 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 24427 T144 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 24428 T144 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 24429 T144 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 24429 T144 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 24430 T144 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 24431 T144 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 24433 T144 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 24435 T144 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 24437 T144 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 24437 T144 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 24439 T144 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.760289642739635]
   [junit4]   2> 24443 T144 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
   [junit4]   2> 24443 T144 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 24447 T144 oass.SolrIndexSearcher.<init> Opening Searcher@40055f21[collection1] main
   [junit4]   2> 24447 T144 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 24449 T144 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 24449 T144 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 24450 T144 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 24451 T144 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 24451 T144 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 24452 T144 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 24453 T144 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 24453 T144 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 24453 T144 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 24464 T145 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40055f21[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 24471 T144 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 24472 T148 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:46412/vgvl collection:collection1 shard:shard2
   [junit4]   2> 24473 T97 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 24473 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 24473 T148 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 24480 T148 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
   [junit4]   2> 24482 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24483 T148 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 24484 T148 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C32 name=collection1 org.apache.solr.core.SolrCore@36223802 url=https://127.0.0.1:46412/vgvl/collection1 node=127.0.0.1:46412_vgvl C32_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:46412/vgvl, core=collection1, node_name=127.0.0.1:46412_vgvl}
   [junit4]   2> 24484 T148 C32 P46412 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:46412/vgvl/collection1/
   [junit4]   2> 24485 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 24485 T148 C32 P46412 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 24485 T148 C32 P46412 oasc.SyncStrategy.syncToMe https://127.0.0.1:46412/vgvl/collection1/ has no replicas
   [junit4]   2> 24485 T148 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:46412/vgvl/collection1/ shard2
   [junit4]   2> 24485 T148 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 24489 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24494 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24499 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24601 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24602 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24601 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24646 T148 oasc.ZkController.register We are https://127.0.0.1:46412/vgvl/collection1/ and leader is https://127.0.0.1:46412/vgvl/collection1/
   [junit4]   2> 24647 T148 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:46412/vgvl
   [junit4]   2> 24647 T148 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 24647 T148 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 24647 T148 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 24649 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24650 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24650 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24651 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 24653 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:46412/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:46412_vgvl",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 24654 T148 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 24656 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 24760 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24760 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24761 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 24768 T97 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 24769 T97 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 24773 T97 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 24779 T97 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:๔๖๖๗๗
   [junit4]   2> 24781 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 24781 T97 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 24782 T97 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004
   [junit4]   2> 24782 T97 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/'
   [junit4]   2> 24798 T97 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/solr.xml
   [junit4]   2> 24812 T97 oasc.CoreContainer.<init> New CoreContainer 1344351096
   [junit4]   2> 24813 T97 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/]
   [junit4]   2> 24814 T97 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 24814 T97 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 24815 T97 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 24815 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 24815 T97 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 24816 T97 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 24816 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 24817 T97 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 24817 T97 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 24820 T97 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 24821 T97 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 24821 T97 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 24821 T97 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53091/solr
   [junit4]   2> 24822 T97 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 24823 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 24826 T159 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@379929cf name:ZooKeeperConnection Watcher:127.0.0.1:53091 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 24826 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 24830 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 24833 T161 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@164e937f name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 24833 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 24870 T97 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 25874 T97 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46677_vgvl
   [junit4]   2> 25875 T97 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46677_vgvl
   [junit4]   2> 25877 T130 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 25878 T121 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 25877 T161 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 25878 T143 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 25884 T162 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 25884 T162 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 25885 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 25885 T162 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 25885 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 25886 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 25887 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 25888 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:46677/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:46677_vgvl",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 25888 T122 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
   [junit4]   2> 25888 T122 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
   [junit4]   2> 25897 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 26000 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 26000 T161 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 26000 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 26000 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 26885 T162 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 26886 T162 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 26887 T162 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 26887 T162 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 26888 T162 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 26889 T162 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/collection1/'
   [junit4]   2> 26889 T162 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/collection1/lib/.svn/' to classloader
   [junit4]   2> 26890 T162 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/collection1/lib/classes/' to classloader
   [junit4]   2> 26890 T162 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/collection1/lib/README' to classloader
   [junit4]   2> 26925 T162 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 26946 T162 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 26947 T162 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 26956 T162 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 27129 T162 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 27132 T162 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 27133 T162 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 27143 T162 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 27173 T162 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 27174 T162 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 27174 T162 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-004/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2/
   [junit4]   2> 27174 T162 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b949b12
   [junit4]   2> 27175 T162 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2
   [junit4]   2> 27176 T162 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2/index/
   [junit4]   2> 27176 T162 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 27177 T162 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2/index
   [junit4]   2> 27178 T162 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=78.2958984375, floorSegmentMB=1.412109375, forceMergeDeletesPctAllowed=12.586972635433234, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4610765623177492
   [junit4]   2> 27199 T162 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
   [junit4]   2> 27200 T162 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 27207 T162 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 27208 T162 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 27208 T162 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 27208 T162 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 27209 T162 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 27209 T162 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 27209 T162 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 27210 T162 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 27210 T162 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 27211 T162 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 27212 T162 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 27212 T162 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 27213 T162 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 27214 T162 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 27230 T162 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 27231 T162 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 27233 T162 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 27249 T162 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 27249 T162 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 27255 T162 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.760289642739635]
   [junit4]   2> 27257 T162 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
   [junit4]   2> 27257 T162 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 27258 T162 oass.SolrIndexSearcher.<init> Opening Searcher@2e7a6113[collection1] main
   [junit4]   2> 27258 T162 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 27259 T162 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 27260 T162 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 27261 T162 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 27262 T162 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 27262 T162 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 27267 T162 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 27267 T162 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 27267 T162 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 27268 T162 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 27275 T163 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e7a6113[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 27279 T162 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 27283 T166 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:46677/vgvl collection:collection1 shard:shard3
   [junit4]   2> 27283 T97 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 27284 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 27300 T166 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
   [junit4]   2> 27313 T166 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
   [junit4]   2> 27319 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27319 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27319 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27320 T166 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 27320 T166 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C33 name=collection1 org.apache.solr.core.SolrCore@67e876be url=https://127.0.0.1:46677/vgvl/collection1 node=127.0.0.1:46677_vgvl C33_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:46677/vgvl, core=collection1, node_name=127.0.0.1:46677_vgvl}
   [junit4]   2> 27320 T166 C33 P46677 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:46677/vgvl/collection1/
   [junit4]   2> 27321 T166 C33 P46677 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 27321 T166 C33 P46677 oasc.SyncStrategy.syncToMe https://127.0.0.1:46677/vgvl/collection1/ has no replicas
   [junit4]   2> 27321 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 27321 T166 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:46677/vgvl/collection1/ shard3
   [junit4]   2> 27322 T166 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
   [junit4]   2> 27328 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27334 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27338 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27441 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27441 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27441 T161 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27441 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27500 T166 oasc.ZkController.register We are https://127.0.0.1:46677/vgvl/collection1/ and leader is https://127.0.0.1:46677/vgvl/collection1/
   [junit4]   2> 27501 T166 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:46677/vgvl
   [junit4]   2> 27501 T166 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 27501 T166 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 27501 T166 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 27503 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27503 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27504 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27504 T166 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 27505 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 27506 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:46677/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:46677_vgvl",
   [junit4]   2> 	  "shard":"shard3",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":"core_node2"}
   [junit4]   2> 27509 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 27613 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27614 T161 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27613 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27614 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 27701 T97 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
   [junit4]   2> 27702 T97 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 27733 T97 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 27735 T97 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:๕๖๕๖๔
   [junit4]   2> 27746 T97 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 27747 T97 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 27747 T97 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005
   [junit4]   2> 27748 T97 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/'
   [junit4]   2> 27771 T97 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/solr.xml
   [junit4]   2> 27797 T97 oasc.CoreContainer.<init> New CoreContainer 1453877157
   [junit4]   2> 27797 T97 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/]
   [junit4]   2> 27798 T97 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 27798 T97 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 27799 T97 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 27799 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 27799 T97 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 27800 T97 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 27800 T97 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 27800 T97 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 27801 T97 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 27804 T97 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 27805 T97 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 27805 T97 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 27805 T97 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53091/solr
   [junit4]   2> 27806 T97 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 27807 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 27810 T177 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c2b0fca name:ZooKeeperConnection Watcher:127.0.0.1:53091 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 27810 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 27831 T97 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 27833 T179 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@31d805b6 name:ZooKeeperConnection Watcher:127.0.0.1:53091/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 27833 T97 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 27839 T97 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 28841 T97 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56564_vgvl
   [junit4]   2> 28843 T97 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56564_vgvl
   [junit4]   2> 28846 T130 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 28846 T143 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 28846 T179 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 28846 T161 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 28846 T121 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 28854 T180 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 28855 T180 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 28856 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 28856 T180 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 28856 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 28856 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 28857 T122 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 28858 T122 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:56564/vgvl",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:56564_vgvl",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"3",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 28858 T122 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
   [junit4]   2> 28858 T122 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 28861 T121 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 28964 T143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 28964 T161 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 28964 T130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 28964 T121 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 28964 T179 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 29856 T180 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 29856 T180 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 29857 T180 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 29857 T180 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 29858 T180 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 29858 T180 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/collection1/'
   [junit4]   2> 29859 T180 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/collection1/lib/.svn/' to classloader
   [junit4]   2> 29859 T180 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/collection1/lib/classes/' to classloader
   [junit4]   2> 29860 T180 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/collection1/lib/README' to classloader
   [junit4]   2> 29900 T180 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 29922 T180 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 29924 T180 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 29934 T180 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 30058 T180 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 30060 T180 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 30060 T180 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 30064 T180 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 30086 T180 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 30086 T180 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
   [junit4]   2> 30087 T180 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-005/collection1/, dataDir=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty3/
   [junit4]   2> 30087 T180 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b949b12
   [junit4]   2> 30088 T180 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty3
   [junit4]   2> 30088 T180 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty3/index/
   [junit4]   2> 30089 T180 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty3/index' doesn't exist. Creating new index...
   [junit4]   2> 30089 T180 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solr.cloud.ChaosMonkeySafeLeaderTest-EFDBD511048CB648-001/tempDir-001/jetty3/index
   [junit4]   2> 30090 T180 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=78.2958984375, floorSegmentMB=1.412109375, forceMergeDeletesPctAllowed=12.586972635433234, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4610765623177492
   [junit4]   2> 30096 T180 oasc.

[...truncated too long message...]

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> 	  69) Thread[id=581, name=coreZkRegister-226-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> 	  70) Thread[id=178, name=TEST-ChaosMonkeySafeLeaderTest.testDistribSearch-seed#[EFDBD511048CB648]-SendThread(127.0.0.1:53091), state=TIMED_WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
   [junit4]   2> 	        at java.lang.Thread.sleep(Native Method)
   [junit4]   2> 	        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
   [junit4]   2> 	  71) Thread[id=166, name=coreZkRegister-80-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> 	  72) Thread[id=516, name=HashSessionScavenger-23, state=TIMED_WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
   [junit4]   2> 	        at java.lang.Object.wait(Native Method)
   [junit4]   2> 	        at java.util.TimerThread.mainLoop(Timer.java:552)
   [junit4]   2> 	        at java.util.TimerThread.run(Timer.java:505)
   [junit4]   2> 	  73) Thread[id=465, name=coreZkRegister-184-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> 	  74) Thread[id=531, name=searcherExecutor-213-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> 	  75) Thread[id=401, name=qtp1239225108-401 Selector0, state=RUNNABLE, group=TGRP-ChaosMonkeySafeLeaderTest]
   [junit4]   2> 	        at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
   [junit4]   2> 	        at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
   [junit4]   2> 	        at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
   [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.eclipse.jetty.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java: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> 	  76) Thread[id=168, name=qtp729322151-168 Selector0, state=RUNNABLE, group=TGRP-ChaosMonkeySafeLeaderTest]
   [junit4]   2> 	        at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
   [junit4]   2> 	        at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
   [junit4]   2> 	        at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
   [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.eclipse.jetty.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java: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> NOTE: test params are: codec=Lucene49: {rnd_b=PostingsFormat(name=TestBloomFilteredLucene41Postings), _version_=PostingsFormat(name=FSTPulsing41), a_t=PostingsFormat(name=FSTPulsing41), a_si=FST41, id=PostingsFormat(name=TestBloomFilteredLucene41Postings)}, docValues:{}, sim=DefaultSimilarity, locale=th_TH_TH_#u-nu-thai, timezone=Europe/Belgrade
   [junit4]   2> NOTE: Linux 3.8.0-41-generic amd64/Oracle Corporation 1.8.0_20-ea (64-bit)/cpus=8,threads=88,free=56524416,total=446189568
   [junit4]   2> NOTE: All tests run in this JVM: [CurrencyFieldOpenExchangeTest, SolrCmdDistributorTest, TestDefaultSimilarityFactory, PrimUtilsTest, ChangedSchemaMergeTest, ChaosMonkeySafeLeaderTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ChaosMonkeySafeLeaderTest -Dtests.seed=EFDBD511048CB648 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th_TH_TH_#u-nu-thai -Dtests.timezone=Europe/Belgrade -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | ChaosMonkeySafeLeaderTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([EFDBD511048CB648]:0)
   [junit4] Completed on J1 in 7225.32s, 1 test, 2 errors <<< FAILURES!

[...truncated 294 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1297: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:921: There were test failures: 397 suites, 1640 tests, 1 suite-level error, 1 error, 378 ignored (15 assumptions)

Total time: 162 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0_20-ea-b15 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



Mime
View raw message