lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0_05) - Build # 4030 - Still Failing!
Date Sun, 15 Jun 2014 20:48:00 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/4030/
Java: 64bit/jdk1.8.0_05 -XX:+UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([77B74CD39F00A0CA:F651C2CBE85FC0F6]:0)
	at org.junit.Assert.fail(Assert.java:92)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertTrue(Assert.java:54)
	at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:162)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:483)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 12476 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\init-core-data-001
   [junit4]   2> 110104 T382 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
   [junit4]   2> 110104 T382 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
   [junit4]   2> 110109 T382 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
   [junit4]   2> 110112 T382 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 110123 T382 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 110139 T383 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 110347 T382 oasc.ZkTestServer.run start zk server on port:60699
   [junit4]   2> 110490 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 110535 T384 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 112364 T389 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1edf212a name:ZooKeeperConnection Watcher:127.0.0.1:60699 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 112365 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 112370 T382 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 112439 T384 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146a147cf550000, 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> 112440 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 112444 T391 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@59dbccfc name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 112445 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 112453 T382 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 112462 T382 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 112469 T382 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 112475 T382 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 112482 T382 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 112484 T382 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 112496 T382 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 112497 T382 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\enumsConfig.xml because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt because it doesn't exist
   [junit4]   2> 112503 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt because it doesn't exist
   [junit4]   2> 112506 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt because it doesn't exist
   [junit4]   2> 112510 T384 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146a147cf550001, 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> 112520 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 112524 T393 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a02a9fc name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 112524 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 112529 T384 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146a147cf550002, 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> 113003 T382 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 113019 T382 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 113081 T382 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60710
   [junit4]   2> 113084 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 113084 T382 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 113084 T382 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-002
   [junit4]   2> 113084 T382 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-002\'
   [junit4]   2> 113120 T382 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-002\solr.xml
   [junit4]   2> 113137 T382 oasc.CoreContainer.<init> New CoreContainer 1392172990
   [junit4]   2> 113138 T382 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-002\]
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 113141 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 113144 T382 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 113144 T382 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 113152 T382 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 113152 T382 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 113152 T382 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 113154 T382 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60699/solr
   [junit4]   2> 113192 T382 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 113194 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 113201 T404 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@57154b4 name:ZooKeeperConnection Watcher:127.0.0.1:60699 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 113202 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 113226 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 113232 T406 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b937280 name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 113232 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 113263 T382 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 113279 T382 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 113289 T382 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 113297 T382 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 113305 T382 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 113325 T382 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 113329 T382 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60710_
   [junit4]   2> 113334 T382 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60710_
   [junit4]   2> 113340 T382 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 113345 T382 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 113367 T382 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:60710_
   [junit4]   2> 113368 T382 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 113372 T382 oasc.Overseer.start Overseer (id=91938172200878084-127.0.0.1:60710_-n_0000000000) starting
   [junit4]   2> 113408 T382 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 113486 T408 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 113489 T382 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 113497 T382 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 113501 T382 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 113529 T407 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 113545 T409 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
   [junit4]   2> 113545 T409 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 113553 T409 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 113554 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 113560 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 113562 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60710",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60710_",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 113563 T407 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 113590 T407 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 113597 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 113607 T406 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> 114599 T409 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 114599 T409 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 114601 T409 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 114601 T409 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 114604 T409 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 114605 T409 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-002\collection1\'
   [junit4]   2> 114671 T409 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 114675 T409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 114679 T409 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 114688 T409 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 114781 T409 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 114783 T409 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 114784 T409 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 114784 T409 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 114784 T409 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 114785 T409 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 114785 T409 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-002\collection1\, dataDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/control/data\
   [junit4]   2> 114785 T409 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 114790 T409 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/control/data\
   [junit4]   2> 114790 T409 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/control/data\index/
   [junit4]   2> 114791 T409 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\control\data\index' doesn't exist. Creating new index...
   [junit4]   2> 114810 T409 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/control/data\index
   [junit4]   2> 114816 T409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8faa0b4 lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\control\data\index)),segFN=segments_1,generation=1}
   [junit4]   2> 114816 T409 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 114821 T409 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 114821 T409 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 114821 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 114821 T409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 114822 T409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 114822 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 114822 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 114823 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 114830 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 114832 T409 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 114832 T409 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 114833 T409 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@8faa0b4 lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\control\data\index)),segFN=segments_1,generation=1}
   [junit4]   2> 114833 T409 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 114834 T409 oass.SolrIndexSearcher.<init> Opening Searcher@50557eaf[collection1] main
   [junit4]   2> 114836 T409 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 114838 T409 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 114838 T409 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 114849 T409 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 114849 T409 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 114849 T409 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 114850 T409 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 114851 T409 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 114851 T409 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 114851 T409 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 114852 T409 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 114854 T410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50557eaf[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 114855 T413 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:60710 collection:control_collection shard:shard1
   [junit4]   2> 114861 T382 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
   [junit4]   2> 114861 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 114886 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 114905 T413 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 114906 T415 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d53f51e name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 114906 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 114911 T382 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 114927 T382 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 115057 T413 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 115063 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115065 T413 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 115065 T413 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C98 name=collection1 org.apache.solr.core.SolrCore@7c5357b9 url=https://127.0.0.1:60710/collection1 node=127.0.0.1:60710_ C98_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:60710, core=collection1, node_name=127.0.0.1:60710_}
   [junit4]   2> 115081 T413 C98 P60710 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:60710/collection1/
   [junit4]   2> 115082 T413 C98 P60710 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 115082 T413 C98 P60710 oasc.SyncStrategy.syncToMe https://127.0.0.1:60710/collection1/ has no replicas
   [junit4]   2> 115082 T413 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:60710/collection1/ shard1
   [junit4]   2> 115086 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 115090 T413 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 115110 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115121 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115132 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115260 T415 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> 115261 T406 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> 115304 T413 oasc.ZkController.register We are https://127.0.0.1:60710/collection1/ and leader is https://127.0.0.1:60710/collection1/
   [junit4]   2> 115304 T413 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:60710
   [junit4]   2> 115304 T413 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 115304 T413 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
   [junit4]   2> 115304 T413 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 115317 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115318 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115318 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115321 T413 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 115331 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 115348 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60710",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60710_",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 115366 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 115366 T382 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 115369 T382 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 115547 T415 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> 115548 T406 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> 115553 T382 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 115556 T382 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60722
   [junit4]   2> 115564 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 115564 T382 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 115565 T382 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-003
   [junit4]   2> 115565 T382 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-003\'
   [junit4]   2> 115594 T382 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-003\solr.xml
   [junit4]   2> 115610 T382 oasc.CoreContainer.<init> New CoreContainer 752898804
   [junit4]   2> 115610 T382 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-003\]
   [junit4]   2> 115611 T382 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 115611 T382 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 115611 T382 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 115611 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 115611 T382 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 115612 T382 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 115612 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 115612 T382 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 115612 T382 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 115620 T382 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 115620 T382 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 115620 T382 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 115620 T382 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60699/solr
   [junit4]   2> 115621 T382 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 115624 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 115629 T426 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12ad48f7 name:ZooKeeperConnection Watcher:127.0.0.1:60699 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 115630 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 115636 T384 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146a147cf550006, 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> 115639 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 115642 T428 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ecee30f name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 115644 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 115664 T382 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 116822 T382 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60722_
   [junit4]   2> 116827 T382 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60722_
   [junit4]   2> 116839 T406 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 116839 T415 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 116839 T428 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 116872 T429 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 116872 T429 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 116874 T429 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 116875 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 116875 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 116875 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 116877 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 116878 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60722",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60722_",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 116878 T407 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
   [junit4]   2> 116880 T407 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 116884 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 117010 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 117011 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 117012 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118025 T429 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 118025 T429 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 118028 T429 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 118028 T429 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 118034 T429 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 118034 T429 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-003\collection1\'
   [junit4]   2> 118082 T429 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 118089 T429 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 118103 T429 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 118109 T429 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 118195 T429 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 118197 T429 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 118207 T429 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 118208 T429 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 118208 T429 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 118208 T429 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 118208 T429 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-003\collection1\, dataDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty1\
   [junit4]   2> 118208 T429 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 118209 T429 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty1\
   [junit4]   2> 118209 T429 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty1\index/
   [junit4]   2> 118209 T429 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty1\index' doesn't exist. Creating new index...
   [junit4]   2> 118210 T429 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty1\index
   [junit4]   2> 118213 T429 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@32dc04fe lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty1\index)),segFN=segments_1,generation=1}
   [junit4]   2> 118213 T429 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 118215 T429 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 118216 T429 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 118216 T429 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 118216 T429 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 118216 T429 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 118216 T429 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 118217 T429 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 118217 T429 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 118222 T429 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 118224 T429 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 118224 T429 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 118226 T429 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@32dc04fe lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty1\index)),segFN=segments_1,generation=1}
   [junit4]   2> 118226 T429 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 118226 T429 oass.SolrIndexSearcher.<init> Opening Searcher@6031c316[collection1] main
   [junit4]   2> 118226 T429 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 118233 T429 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 118233 T429 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 118234 T429 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 118234 T429 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 118234 T429 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 118235 T429 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 118236 T429 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 118236 T429 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 118236 T429 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 118237 T430 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6031c316[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 118237 T429 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 118239 T433 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:60722 collection:collection1 shard:shard2
   [junit4]   2> 118239 T382 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
   [junit4]   2> 118239 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 118242 T433 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 118270 T433 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
   [junit4]   2> 118277 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118277 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118277 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118280 T433 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 118280 T433 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C99 name=collection1 org.apache.solr.core.SolrCore@7498cc24 url=https://127.0.0.1:60722/collection1 node=127.0.0.1:60722_ C99_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:60722, core=collection1, node_name=127.0.0.1:60722_}
   [junit4]   2> 118280 T433 C99 P60722 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:60722/collection1/
   [junit4]   2> 118281 T433 C99 P60722 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 118281 T433 C99 P60722 oasc.SyncStrategy.syncToMe https://127.0.0.1:60722/collection1/ has no replicas
   [junit4]   2> 118281 T433 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:60722/collection1/ shard2
   [junit4]   2> 118282 T433 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 118285 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 118321 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118372 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118384 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118506 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118507 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118507 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118552 T433 oasc.ZkController.register We are https://127.0.0.1:60722/collection1/ and leader is https://127.0.0.1:60722/collection1/
   [junit4]   2> 118552 T433 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:60722
   [junit4]   2> 118552 T433 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 118552 T433 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 118552 T433 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 118556 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118556 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118556 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118560 T433 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 118565 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 118574 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60722",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60722_",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 118584 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 118625 T382 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 118626 T382 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 118638 T382 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 118639 T382 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60731
   [junit4]   2> 118643 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 118643 T382 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 118643 T382 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-004
   [junit4]   2> 118643 T382 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-004\'
   [junit4]   2> 118674 T382 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-004\solr.xml
   [junit4]   2> 118688 T382 oasc.CoreContainer.<init> New CoreContainer 362396608
   [junit4]   2> 118688 T382 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-004\]
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 118690 T382 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 118698 T382 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 118698 T382 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 118698 T382 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 118698 T382 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60699/solr
   [junit4]   2> 118698 T382 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 118704 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 118713 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118715 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118715 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 118717 T444 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@516a07f6 name:ZooKeeperConnection Watcher:127.0.0.1:60699 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 118717 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 118731 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 118737 T446 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ff66dfe name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 118738 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 118755 T382 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 119911 T382 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60731_
   [junit4]   2> 119916 T382 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60731_
   [junit4]   2> 119928 T428 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 119929 T415 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 119932 T446 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 119932 T406 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 119960 T447 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 119960 T447 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 119965 T447 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 119966 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 119966 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 119966 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 119971 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 119973 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60731",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60731_",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 119973 T407 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 119973 T407 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 119980 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 120101 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 120101 T446 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 120102 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 120101 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121116 T447 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 121116 T447 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 121118 T447 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 121118 T447 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 121124 T447 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 121124 T447 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-004\collection1\'
   [junit4]   2> 121158 T447 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 121167 T447 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 121171 T447 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 121177 T447 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 121282 T447 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 121283 T447 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 121285 T447 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 121285 T447 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 121288 T447 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 121290 T447 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 121290 T447 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-004\collection1\, dataDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty2\
   [junit4]   2> 121290 T447 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 121292 T447 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty2\
   [junit4]   2> 121292 T447 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty2\index/
   [junit4]   2> 121292 T447 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty2\index' doesn't exist. Creating new index...
   [junit4]   2> 121293 T447 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty2\index
   [junit4]   2> 121302 T447 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@7cfcfca lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty2\index)),segFN=segments_1,generation=1}
   [junit4]   2> 121302 T447 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 121306 T447 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 121306 T447 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 121306 T447 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 121306 T447 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 121307 T447 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 121307 T447 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 121307 T447 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 121308 T447 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 121314 T447 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 121316 T447 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 121317 T447 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 121318 T447 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@7cfcfca lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty2\index)),segFN=segments_1,generation=1}
   [junit4]   2> 121318 T447 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 121318 T447 oass.SolrIndexSearcher.<init> Opening Searcher@1ba00ddd[collection1] main
   [junit4]   2> 121319 T447 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 121322 T447 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 121322 T447 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 121323 T447 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 121324 T447 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 121324 T447 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 121325 T447 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 121325 T447 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 121325 T447 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 121325 T447 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 121328 T447 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 121328 T448 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ba00ddd[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 121329 T451 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:60731 collection:collection1 shard:shard1
   [junit4]   2> 121331 T382 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
   [junit4]   2> 121331 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 121333 T451 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 121351 T451 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 121358 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121358 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121358 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121367 T451 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 121367 T451 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C100 name=collection1 org.apache.solr.core.SolrCore@693f6ee6 url=https://127.0.0.1:60731/collection1 node=127.0.0.1:60731_ C100_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:60731, core=collection1, node_name=127.0.0.1:60731_}
   [junit4]   2> 121367 T451 C100 P60731 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:60731/collection1/
   [junit4]   2> 121367 T451 C100 P60731 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 121367 T451 C100 P60731 oasc.SyncStrategy.syncToMe https://127.0.0.1:60731/collection1/ has no replicas
   [junit4]   2> 121367 T451 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:60731/collection1/ shard1
   [junit4]   2> 121368 T451 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 121377 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 121388 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121392 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121406 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121527 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121529 T446 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121529 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121529 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121574 T451 oasc.ZkController.register We are https://127.0.0.1:60731/collection1/ and leader is https://127.0.0.1:60731/collection1/
   [junit4]   2> 121574 T451 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:60731
   [junit4]   2> 121574 T451 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 121574 T451 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 121574 T451 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 121580 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121580 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121580 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121582 T451 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 121585 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 121588 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"active",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60731",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60731_",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node2"}
   [junit4]   2> 121594 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 121717 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121717 T446 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121717 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121718 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 121792 T382 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
   [junit4]   2> 121794 T382 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 121807 T382 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 121809 T382 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60740
   [junit4]   2> 121813 T382 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 121814 T382 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 121814 T382 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-005
   [junit4]   2> 121814 T382 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-005\'
   [junit4]   2> 121846 T382 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-005\solr.xml
   [junit4]   2> 121860 T382 oasc.CoreContainer.<init> New CoreContainer 1930307971
   [junit4]   2> 121860 T382 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-005\]
   [junit4]   2> 121861 T382 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 121861 T382 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 121861 T382 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 121861 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 121862 T382 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 121862 T382 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 121862 T382 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 121862 T382 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 121863 T382 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 121870 T382 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 121870 T382 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 121870 T382 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 121871 T382 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60699/solr
   [junit4]   2> 121871 T382 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 121873 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 121877 T462 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a5bd2d6 name:ZooKeeperConnection Watcher:127.0.0.1:60699 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 121879 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 121886 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 121890 T464 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a3dd5ff name:ZooKeeperConnection Watcher:127.0.0.1:60699/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 121890 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 121908 T382 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 123063 T382 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60740_
   [junit4]   2> 123066 T382 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60740_
   [junit4]   2> 123079 T464 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 123080 T428 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 123080 T415 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 123081 T446 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 123080 T406 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 123096 T465 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 123096 T465 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 123098 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 123098 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 123098 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 123098 T465 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 123104 T407 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 123105 T407 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2> 	  "operation":"state",
   [junit4]   2> 	  "state":"down",
   [junit4]   2> 	  "base_url":"https://127.0.0.1:60740",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:60740_",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 123105 T407 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 123105 T407 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 123112 T406 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 123235 T428 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 123235 T415 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 123238 T446 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 123239 T406 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 123236 T464 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 124249 T465 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 124249 T465 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 124252 T465 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 124252 T465 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 124257 T465 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 124257 T465 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-005\collection1\'
   [junit4]   2> 124298 T465 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 124303 T465 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 124307 T465 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 124317 T465 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 124415 T465 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 124416 T465 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 124417 T465 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 124419 T465 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 124419 T465 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 124419 T465 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 124419 T465 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-005\collection1\, dataDir=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty3\
   [junit4]   2> 124420 T465 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 124421 T465 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty3\
   [junit4]   2> 124421 T465 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty3\index/
   [junit4]   2> 124421 T465 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty3\index' doesn't exist. Creating new index...
   [junit4]   2> 124422 T465 oasc.CachingDirectoryFactory.get return new directory for .\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001/jetty3\index
   [junit4]   2> 124426 T465 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@6a7fe1d9 lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty3\index)),segFN=segments_1,generation=1}
   [junit4]   2> 124426 T465 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 124427 T465 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 124427 T465 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 124429 T465 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 124429 T465 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 124429 T465 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 124429 T465 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 124430 T465 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 124430 T465 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 124434 T465 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 124436 T465 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 124436 T465 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 124438 T465 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@6a7fe1d9 lockFactory=NativeFSLockFactory@.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001\tempDir-001\jetty3\index)),segFN=segments_1,generation=1}
   [junit4]   2> 124438 T465 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 124439 T465 oass.SolrIndexSearcher.<init> Opening Searcher@13bc43d8[collection1] main
   [junit4]   2> 124439 T465 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 124441 T465 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 124441 T465 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 124442 T465 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 124442 T465 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 124442 T465 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 124444 T465 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 124444 T465 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 124444 T465 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 124444 T465 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 124449 T465 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 124449 T466 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13bc43d8[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 124450 T469 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:60740 collection:collection

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

###Ending testWrongZkChrootTest
   [junit4]   2> 134809 T382 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60780 60780
   [junit4]   2> 137083 T482 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60780 60780
   [junit4]   2> 137089 T382 oas.SolrTestCaseJ4.setUp ###Starting testShutdown
   [junit4]   2> 137092 T382 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 137094 T495 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 137187 T382 oasc.ZkTestServer.run start zk server on port:60795
   [junit4]   2> 137189 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 137197 T501 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@351db92d name:ZooKeeperConnection Watcher:127.0.0.1:60795 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 137197 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 137197 T382 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 137206 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 137212 T503 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@58720031 name:ZooKeeperConnection Watcher:127.0.0.1:60795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 137212 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 137212 T382 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 137218 T382 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 137223 T382 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 137228 T382 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 137232 T382 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 137232 T382 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 137238 T382 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 137238 T382 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 137244 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml because it doesn't exist
   [junit4]   2> 137244 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt because it doesn't exist
   [junit4]   2> 137245 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt because it doesn't exist
   [junit4]   2> 137245 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml because it doesn't exist
   [junit4]   2> 137245 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\enumsConfig.xml because it doesn't exist
   [junit4]   2> 137245 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json because it doesn't exist
   [junit4]   2> 137246 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt because it doesn't exist
   [junit4]   2> 137246 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt because it doesn't exist
   [junit4]   2> 137247 T382 oasc.AbstractZkTestCase.putConfig skipping C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt because it doesn't exist
   [junit4]   2> 137250 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 137253 T505 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6283a715 name:ZooKeeperConnection Watcher:127.0.0.1:60795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 137254 T382 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 137257 T496 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x146a14837ce0002, 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> 137260 T382 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 137261 T506 oaz.ClientCnxn$SendThread.run WARN Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect java.net.BindException: Cannot assign requested address: no further information
   [junit4]   2> 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
   [junit4]   2> 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:712)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
   [junit4]   2> 
   [junit4]   2> 138383 T382 oas.SolrTestCaseJ4.tearDown ###Ending testShutdown
   [junit4]   2> 138387 T382 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60795 60795
   [junit4]   2> 140490 T495 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60795 60795
   [junit4]   2> 140503 T382 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solr.client.solrj.impl.CloudSolrServerTest-77B74CD39F00A0CA-001
   [junit4]   2> NOTE: test params are: codec=Lucene49: {_version_=PostingsFormat(name=TestBloomFilteredLucene41Postings), multiDefault=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=TestBloomFilteredLucene41Postings), intDefault=PostingsFormat(name=TestBloomFilteredLucene41Postings), range_facet_sl=Pulsing41(freqCutoff=11 minBlockSize=46 maxBlockSize=138), range_facet_si=PostingsFormat(name=Memory doPackFST= true), id=Pulsing41(freqCutoff=5 minBlockSize=46 maxBlockSize=138), text=Pulsing41(freqCutoff=11 minBlockSize=46 maxBlockSize=138), range_facet_l=PostingsFormat(name=TestBloomFilteredLucene41Postings), timestamp=PostingsFormat(name=TestBloomFilteredLucene41Postings)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_AE, timezone=Europe/Sofia
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=59554344,total=204472320
   [junit4]   2> NOTE: All tests run in this JVM: [SolrExampleBinaryTest, NoOpResponseParserTest, TestNamedListCodec, SolrExceptionTest, ClientUtilsTest, QueryResponseTest, JettyWebappTest, TestLBHttpSolrServer, TestBatchUpdate, AnlysisResponseBaseTest, SolrExampleStreamingBinaryTest, ContentStreamTest, TestSolrProperties, TestHash, NamedListTest, SolrPingTest, TermsResponseTest, TestJavaBinCodec, SolrQueryTest, SolrExampleEmbeddedTest, TestRetryUtil, MultiCoreExampleJettyTest, FieldAnalysisResponseTest, LargeVolumeBinaryJettyTest, CloudSolrServerTest]
   [junit4] Completed in 30.45s, 3 tests, 1 failure <<< FAILURES!

[...truncated 78 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:45: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:202: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:496: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1297: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:921: There were test failures: 49 suites, 295 tests, 1 failure

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



Mime
View raw message