Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4268/ Java: 32bit/jdk1.8.0_40-ea-b09 -client -XX:+UseG1GC
No tests ran. Build Log: [...truncated 11361 lines...] [junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\init-core-data-001 [junit4] 2> 3472022 T9063 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true) [junit4] 2> 3472023 T9063 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 3472030 T9063 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 3472034 T9063 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3472035 T9064 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3472162 T9063 oasc.ZkTestServer.run start zk server on port:61729 [junit4] 2> 3472162 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3472166 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3472171 T9070 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ba8b48 name:ZooKeeperConnection Watcher:127.0.0.1:61729 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3472172 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3472172 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3472172 T9063 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3472180 T9065 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1490a572d760000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3472180 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3472184 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3472185 T9072 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c01b63 name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3472187 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3472187 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3472188 T9063 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3472194 T9063 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3472198 T9063 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3472200 T9063 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3472205 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3472205 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3472216 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml [junit4] 2> 3472216 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3472221 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3472221 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3472226 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3472226 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3472232 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3472232 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3472239 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 3472239 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3472247 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3472247 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3472252 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3472252 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3472258 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3472259 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3472266 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3472266 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3472274 T9063 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3472274 T9063 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 3474610 T9063 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3474642 T9063 oejs.AbstractConnector.doStart Started [email protected]:61736 [junit4] 2> 3474642 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3474642 T9063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3474642 T9063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-002 [junit4] 2> 3474644 T9063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-002\' [junit4] 2> 3474698 T9063 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-002\solr.xml [junit4] 2> 3474730 T9063 oasc.CoreContainer.<init> New CoreContainer 338618 [junit4] 2> 3474730 T9063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-002\] [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3474734 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3474738 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3474738 T9063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3474738 T9063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3474738 T9063 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3474738 T9063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3474738 T9063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3474738 T9063 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3474738 T9063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61729/solr [junit4] 2> 3474742 T9063 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3474742 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3474745 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3474747 T9083 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74f092 name:ZooKeeperConnection Watcher:127.0.0.1:61729 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3474749 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3474749 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3474752 T9065 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1490a572d760002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3474756 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3474760 T9085 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f7e777 name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3474760 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3474762 T9063 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3474769 T9063 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3474776 T9063 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3474783 T9063 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3474789 T9063 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3474796 T9063 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3474800 T9063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61736_ [junit4] 2> 3474801 T9063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61736_ [junit4] 2> 3474809 T9063 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3474812 T9063 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3474819 T9063 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3474822 T9063 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:61736_ [junit4] 2> 3474822 T9063 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3474828 T9063 oasc.Overseer.start Overseer (id=92616636878946307-127.0.0.1:61736_-n_0000000000) starting [junit4] 2> 3474836 T9063 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3474858 T9063 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3474860 T9087 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3474863 T9063 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3474870 T9063 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3474876 T9063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3474887 T9086 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3474894 T9089 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 3474894 T9089 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3474896 T9089 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3474896 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3474906 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61736";, [junit4] 2> "node_name":"127.0.0.1:61736_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3474906 T9086 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 3474906 T9086 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1 [junit4] 2> 3474906 T9086 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3474916 T9090 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> 3476156 T9089 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3476156 T9089 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 3476158 T9089 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3476158 T9089 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3476160 T9089 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3476160 T9089 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-002\collection1\' [junit4] 2> 3476165 T9089 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 3476167 T9089 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 3476168 T9089 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 3476244 T9089 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 3476262 T9089 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3476266 T9089 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3476294 T9089 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3476534 T9089 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3476536 T9089 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3476539 T9089 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3476545 T9089 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3476605 T9089 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3476607 T9089 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory [junit4] 2> 3476608 T9089 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-002\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001/control/data\ [junit4] 2> 3476608 T9089 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@55ea26 [junit4] 2> 3476613 T9089 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\control\data [junit4] 2> 3476614 T9089 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001/control/data\index/ [junit4] 2> 3476615 T9089 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 3476616 T9089 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\control\data\index [junit4] 2> 3476618 T9089 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=1.427734375, floorSegmentMB=1.658203125, forceMergeDeletesPctAllowed=23.196266288037883, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3476624 T9089 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\control\data\index,segFN=segments_1,generation=1} [junit4] 2> 3476624 T9089 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3476630 T9089 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3476631 T9089 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3476631 T9089 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3476631 T9089 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3476632 T9089 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3476632 T9089 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3476632 T9089 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3476634 T9089 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3476634 T9089 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3476636 T9089 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3476637 T9089 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3476637 T9089 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3476638 T9089 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3476638 T9089 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3476638 T9089 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3476638 T9089 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3476641 T9089 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3476641 T9089 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3476642 T9089 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3476642 T9089 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3476650 T9089 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3476656 T9089 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3476657 T9089 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3476661 T9089 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3476663 T9089 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3476663 T9089 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3476665 T9089 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=15.7900390625, floorSegmentMB=1.6015625, forceMergeDeletesPctAllowed=15.477454280454245, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3476667 T9089 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\control\data\index,segFN=segments_1,generation=1} [junit4] 2> 3476667 T9089 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3476668 T9089 oass.SolrIndexSearcher.<init> Opening Searcher@183d690[collection1] main [junit4] 2> 3476668 T9089 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3476670 T9089 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3476672 T9089 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3476673 T9089 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3476673 T9089 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3476673 T9089 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3476675 T9089 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3476675 T9089 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3476677 T9089 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 3476683 T9089 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 3476683 T9089 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3476683 T9089 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3476690 T9091 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@183d690[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3476693 T9089 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3476695 T9094 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61736 collection:control_collection shard:shard1 [junit4] 2> 3476696 T9063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3476696 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3476697 T9094 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 3476697 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3476704 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3476705 T9096 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fc5280 name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3476705 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3476706 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3476708 T9063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3476712 T9094 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3476717 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3476717 T9063 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3476718 T9063 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 3476718 T9094 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3476720 T9094 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 3476718 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> ASYNC NEW_CORE C2636 name=collection1 org.apache.solr.core.SolrCore@15cd48 url=http://127.0.0.1:61736/collection1 node=127.0.0.1:61736_ C2636_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:61736, node_name=127.0.0.1:61736_, state=down} [junit4] 2> 3476720 T9094 C2636 P61736 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61736/collection1/ [junit4] 2> 3476721 T9094 C2636 P61736 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3476722 T9094 C2636 P61736 oasc.SyncStrategy.syncToMe http://127.0.0.1:61736/collection1/ has no replicas [junit4] 2> 3476722 T9094 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61736/collection1/ shard1 [junit4] 2> 3476722 T9094 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3476723 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3476727 T9098 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18adf17 name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3476727 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3476727 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3476740 T9086 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 3476740 T9086 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 3476740 T9086 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 3476745 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3476747 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 3476750 T9090 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> 3476753 T9099 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> 3476779 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3476781 T9099 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> 3476783 T9090 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> 3476806 T9094 oasc.ZkController.register We are http://127.0.0.1:61736/collection1/ and leader is http://127.0.0.1:61736/collection1/ [junit4] 2> 3476806 T9094 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61736 [junit4] 2> 3476806 T9094 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3476806 T9094 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 3476806 T9094 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3476810 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3476819 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61736";, [junit4] 2> "node_name":"127.0.0.1:61736_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3476958 T9099 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> 3476958 T9090 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> 3478777 T9063 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3478778 T9063 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3478783 T9063 oejs.AbstractConnector.doStart Started [email protected]:61751 [junit4] 2> 3478788 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3478789 T9063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3478790 T9063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-003 [junit4] 2> 3478791 T9063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-003\' [junit4] 2> 3478842 T9063 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-003\solr.xml [junit4] 2> 3478869 T9063 oasc.CoreContainer.<init> New CoreContainer 25776900 [junit4] 2> 3478870 T9063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-003\] [junit4] 2> 3478871 T9063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3478871 T9063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3478872 T9063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3478872 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3478872 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3478874 T9063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3478874 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3478875 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3478875 T9063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3478876 T9063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3478876 T9063 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3478877 T9063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3478877 T9063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3478880 T9063 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3478880 T9063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61729/solr [junit4] 2> 3478881 T9063 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3478881 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3478883 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3478886 T9110 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@156a640 name:ZooKeeperConnection Watcher:127.0.0.1:61729 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3478887 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3478887 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3478892 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3478895 T9112 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b8746c name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3478895 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3478911 T9063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3480182 T9063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61751_ [junit4] 2> 3480185 T9063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61751_ [junit4] 2> 3480196 T9063 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3480212 T9114 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3480212 T9114 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3480215 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3480217 T9114 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 3480218 T9114 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 3480218 T9114 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 3480222 T9114 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 3480222 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61751";, [junit4] 2> "node_name":"127.0.0.1:61751_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3480222 T9114 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3480230 T9086 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 3480233 T9086 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 3480245 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3480246 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3480247 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 3481488 T9114 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3481488 T9114 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3481491 T9114 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3481491 T9114 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3481495 T9114 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3481495 T9114 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-003\collection1\' [junit4] 2> 3481500 T9114 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 3481500 T9114 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 3481504 T9114 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 3481575 T9114 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 3481595 T9114 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3481599 T9114 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3481618 T9114 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3481829 T9114 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3481833 T9114 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3481835 T9114 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3481844 T9114 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3481903 T9114 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3481903 T9114 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory [junit4] 2> 3481903 T9114 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-003\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001/jetty1\ [junit4] 2> 3481904 T9114 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@55ea26 [junit4] 2> 3481906 T9114 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty1 [junit4] 2> 3481907 T9114 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001/jetty1\index/ [junit4] 2> 3481908 T9114 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty1\index' doesn't exist. Creating new index... [junit4] 2> 3481911 T9114 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty1\index [junit4] 2> 3481911 T9114 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=1.427734375, floorSegmentMB=1.658203125, forceMergeDeletesPctAllowed=23.196266288037883, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3481919 T9114 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1} [junit4] 2> 3481919 T9114 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3481930 T9114 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3481933 T9114 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3481934 T9114 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3481934 T9114 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3481935 T9114 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3481935 T9114 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3481937 T9114 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3481938 T9114 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3481939 T9114 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3481941 T9114 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3481944 T9114 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3481945 T9114 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3481948 T9114 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3481950 T9114 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3481950 T9114 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3481952 T9114 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3481953 T9114 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3481954 T9114 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3481956 T9114 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3481958 T9114 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3481972 T9114 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3481977 T9114 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3481981 T9114 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3481988 T9114 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3481992 T9114 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3481994 T9114 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3481995 T9114 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=15.7900390625, floorSegmentMB=1.6015625, forceMergeDeletesPctAllowed=15.477454280454245, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3481996 T9114 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1} [junit4] 2> 3481996 T9114 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3481999 T9114 oass.SolrIndexSearcher.<init> Opening Searcher@e6a74f[collection1] main [junit4] 2> 3481999 T9114 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3482002 T9114 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3482002 T9114 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3482003 T9114 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3482003 T9114 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3482003 T9114 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3482007 T9114 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3482008 T9114 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3482008 T9114 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3482008 T9114 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3482021 T9115 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e6a74f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3482035 T9114 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3482039 T9118 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61751 collection:collection1 shard:shard2 [junit4] 2> 3482041 T9063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3482042 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3482044 T9118 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 3482057 T9118 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 3482064 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3482065 T9118 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3482065 T9118 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2637 name=collection1 org.apache.solr.core.SolrCore@b37521 url=http://127.0.0.1:61751/collection1 node=127.0.0.1:61751_ C2637_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:61751, node_name=127.0.0.1:61751_, state=down} [junit4] 2> 3482065 T9118 C2637 P61751 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61751/collection1/ [junit4] 2> 3482068 T9118 C2637 P61751 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3482068 T9118 C2637 P61751 oasc.SyncStrategy.syncToMe http://127.0.0.1:61751/collection1/ has no replicas [junit4] 2> 3482068 T9118 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61751/collection1/ shard2 [junit4] 2> 3482069 T9118 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 3482098 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3482100 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3482103 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 3482121 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3482122 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3482125 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3482129 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 3482167 T9118 oasc.ZkController.register We are http://127.0.0.1:61751/collection1/ and leader is http://127.0.0.1:61751/collection1/ [junit4] 2> 3482167 T9118 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61751 [junit4] 2> 3482167 T9118 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3482167 T9118 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3482167 T9118 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3482172 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3482185 T9118 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 3482188 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61751";, [junit4] 2> "node_name":"127.0.0.1:61751_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3482203 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3482206 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3482208 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 3484452 T9063 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3484455 T9063 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3484462 T9063 oejs.AbstractConnector.doStart Started [email protected]:61760 [junit4] 2> 3484466 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3484466 T9063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3484467 T9063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-004 [junit4] 2> 3484467 T9063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-004\' [junit4] 2> 3484524 T9063 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-004\solr.xml [junit4] 2> 3484612 T9063 oasc.CoreContainer.<init> New CoreContainer 9099994 [junit4] 2> 3484614 T9063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-004\] [junit4] 2> 3484616 T9063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3484616 T9063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3484617 T9063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3484617 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3484618 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3484618 T9063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3484620 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3484620 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3484620 T9063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3484621 T9063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3484622 T9063 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3484622 T9063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3484624 T9063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3484624 T9063 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3484624 T9063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61729/solr [junit4] 2> 3484625 T9063 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3484625 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3484631 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3484633 T9129 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ff41c5 name:ZooKeeperConnection Watcher:127.0.0.1:61729 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3484633 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3484635 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3484640 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3484643 T9131 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@176bf05 name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3484643 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3484658 T9063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3486054 T9063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61760_ [junit4] 2> 3486056 T9063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61760_ [junit4] 2> 3486067 T9063 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3486082 T9133 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3486082 T9133 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3486086 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3486089 T9133 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 3486089 T9133 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 3486090 T9133 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 3486093 T9133 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 3486093 T9133 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3486094 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61760";, [junit4] 2> "node_name":"127.0.0.1:61760_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3486102 T9086 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 3486105 T9086 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3486119 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3486120 T9132 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3486121 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3486123 T9132 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 3486123 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 3487478 T9133 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3487478 T9133 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3487480 T9133 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3487480 T9133 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3487484 T9133 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3487484 T9133 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-004\collection1\' [junit4] 2> 3487488 T9133 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 3487489 T9133 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 3487489 T9133 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 3487557 T9133 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 3487573 T9133 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3487580 T9133 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3487593 T9133 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3487769 T9133 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3487772 T9133 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3487773 T9133 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3487780 T9133 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3487834 T9133 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3487835 T9133 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory [junit4] 2> 3487835 T9133 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-004\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001/jetty2\ [junit4] 2> 3487836 T9133 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@55ea26 [junit4] 2> 3487839 T9133 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty2 [junit4] 2> 3487841 T9133 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001/jetty2\index/ [junit4] 2> 3487842 T9133 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty2\index' doesn't exist. Creating new index... [junit4] 2> 3487843 T9133 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty2\index [junit4] 2> 3487845 T9133 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=1.427734375, floorSegmentMB=1.658203125, forceMergeDeletesPctAllowed=23.196266288037883, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3487850 T9133 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1} [junit4] 2> 3487850 T9133 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3487855 T9133 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3487857 T9133 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3487857 T9133 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3487858 T9133 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3487858 T9133 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3487858 T9133 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3487859 T9133 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3487859 T9133 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3487861 T9133 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3487862 T9133 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3487862 T9133 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3487864 T9133 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3487865 T9133 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3487866 T9133 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3487866 T9133 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3487868 T9133 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3487869 T9133 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3487869 T9133 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3487870 T9133 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3487872 T9133 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3487880 T9133 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3487884 T9133 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3487885 T9133 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3487888 T9133 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3487893 T9133 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3487893 T9133 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3487897 T9133 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=15.7900390625, floorSegmentMB=1.6015625, forceMergeDeletesPctAllowed=15.477454280454245, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3487900 T9133 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1} [junit4] 2> 3487901 T9133 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3487901 T9133 oass.SolrIndexSearcher.<init> Opening Searcher@1892b0f[collection1] main [junit4] 2> 3487903 T9133 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3487905 T9133 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3487905 T9133 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3487907 T9133 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3487907 T9133 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3487907 T9133 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3487911 T9133 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 3487911 T9133 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3487911 T9133 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 3487911 T9133 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3487920 T9134 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1892b0f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3487928 T9133 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3487932 T9137 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61760 collection:collection1 shard:shard1 [junit4] 2> 3487934 T9063 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3487934 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3487935 T9137 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 3487947 T9137 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3487953 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3487953 T9137 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3487953 T9137 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2638 name=collection1 org.apache.solr.core.SolrCore@1ebf4e5 url=http://127.0.0.1:61760/collection1 node=127.0.0.1:61760_ C2638_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:61760, node_name=127.0.0.1:61760_, state=down} [junit4] 2> 3487954 T9137 C2638 P61760 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61760/collection1/ [junit4] 2> 3487954 T9137 C2638 P61760 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3487954 T9137 C2638 P61760 oasc.SyncStrategy.syncToMe http://127.0.0.1:61760/collection1/ has no replicas [junit4] 2> 3487954 T9137 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61760/collection1/ shard1 [junit4] 2> 3487955 T9137 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 3487982 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3487985 T9132 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3487987 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3487989 T9132 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 6 [junit4] 2> 3487991 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 6 [junit4] 2> 3488008 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3488010 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3488012 T9132 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3488012 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3488015 T9132 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 7 [junit4] 2> 3488016 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 7 [junit4] 2> 3488050 T9137 oasc.ZkController.register We are http://127.0.0.1:61760/collection1/ and leader is http://127.0.0.1:61760/collection1/ [junit4] 2> 3488050 T9137 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61760 [junit4] 2> 3488050 T9137 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3488050 T9137 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3488050 T9137 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3488055 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3488068 T9137 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 7 [junit4] 2> 3488068 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61760";, [junit4] 2> "node_name":"127.0.0.1:61760_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3488084 T9086 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 3488087 T9132 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3488087 T9113 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 3488089 T9132 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 8 [junit4] 2> 3488089 T9113 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 8 [junit4] 2> 3490502 T9063 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 3490505 T9063 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3490515 T9063 oejs.AbstractConnector.doStart Started [email protected]:61769 [junit4] 2> 3490519 T9063 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3490519 T9063 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3490521 T9063 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-005 [junit4] 2> 3490522 T9063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-005\' [junit4] 2> 3490606 T9063 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-005\solr.xml [junit4] 2> 3490644 T9063 oasc.CoreContainer.<init> New CoreContainer 23074071 [junit4] 2> 3490645 T9063 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-93F4C42173ACF1E-001\tempDir-005\] [junit4] 2> 3490646 T9063 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3490646 T9063 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3490648 T9063 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3490649 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3490649 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3490651 T9063 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3490652 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3490652 T9063 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3490653 T9063 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3490655 T9063 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3490656 T9063 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 3490657 T9063 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3490657 T9063 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3490659 T9063 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3490660 T9063 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61729/solr [junit4] 2> 3490660 T9063 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3490663 T9063 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3490667 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3490674 T9148 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1248822 name:ZooKeeperConnection Watcher:127.0.0.1:61729 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3490675 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3490675 T9063 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3490682 T9063 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3490684 T9150 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5e465c name:ZooKeeperConnection Watcher:127.0.0.1:61729/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3490686 T9063 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3490702 T9063 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3492103 T9063 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61769_ [junit4] 2> 3492109 T9063 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61769_ [junit4] 2> 3492135 T9063 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3492152 T9152 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3492152 T9152 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3492160 T9090 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3492164 T9152 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 3492164 T9152 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 3492167 T9152 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 3492172 T9152 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 8 [junit4] 2> 3492174 T9086 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:61769";, [junit4] 2> "node_name":"127.0.0.1:61769_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3492174 T9152 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3492183 T9086 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 3492187 T9086 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2 [...truncated too long message...] eetSpotSimilarityFactory, TestRemoteStreaming, TestMultiCoreConfBootstrap, TestComponentsName, TestDFRSimilarityFactory, HardAutoCommitTest, TestDistributedSearch, HdfsChaosMonkeySafeLeaderTest, TestUpdate, SolrIndexConfigTest, DirectUpdateHandlerOptimizeTest, SpellPossibilityIteratorTest, TestWordDelimiterFilterFactory, TestHighFrequencyDictionaryFactory, TestFiltering, OverseerTest, CopyFieldTest, TestSolrDeletionPolicy1, DistanceFunctionTest, MinimalSchemaTest, CollectionsAPIDistributedZkTest, TestPseudoReturnFields, RemoteQueryErrorTest, TestJmxMonitoredMap, TestDocumentBuilder, CoreAdminCreateDiscoverTest, TestMaxScoreQueryParser, TestSchemaSimilarityResource, ConnectionManagerTest, SpellingQueryConverterTest, SuggesterFSTTest, DisMaxRequestHandlerTest, TestSchemaNameResource, TestReplicaProperties, TestLMJelinekMercerSimilarityFactory, StatsComponentTest, SuggesterWFSTTest, SystemInfoHandlerTest, ShardRoutingTest, TestShortCircuitedRequests, TestSearchPerf, TestManagedStopFilterFactory, TestCollationFieldDocValues, ZkCLITest, TestCloudPivotFacet, TestSolrIndexConfig, TestInfoStreamLogging, ChaosMonkeyNothingIsSafeTest, DistributedTermsComponentTest, SampleTest, TestBlendedInfixSuggestions, TestLuceneMatchVersion, TestJmxIntegration, PeerSyncTest, SimpleFacetsTest, CachingDirectoryFactoryTest, TestManagedSchemaDynamicFieldResource, SOLR749Test, TestAtomicUpdateErrorCases, TestRequestStatusCollectionAPI, DistributedQueryComponentOptimizationTest, TestFieldResource, RankQueryTest, FileUtilsTest, TestZkChroot, TestImplicitCoreProperties, PrimUtilsTest, CSVRequestHandlerTest, TestJoin, TestIndexingPerformance, SearchHandlerTest, PolyFieldTest, RollingRestartTest, CoreContainerCoreInitFailuresTest, RecoveryZkTest, TestSerializedLuceneMatchVersion, DistributedExpandComponentTest, TestCursorMarkWithoutUniqueKey, SchemaVersionSpecificBehaviorTest, HighlighterConfigTest, ClusterStateUpdateTest, ConvertedLegacyTest, TestFastWriter, TestStandardQParsers, TestNoOpRegenerator, HdfsBasicDistributedZk2Test, TestCopyFieldCollectionResource, CursorPagingTest, TestStressReorder, RequestHandlersTest, BadIndexSchemaTest, XsltUpdateRequestHandlerTest, ReturnFieldsTest, TestCharFilters, TestOrdValues, TestSurroundQueryParser, ZkControllerTest, ShardSplitTest, TestValueSourceCache, TestFieldTypeCollectionResource, TestSolrJ, TestHashPartitioner, HdfsDirectoryTest, TestExpandComponent, TestFastOutputStream, ResponseLogComponentTest, TestReversedWildcardFilterFactory, TestWriterPerf, TestPivotHelperCode, TestNonNRTOpen, FileBasedSpellCheckerTest, SimplePostToolTest, FullSolrCloudDistribCmdsTest, DistribDocExpirationUpdateProcessorTest, TestCloudManagedSchema, TestSolrXml, TestLeaderElectionZkExpiry, SolrIndexSplitterTest, TestTolerantSearch, TestMergePolicyConfig, DefaultValueUpdateProcessorTest, TestModifyConfFiles, DocValuesTest, TestIntervalFaceting, MigrateRouteKeyTest, LeaderFailoverAfterPartitionTest, TestDynamicFieldCollectionResource, InfoHandlerTest, SpatialFilterTest, TestReloadAndDeleteDocs, TestStressRecovery, QueryEqualityTest, ParsingFieldUpdateProcessorsTest, ShowFileRequestHandlerTest, SyncSliceTest, DocumentAnalysisRequestHandlerTest, DeleteShardTest, HdfsCollectionsAPIDistributedZkTest, TestIndexSearcher, OpenExchangeRatesOrgProviderTest, SharedFSAutoReplicaFailoverUtilsTest, TestArbitraryIndexDir, TestFastLRUCache, FieldMutatingUpdateProcessorTest, TestCloudSchemaless, SliceStateTest, TestSolr4Spatial, UpdateParamsTest, CustomCollectionTest, SolrRequestParserTest, TestRestManager, TestPhraseSuggestions, TestPostingsSolrHighlighter, TestReplicationHandler, TestCollapseQParserPlugin, UnloadDistributedZkTest, BadComponentTest, StatelessScriptUpdateProcessorFactoryTest, IndexBasedSpellCheckerTest, SpellCheckCollatorTest, TermsComponentTest, OpenCloseCoreStressTest, TestRealTimeGet, TestFoldingMultitermQuery, TestOmitPositions, ModifyConfFileTest, TestRandomMergePolicy, BasicDistributedZkTest, OutOfBoxZkACLAndCredentialsProvidersTest, ChaosMonkeySafeLeaderTest, DirectUpdateHandlerTest, TestFaceting, SSLMigrationTest, TestMiniSolrCloudCluster, IndexSchemaRuntimeFieldTest, DistributedSuggestComponentTest, TestAnalyzeInfixSuggestions, TestCollectionAPI, DistributedQueryComponentCustomSortTest, TestReplicationHandlerBackup, SignatureUpdateProcessorFactoryTest, UUIDUpdateProcessorFallbackTest, TestLazyCores, TestSchemaManager, TestBM25SimilarityFactory, AsyncMigrateRouteKeyTest, TestSimpleQParserPlugin, SuggesterTest, BasicDistributedZk2Test, BasicZkTest, ZkSolrClientTest, TestRandomDVFaceting, ShardRoutingCustomTest, AutoCommitTest, TestDistributedGrouping, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestReload, TestStressVersions, TestRangeQuery, TestCoreContainer, SolrCoreTest, SpellCheckComponentTest, TestGroupingSearch, SolrCmdDistributorTest, TestSort, TestFunctionQuery, BasicFunctionalityTest, TestBadConfig, OverseerCollectionProcessorTest, HighlighterTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, CoreAdminHandlerTest, SuggesterTSTTest, TestStressLucene, TestTrie, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, SortByFunctionTest, DocValuesMultiTest, SolrInfoMBeanTest, DebugComponentTest, CacheHeaderTest, LukeRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, PingRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, TestBinaryField, NumericFieldsTest, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, DateMathParserTest, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, DateFieldTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, TestUtils, ZkNodePropsTest, UUIDFieldTest, CircularListTest, TestRTGBase, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestDistributedMissingSort, TestHighlightDedupGrouping, AliasIntegrationTest, AssignTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest] [junit4] Completed in 145.08s, 1 test, 1 error <<< FAILURES! [...truncated 195 lines...] FATAL: hudson.remoting.RequestAbortedException: java.io.IOException: Unexpected termination of the channel hudson.remoting.RequestAbortedException: hudson.remoting.RequestAbortedException: java.io.IOException: Unexpected termination of the channel at hudson.remoting.RequestAbortedException.wrapForRethrow(RequestAbortedException.java:41) at hudson.remoting.RequestAbortedException.wrapForRethrow(RequestAbortedException.java:34) at hudson.remoting.Request.call(Request.java:174) at hudson.remoting.Channel.call(Channel.java:742) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:168) at com.sun.proxy.$Proxy84.join(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:978) at hudson.Launcher$ProcStarter.join(Launcher.java:387) at hudson.tasks.Ant.perform(Ant.java:217) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:770) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:160) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:533) at hudson.model.Run.execute(Run.java:1745) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:89) at hudson.model.Executor.run(Executor.java:240) Caused by: hudson.remoting.RequestAbortedException: java.io.IOException: Unexpected termination of the channel at hudson.remoting.Request.abort(Request.java:299) at hudson.remoting.Channel.terminate(Channel.java:805) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:69) Caused by: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:50) Caused by: java.io.EOFException at java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2325) at java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794) at java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801) at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:40) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:34) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:48)
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
