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-trunk-MacOSX (64bit/jdk1.7.0) - Build # 1455 - Failure!
Date Mon, 31 Mar 2014 16:45:20 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1455/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC -XX:-UseSuperWord

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

Error Message:


Stack Trace:
java.lang.AssertionError
	at __randomizedtesting.SeedInfo.seed([86DDB259A55509F8:73B3C41D20A69C4]: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:256)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
	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:70)
	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:359)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
	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 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:70)
	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:359)
	at java.lang.Thread.run(Thread.java:744)




Build Log:
[...truncated 12185 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest
   [junit4]   2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495
   [junit4]   2> 72926 T90 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 72929 T90 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /f_yzs/f
   [junit4]   2> 72943 T90 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test?
   [junit4]   2> 72990 T90 oas.SolrTestCaseJ4.setUp ###Starting testShutdown
   [junit4]   2> 73020 T90 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 73031 T91 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 73334 T90 oasc.ZkTestServer.run start zk server on port:52961
   [junit4]   2> 73657 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 73728 T92 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 75855 T97 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@296e455e name:ZooKeeperConnection Watcher:127.0.0.1:52961 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 75860 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 75866 T90 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 76017 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 76026 T99 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@391321da name:ZooKeeperConnection Watcher:127.0.0.1:52961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 76028 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 76041 T90 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 76062 T90 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 76113 T90 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 76124 T90 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 76140 T90 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 76143 T90 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 76165 T90 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 76167 T90 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 76181 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
   [junit4]   2> 76182 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
   [junit4]   2> 76183 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
   [junit4]   2> 76184 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
   [junit4]   2> 76185 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
   [junit4]   2> 76186 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
   [junit4]   2> 76187 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
   [junit4]   2> 76188 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
   [junit4]   2> 76191 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
   [junit4]   2> 76230 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 76238 T101 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e76dbc6 name:ZooKeeperConnection Watcher:127.0.0.1:52961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 76238 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 76304 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 76311 T102 oaz.ClientCnxnSocketNIO.connect ERROR Unable to open socket to ff01::114/ff01:0:0:0:0:0:0:114:33332
   [junit4]   2> 76327 T102 oaz.ClientCnxn$SendThread.run WARN Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect java.net.SocketException: Address family not supported by protocol family
   [junit4]   2> 	at sun.nio.ch.Net.connect0(Native Method)
   [junit4]   2> 	at sun.nio.ch.Net.connect(Net.java:465)
   [junit4]   2> 	at sun.nio.ch.Net.connect(Net.java:457)
   [junit4]   2> 	at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:670)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.registerAndConnect(ClientCnxnSocketNIO.java:277)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.connect(ClientCnxnSocketNIO.java:287)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:967)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
   [junit4]   2> 
   [junit4]   2> 77439 T102 oaz.ClientCnxnSocketNIO.connect ERROR Unable to open socket to ff01::114/ff01:0:0:0:0:0:0:114:33332
   [junit4]   2> 77604 T90 oas.SolrTestCaseJ4.tearDown ###Ending testShutdown
   [junit4]   2> 77615 T90 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:52961 52961
   [junit4]   2> 77646 T91 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:52961 52961
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 78022 T90 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 78025 T90 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 78030 T104 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 78131 T90 oasc.ZkTestServer.run start zk server on port:52966
   [junit4]   2> 78133 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 78145 T110 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c7a9717 name:ZooKeeperConnection Watcher:127.0.0.1:52966 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 78146 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 78147 T90 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 78182 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 78189 T112 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2dfbe29 name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 78190 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 78190 T90 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 78206 T90 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 78220 T90 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 78229 T90 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 78244 T90 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 78244 T90 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 78266 T90 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 78267 T90 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 78281 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
   [junit4]   2> 78281 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
   [junit4]   2> 78282 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
   [junit4]   2> 78284 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
   [junit4]   2> 78284 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
   [junit4]   2> 78284 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
   [junit4]   2> 78285 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
   [junit4]   2> 78286 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
   [junit4]   2> 78286 T90 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
   [junit4]   2> 78293 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 78300 T114 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1934433 name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 78301 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 78735 T90 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 78757 T90 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 78776 T90 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:52970
   [junit4]   2> 78815 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 78817 T90 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 78817 T90 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1396284031879
   [junit4]   2> 78818 T90 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1396284031879/'
   [junit4]   2> 78943 T90 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1396284031879/solr.xml
   [junit4]   2> 79171 T90 oasc.CoreContainer.<init> New CoreContainer 1034538002
   [junit4]   2> 79172 T90 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1396284031879/]
   [junit4]   2> 79176 T90 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 79176 T90 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 79177 T90 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 79178 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 79179 T90 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 79180 T90 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 79181 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 79181 T90 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 79182 T90 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 79189 T90 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 79190 T90 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 79191 T90 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 79193 T90 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52966/solr
   [junit4]   2> 79250 T90 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 79253 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 79259 T125 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@21654a8f name:ZooKeeperConnection Watcher:127.0.0.1:52966 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 79261 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 79301 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 79306 T127 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d0cb215 name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 79307 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 79349 T90 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 79378 T90 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 79402 T90 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
   [junit4]   2> 79417 T90 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
   [junit4]   2> 79432 T90 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
   [junit4]   2> 79476 T90 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 79484 T90 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52970_f_yzs%2Ff
   [junit4]   2> 79497 T90 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52970_f_yzs%2Ff
   [junit4]   2> 79513 T90 oascc.SolrZkClient.makePath makePath: /overseer_elect
   [junit4]   2> 79524 T90 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 79561 T90 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:52970_f_yzs%2Ff
   [junit4]   2> 79562 T90 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 79579 T90 oasc.Overseer.start Overseer (id=91506870296051716-127.0.0.1:52970_f_yzs%2Ff-n_0000000000) starting
   [junit4]   2> 79631 T90 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 79711 T129 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 79714 T90 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 79722 T129 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
   [junit4]   2> 79728 T90 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 79737 T90 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 79779 T128 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 80154 T130 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
   [junit4]   2> 80155 T130 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 80167 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 80168 T130 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 80181 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 80185 T128 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:52970/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52970_f_yzs%2Ff",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"1",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 80186 T128 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 80238 T128 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 80254 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 80271 T127 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> 81171 T130 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 81171 T130 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 81178 T130 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 81179 T130 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 81182 T130 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 81186 T130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1396284031879/collection1/'
   [junit4]   2> 81336 T130 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 81528 T130 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 81535 T130 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 81586 T130 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 82662 T130 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 82675 T130 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 82677 T130 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 82679 T130 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 82681 T130 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 82681 T130 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 82682 T130 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-controljetty-1396284031879/collection1/, dataDir=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data/
   [junit4]   2> 82682 T130 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 82702 T130 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data
   [junit4]   2> 82702 T130 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data/index/
   [junit4]   2> 82702 T130 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 82703 T130 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data/index
   [junit4]   2> 82708 T130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@66ef2238 lockFactory=NativeFSLockFactory@./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data/index),segFN=segments_1,generation=1}
   [junit4]   2> 82708 T130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 82712 T130 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 82712 T130 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 82712 T130 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 82713 T130 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 82714 T130 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 82714 T130 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 82715 T130 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 82715 T130 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 82731 T130 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 82740 T130 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 82741 T130 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 82743 T130 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@66ef2238 lockFactory=NativeFSLockFactory@./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/control/data/index),segFN=segments_1,generation=1}
   [junit4]   2> 82743 T130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 82744 T130 oass.SolrIndexSearcher.<init> Opening Searcher@5211eb0a[collection1] main
   [junit4]   2> 82745 T130 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 82754 T130 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 82755 T130 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 82794 T130 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 82794 T130 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 82794 T130 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 82797 T130 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 82798 T130 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 82798 T130 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 82799 T130 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 82801 T131 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5211eb0a[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 82801 T130 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 82805 T134 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:52970/f_yzs/f collection:control_collection shard:shard1
   [junit4]   2> 82806 T90 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
   [junit4]   2> 82807 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 82819 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 82825 T136 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74a1e0a5 name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 82825 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 82830 T90 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 82844 T90 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 82854 T134 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 82933 T134 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 82944 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 82953 T134 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 82954 T134 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C10 name=collection1 org.apache.solr.core.SolrCore@369336bb url=https://127.0.0.1:52970/f_yzs/f/collection1 node=127.0.0.1:52970_f_yzs%2Ff C10_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:52970/f_yzs/f, core=collection1, node_name=127.0.0.1:52970_f_yzs%2Ff}
   [junit4]   2> 82969 T134 C10 P52970 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:52970/f_yzs/f/collection1/
   [junit4]   2> 82970 T134 C10 P52970 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 82970 T134 C10 P52970 oasc.SyncStrategy.syncToMe https://127.0.0.1:52970/f_yzs/f/collection1/ has no replicas
   [junit4]   2> 82971 T134 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:52970/f_yzs/f/collection1/ shard1
   [junit4]   2> 82979 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 82991 T134 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 83010 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 83014 T136 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> 83014 T127 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> 83059 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 83080 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 83198 T136 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> 83205 T127 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> 83262 T134 oasc.ZkController.register We are https://127.0.0.1:52970/f_yzs/f/collection1/ and leader is https://127.0.0.1:52970/f_yzs/f/collection1/
   [junit4]   2> 83264 T134 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:52970/f_yzs/f
   [junit4]   2> 83264 T134 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 83264 T134 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
   [junit4]   2> 83265 T134 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 83269 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 83270 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 83271 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 83279 T134 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 83288 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 83292 T128 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:52970/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52970_f_yzs%2Ff",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"control_collection",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 83306 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 83343 T90 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
   [junit4]   2> 83345 T90 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 83358 T90 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 83366 T90 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:52974
   [junit4]   2> 83372 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 83373 T90 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 83374 T90 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1396284036416
   [junit4]   2> 83374 T90 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1396284036416/'
   [junit4]   2> 83418 T127 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> 83419 T136 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> 83468 T90 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1396284036416/solr.xml
   [junit4]   2> 83662 T90 oasc.CoreContainer.<init> New CoreContainer 1475601909
   [junit4]   2> 83662 T90 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1396284036416/]
   [junit4]   2> 83665 T90 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 83666 T90 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 83666 T90 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 83667 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 83667 T90 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 83668 T90 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 83669 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 83669 T90 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 83670 T90 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 83682 T90 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 83683 T90 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 83684 T90 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 83686 T90 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52966/solr
   [junit4]   2> 83688 T90 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 83692 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 83701 T147 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3f442d2d name:ZooKeeperConnection Watcher:127.0.0.1:52966 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 83703 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 83716 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 83727 T149 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18e21b1a name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 83728 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 83787 T90 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 84807 T90 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52974_f_yzs%2Ff
   [junit4]   2> 84811 T90 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52974_f_yzs%2Ff
   [junit4]   2> 84825 T136 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 84827 T127 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 84827 T149 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 84872 T150 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 84874 T150 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 84879 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 84881 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 84883 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 84881 T150 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 84891 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 84894 T128 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:52974/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52974_f_yzs%2Ff",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 84895 T128 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
   [junit4]   2> 84895 T128 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 84909 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 85019 T127 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> 85019 T149 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> 85020 T136 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> 85888 T150 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 85888 T150 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 85891 T150 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 85891 T150 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 85895 T150 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 85896 T150 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1396284036416/collection1/'
   [junit4]   2> 85981 T150 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 86143 T150 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 86150 T150 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 86186 T150 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 87408 T150 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 87418 T150 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 87422 T150 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 87426 T150 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 87427 T150 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 87428 T150 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 87429 T150 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty1-1396284036416/collection1/, dataDir=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1/
   [junit4]   2> 87429 T150 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 87431 T150 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1
   [junit4]   2> 87432 T150 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1/index/
   [junit4]   2> 87433 T150 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 87434 T150 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1/index
   [junit4]   2> 87439 T150 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@603b88a8 lockFactory=NativeFSLockFactory@./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1/index),segFN=segments_1,generation=1}
   [junit4]   2> 87439 T150 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 87444 T150 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 87445 T150 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 87446 T150 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 87446 T150 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 87447 T150 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 87448 T150 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 87449 T150 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 87449 T150 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 87464 T150 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 87474 T150 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 87474 T150 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 87478 T150 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@603b88a8 lockFactory=NativeFSLockFactory@./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty1/index),segFN=segments_1,generation=1}
   [junit4]   2> 87479 T150 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 87480 T150 oass.SolrIndexSearcher.<init> Opening Searcher@211f70d7[collection1] main
   [junit4]   2> 87480 T150 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 87487 T150 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 87487 T150 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 87490 T150 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 87490 T150 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 87491 T150 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 87494 T150 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 87494 T150 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 87494 T150 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 87495 T150 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 87499 T151 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@211f70d7[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 87499 T150 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 87501 T154 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:52974/f_yzs/f collection:collection1 shard:shard2
   [junit4]   2> 87504 T90 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
   [junit4]   2> 87505 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 87507 T154 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 87541 T154 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
   [junit4]   2> 87569 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87570 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87570 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87581 T154 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 87582 T154 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C11 name=collection1 org.apache.solr.core.SolrCore@4de9f8f8 url=https://127.0.0.1:52974/f_yzs/f/collection1 node=127.0.0.1:52974_f_yzs%2Ff C11_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:52974/f_yzs/f, core=collection1, node_name=127.0.0.1:52974_f_yzs%2Ff}
   [junit4]   2> 87582 T154 C11 P52974 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:52974/f_yzs/f/collection1/
   [junit4]   2> 87584 T154 C11 P52974 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 87584 T154 C11 P52974 oasc.SyncStrategy.syncToMe https://127.0.0.1:52974/f_yzs/f/collection1/ has no replicas
   [junit4]   2> 87585 T154 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:52974/f_yzs/f/collection1/ shard2
   [junit4]   2> 87586 T154 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 87588 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 87612 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87617 T136 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> 87618 T127 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> 87623 T149 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> 87672 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87689 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 87707 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87819 T136 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> 87820 T127 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> 87824 T149 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> 87837 T154 oasc.ZkController.register We are https://127.0.0.1:52974/f_yzs/f/collection1/ and leader is https://127.0.0.1:52974/f_yzs/f/collection1/
   [junit4]   2> 87838 T154 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:52974/f_yzs/f
   [junit4]   2> 87838 T154 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 87838 T154 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 87839 T154 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 87851 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87852 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87854 T154 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 87863 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 87866 T128 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:52974/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52974_f_yzs%2Ff",
   [junit4]   2> 	  "shard":"shard2",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node1"}
   [junit4]   2> 87880 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 87991 T149 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> 87991 T127 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> 87991 T136 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> 88021 T90 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
   [junit4]   2> 88022 T90 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 88037 T90 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 88042 T90 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:52977
   [junit4]   2> 88047 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 88048 T90 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 88048 T90 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1396284041082
   [junit4]   2> 88049 T90 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1396284041082/'
   [junit4]   2> 88109 T90 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1396284041082/solr.xml
   [junit4]   2> 88301 T90 oasc.CoreContainer.<init> New CoreContainer 1434116570
   [junit4]   2> 88302 T90 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1396284041082/]
   [junit4]   2> 88305 T90 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 88305 T90 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 88306 T90 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 88306 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 88307 T90 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 88308 T90 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 88309 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 88309 T90 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 88310 T90 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 88319 T90 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 88319 T90 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 88320 T90 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 88321 T90 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52966/solr
   [junit4]   2> 88322 T90 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 88325 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 88331 T165 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@101e0247 name:ZooKeeperConnection Watcher:127.0.0.1:52966 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 88332 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 88342 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 88348 T167 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5342836a name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 88349 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 88387 T90 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 89404 T90 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52977_f_yzs%2Ff
   [junit4]   2> 89407 T90 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52977_f_yzs%2Ff
   [junit4]   2> 89425 T149 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 89426 T136 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 89426 T127 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 89427 T167 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 89460 T168 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 89461 T168 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 89465 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 89465 T168 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 89466 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 89467 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 89474 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 89476 T128 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:52977/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52977_f_yzs%2Ff",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 89477 T128 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 89477 T128 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 89488 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 89492 T149 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> 89493 T136 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> 89495 T167 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> 89494 T127 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> 90467 T168 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 90467 T168 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 90470 T168 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 90470 T168 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 90479 T168 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 90480 T168 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1396284041082/collection1/'
   [junit4]   2> 90607 T168 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 90780 T168 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 90788 T168 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 90822 T168 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 91806 T168 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 91812 T168 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 91816 T168 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 91816 T168 oass.IndexSchema.readSchema WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 91817 T168 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 91818 T168 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 91819 T168 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty2-1396284041082/collection1/, dataDir=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/
   [junit4]   2> 91819 T168 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 91821 T168 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2
   [junit4]   2> 91821 T168 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index/
   [junit4]   2> 91822 T168 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 91823 T168 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index
   [junit4]   2> 91828 T168 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@1d1e71f9 lockFactory=NativeFSLockFactory@./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index),segFN=segments_1,generation=1}
   [junit4]   2> 91828 T168 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 91831 T168 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 91832 T168 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 91832 T168 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 91832 T168 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 91833 T168 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 91835 T168 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 91835 T168 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 91836 T168 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler
   [junit4]   2> 91849 T168 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 91870 T168 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 91870 T168 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 91875 T168 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(RAMDirectory@1d1e71f9 lockFactory=NativeFSLockFactory@./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index),segFN=segments_1,generation=1}
   [junit4]   2> 91876 T168 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 91877 T168 oass.SolrIndexSearcher.<init> Opening Searcher@7ac63f7e[collection1] main
   [junit4]   2> 91878 T168 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 91888 T168 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 91888 T168 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 91891 T168 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 91891 T168 oasr.RestManager.init Initializing RestManager with initArgs: {}
   [junit4]   2> 91892 T168 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 91895 T168 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 91896 T168 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 91897 T168 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 91898 T168 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 91908 T169 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ac63f7e[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 91909 T168 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 91912 T172 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:52977/f_yzs/f collection:collection1 shard:shard1
   [junit4]   2> 91913 T90 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
   [junit4]   2> 91914 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 91952 T172 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 92009 T172 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 92027 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 92030 T172 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 92031 T172 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> ASYNC  NEW_CORE C12 name=collection1 org.apache.solr.core.SolrCore@3818b20f url=https://127.0.0.1:52977/f_yzs/f/collection1 node=127.0.0.1:52977_f_yzs%2Ff C12_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:52977/f_yzs/f, core=collection1, node_name=127.0.0.1:52977_f_yzs%2Ff}
   [junit4]   2> 92031 T172 C12 P52977 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:52977/f_yzs/f/collection1/
   [junit4]   2> 92032 T172 C12 P52977 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 92032 T172 C12 P52977 oasc.SyncStrategy.syncToMe https://127.0.0.1:52977/f_yzs/f/collection1/ has no replicas
   [junit4]   2> 92033 T172 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:52977/f_yzs/f/collection1/ shard1
   [junit4]   2> 92033 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 92033 T172 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 92088 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 92094 T127 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> 92096 T167 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> 92098 T149 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> 92100 T136 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> 92166 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 92209 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 92329 T136 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> 92332 T149 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> 92333 T127 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> 92336 T167 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> 92361 T172 oasc.ZkController.register We are https://127.0.0.1:52977/f_yzs/f/collection1/ and leader is https://127.0.0.1:52977/f_yzs/f/collection1/
   [junit4]   2> 92361 T172 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:52977/f_yzs/f
   [junit4]   2> 92362 T172 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 92363 T172 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
   [junit4]   2> 92364 T172 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 92374 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 92375 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 92376 T172 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 92388 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 92392 T128 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:52977/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52977_f_yzs%2Ff",
   [junit4]   2> 	  "shard":"shard1",
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":"core_node2"}
   [junit4]   2> 92426 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 92470 T90 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
   [junit4]   2> 92471 T90 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 92521 T90 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 92528 T90 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:52980
   [junit4]   2> 92535 T90 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 92536 T90 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 92537 T90 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1396284045491
   [junit4]   2> 92538 T90 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1396284045491/'
   [junit4]   2> 92576 T167 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> 92577 T136 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> 92578 T149 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> 92579 T127 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> 92645 T90 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1396284045491/solr.xml
   [junit4]   2> 92862 T90 oasc.CoreContainer.<init> New CoreContainer 371279599
   [junit4]   2> 92863 T90 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-jetty3-1396284045491/]
   [junit4]   2> 92866 T90 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 92866 T90 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: 
   [junit4]   2> 92866 T90 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 92867 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 92867 T90 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 92868 T90 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 92868 T90 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 92869 T90 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 92869 T90 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 92874 T90 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 92875 T90 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 92875 T90 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 92876 T90 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52966/solr
   [junit4]   2> 92876 T90 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 92879 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 92884 T183 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22242484 name:ZooKeeperConnection Watcher:127.0.0.1:52966 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 92886 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 92897 T90 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 92901 T185 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f93eb44 name:ZooKeeperConnection Watcher:127.0.0.1:52966/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 92902 T90 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 92955 T90 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
   [junit4]   2> 93971 T90 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52980_f_yzs%2Ff
   [junit4]   2> 93975 T90 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52980_f_yzs%2Ff
   [junit4]   2> 93991 T167 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 93991 T136 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 93992 T127 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 93993 T149 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 93994 T185 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 94026 T186 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 94027 T186 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 94031 T186 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 94032 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 94033 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 94033 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 94041 T128 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
   [junit4]   2> 94044 T128 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:52980/f_yzs/f",
   [junit4]   2> 	  "core":"collection1",
   [junit4]   2> 	  "roles":null,
   [junit4]   2> 	  "node_name":"127.0.0.1:52980_f_yzs%2Ff",
   [junit4]   2> 	  "shard":null,
   [junit4]   2> 	  "collection":"collection1",
   [junit4]   2> 	  "numShards":"2",
   [junit4]   2> 	  "core_node_name":null}
   [junit4]   2> 94046 T128 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 94046 T128 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 94056 T127 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
   [junit4]   2> 94061 T127 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> 94061 T185 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstat

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

st-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2;done=false>>]
   [junit4]   2> 105178 T90 oasc.CachingDirectoryFactory.close Closing directory: ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2
   [junit4]   2> 105179 T90 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index [CachedDir<<refCount=0;path=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index;done=false>>]
   [junit4]   2> 105181 T90 oasc.CachingDirectoryFactory.close Closing directory: ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty2/index
   [junit4]   2> 105181 T167 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> 105226 T90 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/f_yzs/f,null}
   [junit4]   2> 105281 T90 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 52980
   [junit4]   2> 105281 T90 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=371279599
   [junit4]   2> 105282 T90 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
   [junit4]   2> 105282 T90 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 105307 T90 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/f_yzs/f,null}
   [junit4]   2> 105362 T90 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> 105365 T90 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:52966 52966
   [junit4]   2> 105376 T104 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:52966 52966
   [junit4]   1> client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CloudSolrServerTest -Dtests.method=testDistribSearch -Dtests.seed=86DDB259A55509F8 -Dtests.slow=true -Dtests.locale=lt_LT -Dtests.timezone=Australia/Darwin -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 27.8s | CloudSolrServerTest.testDistribSearch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([86DDB259A55509F8:73B3C41D20A69C4]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:256)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 105779 T90 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 106419 T127 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 106567 T149 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 106699 T167 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 107586 T185 C18 P52980 oasc.SyncStrategy.sync WARN Closed, skipping sync up.
   [junit4]   2> 107610 T185 oasc.ShardLeaderElectionContext.rejoinLeaderElection Not rejoining election because CoreContainer is shutdown
   [junit4]   2> 107610 T185 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5a9adfda
   [junit4]   2> 107612 T185 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=5,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=354,transaction_logs_total_number=4}
   [junit4]   2> 107612 T185 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 107613 T185 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 107613 T185 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 107621 T185 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 107622 T185 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 107622 T185 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty3 [CachedDir<<refCount=0;path=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty3;done=false>>]
   [junit4]   2> 107623 T185 oasc.CachingDirectoryFactory.close Closing directory: ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty3
   [junit4]   2> 107623 T185 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty3/index [CachedDir<<refCount=0;path=./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty3/index;done=false>>]
   [junit4]   2> 107624 T185 oasc.CachingDirectoryFactory.close Closing directory: ./solrtest-CloudSolrServerTest-1396284026495/org.apache.solr.client.solrj.impl.CloudSolrServerTest-1396284031595/jetty3/index
   [junit4]   2> 107624 T185 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> 107625 T185 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 107625 T185 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> 107626 T185 oasc.LeaderElector$1.process WARN  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:260)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:257)
   [junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:257)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:92)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
   [junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 
   [junit4]   2> NOTE: test params are: codec=Lucene46: {range_facet_l=MockFixedIntBlock(blockSize=948), timestamp=MockFixedIntBlock(blockSize=948), range_facet_si=PostingsFormat(name=Lucene41VarGapFixedInterval), id=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), intDefault=MockFixedIntBlock(blockSize=948), a_t=MockFixedIntBlock(blockSize=948), range_facet_sl=PostingsFormat(name=FSTPulsing41), _version_=MockFixedIntBlock(blockSize=948), text=PostingsFormat(name=FSTPulsing41), multiDefault=PostingsFormat(name=Lucene41VarGapFixedInterval)}, docValues:{}, sim=DefaultSimilarity, locale=lt_LT, timezone=Australia/Darwin
   [junit4]   2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_51 (64-bit)/cpus=2,threads=1,free=95848432,total=167772160
   [junit4]   2> NOTE: All tests run in this JVM: [LargeVolumeEmbeddedTest, LargeVolumeBinaryJettyTest, NoOpResponseParserTest, TestXMLEscaping, SolrExampleJettyTest, TestHash, SolrPingTest, QueryResponseTest, CloudSolrServerTest]
   [junit4] Completed in 35.54s, 2 tests, 1 failure <<< FAILURES!

[...truncated 129 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:202: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1275: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:907: There were test failures: 49 suites, 284 tests, 1 failure

Total time: 124 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC -XX:-UseSuperWord
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message