Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1744/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed. REGRESSION: org.apache.solr.client.solrj.impl.CloudSolrServerTest.testDistribSearch Error Message: https://127.0.0.1:58186/collection1/ Stack Trace: org.apache.solr.common.SolrException: https://127.0.0.1:58186/collection1/ at __randomizedtesting.SeedInfo.seed([2F988AA3C8F590C1:AE7E04BBBFAAF0FD]:0) at org.apache.solr.client.solrj.impl.CloudSolrServer.directUpdate(CloudSolrServer.java:401) at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:533) at org.apache.solr.client.solrj.impl.CloudSolrServerTest.doTest(CloudSolrServerTest.java:142) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:871) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:58186/collection1 at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:563) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:340) at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:301) at org.apache.solr.client.solrj.impl.CloudSolrServer.directUpdate(CloudSolrServer.java:398) ... 44 more Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448) ... 49 more Build Log: [...truncated 22346 lines...] [junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrServerTest [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/init-core-data-001 [junit4] 2> 184490 T326 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 184492 T326 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 184502 T326 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME() does not exist - solrj test? [junit4] 2> 184507 T326 oas.SolrTestCaseJ4.setUp ###Starting testWrongZkChrootTest [junit4] 2> 184520 T326 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 184531 T327 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 184732 T326 oasc.ZkTestServer.run start zk server on port:58169 [junit4] 2> 184876 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 184929 T328 oazs.NIOServerCnxn.doIO WARN Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running [junit4] 2> 186167 T333 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5dc144bd name:ZooKeeperConnection Watcher:127.0.0.1:58169 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186169 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 186174 T326 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 186257 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 186262 T335 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@76e14e7f name:ZooKeeperConnection Watcher:127.0.0.1:58169/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186262 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 186267 T326 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 186277 T326 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 186287 T326 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 186293 T326 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 186302 T326 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml [junit4] 2> 186307 T326 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 186324 T326 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 186325 T326 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 186339 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist [junit4] 2> 186339 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist [junit4] 2> 186339 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist [junit4] 2> 186340 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist [junit4] 2> 186340 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist [junit4] 2> 186341 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist [junit4] 2> 186341 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist [junit4] 2> 186342 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist [junit4] 2> 186342 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist [junit4] 2> 186379 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 186389 T337 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@382f0f07 name:ZooKeeperConnection Watcher:127.0.0.1:58169/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186389 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 186413 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 186421 T339 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4edb96e8 name:ZooKeeperConnection Watcher:127.0.0.1:58169/solr/xyz/foo got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186421 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 186424 T326 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 186435 T326 oas.SolrTestCaseJ4.tearDown ###Ending testWrongZkChrootTest [junit4] 2> 186439 T326 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:58169 58169 [junit4] 2> 186453 T327 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:58169 58169 [junit4] 2> 186664 T326 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 186679 T326 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 186681 T340 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 186781 T326 oasc.ZkTestServer.run start zk server on port:58175 [junit4] 2> 186783 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 186793 T346 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@50fabdfa name:ZooKeeperConnection Watcher:127.0.0.1:58175 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186793 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 186794 T326 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 186812 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 186818 T348 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ff3ad0e name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186819 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 186819 T326 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 186839 T326 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 186893 T326 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 186915 T326 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 186943 T326 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml [junit4] 2> 186946 T326 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 186956 T326 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 186959 T326 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 186968 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist [junit4] 2> 186970 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist [junit4] 2> 186971 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist [junit4] 2> 186971 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist [junit4] 2> 186972 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist [junit4] 2> 186973 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist [junit4] 2> 186974 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist [junit4] 2> 186975 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist [junit4] 2> 186975 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist [junit4] 2> 186985 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 186989 T350 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d0d0ece name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 186989 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 187339 T326 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 187349 T326 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 187372 T326 oejs.AbstractConnector.doStart Started [email protected]:58179 [junit4] 2> 187375 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 187376 T326 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 187377 T326 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-003 [junit4] 2> 187378 T326 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-003/' [junit4] 2> 187444 T326 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-003/solr.xml [junit4] 2> 187478 T326 oasc.CoreContainer.<init> New CoreContainer 1112105525 [junit4] 2> 187479 T326 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-003/] [junit4] 2> 187485 T326 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 187486 T326 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 187487 T326 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 187488 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 187489 T326 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 187489 T326 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 187489 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 187492 T326 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 187492 T326 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 187499 T326 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 187505 T326 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 187506 T326 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 187508 T326 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 187511 T326 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58175/solr [junit4] 2> 187587 T326 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 187588 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 187597 T361 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c09cb96 name:ZooKeeperConnection Watcher:127.0.0.1:58175 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 187598 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 187619 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 187624 T363 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c731e49 name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 187624 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 187651 T326 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 187663 T326 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 187685 T326 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 187692 T326 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 187705 T326 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 187768 T326 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 187775 T326 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58179_ [junit4] 2> 187795 T326 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58179_ [junit4] 2> 187841 T326 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 187850 T326 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 187857 T326 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 187891 T326 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:58179_ [junit4] 2> 187891 T326 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 187900 T326 oasc.Overseer.start Overseer (id=92313190530875396-127.0.0.1:58179_-n_0000000000) starting [junit4] 2> 187957 T326 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 188017 T326 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 188038 T365 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 188044 T326 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 188062 T326 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 188086 T326 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 188140 T364 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 188164 T367 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 188165 T367 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 188205 T367 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 188210 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 188221 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58179";, [junit4] 2> "node_name":"127.0.0.1:58179_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 188221 T364 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 188262 T364 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 188287 T368 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> 189208 T367 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 189208 T367 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 189210 T367 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 189210 T367 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 189214 T367 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 189215 T367 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-003/collection1/' [junit4] 2> 189286 T367 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 189292 T367 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 189296 T367 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 189307 T367 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 189350 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.IntField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189376 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.LongField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189410 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.FloatField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189423 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DoubleField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189446 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableIntField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189462 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableLongField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189501 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableFloatField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189521 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.SortableDoubleField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189533 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.BCDIntField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189547 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.BCDLongField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189560 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.BCDStrField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189591 T367 oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis class [solr.DateField]. Please consult documentation how to replace it accordingly. [junit4] 2> 189882 T367 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 189884 T367 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 189892 T367 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 189895 T367 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 189921 T367 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 189921 T367 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 189922 T367 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-003/collection1/, dataDir=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data/ [junit4] 2> 189922 T367 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 189925 T367 oasc.CachingDirectoryFactory.get return new directory for ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data [junit4] 2> 189925 T367 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data/index/ [junit4] 2> 189926 T367 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data/index' doesn't exist. Creating new index... [junit4] 2> 189927 T367 oasc.CachingDirectoryFactory.get return new directory for ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data/index [junit4] 2> 189934 T367 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@406a4689 lockFactory=NativeFSLockFactory@./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data/index),segFN=segments_1,generation=1} [junit4] 2> 189934 T367 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 189936 T367 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 189936 T367 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 189937 T367 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 189937 T367 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 189937 T367 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 189938 T367 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 189939 T367 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 189939 T367 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 189939 T367 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 189939 T367 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 189940 T367 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 189940 T367 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 189940 T367 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler [junit4] 2> 189950 T367 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 189951 T367 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 189953 T367 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 189954 T367 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 189956 T367 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 189967 T367 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 189967 T367 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 189969 T367 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@406a4689 lockFactory=NativeFSLockFactory@./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/control/data/index),segFN=segments_1,generation=1} [junit4] 2> 189970 T367 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 189970 T367 oass.SolrIndexSearcher.<init> Opening Searcher@277c2a11[collection1] main [junit4] 2> 189970 T367 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 189973 T367 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 189973 T367 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 189991 T367 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 189991 T367 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 189991 T367 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 189994 T367 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 189995 T367 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 189996 T367 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 190014 T367 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 190015 T367 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 190016 T367 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 190019 T367 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 190019 T369 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@277c2a11[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 190021 T372 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:58179 collection:control_collection shard:shard1 [junit4] 2> 190026 T326 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0 [junit4] 2> 190027 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 190039 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 190048 T374 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5c2c5669 name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 190048 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 190061 T326 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 190078 T372 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 190083 T326 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 190135 T372 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 190141 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 190143 T372 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 190144 T372 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C137 name=collection1 org.apache.solr.core.SolrCore@537f5f33 url=https://127.0.0.1:58179/collection1 node=127.0.0.1:58179_ C137_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:58179, node_name=127.0.0.1:58179_, state=down} [junit4] 2> 190151 T372 C137 P58179 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:58179/collection1/ [junit4] 2> 190152 T372 C137 P58179 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 190152 T372 C137 P58179 oasc.SyncStrategy.syncToMe https://127.0.0.1:58179/collection1/ has no replicas [junit4] 2> 190152 T372 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:58179/collection1/ shard1 [junit4] 2> 190168 T372 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 190220 T368 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> 190237 T375 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> 190273 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 190422 T375 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> 190423 T368 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> 190460 T372 oasc.ZkController.register We are https://127.0.0.1:58179/collection1/ and leader is https://127.0.0.1:58179/collection1/ [junit4] 2> 190461 T372 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:58179 [junit4] 2> 190461 T372 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 190461 T372 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 190461 T372 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 190475 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 190509 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58179";, [junit4] 2> "node_name":"127.0.0.1:58179_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 190641 T375 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> 190642 T368 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> 190672 T326 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 190673 T326 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 190684 T326 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 190710 T326 oejs.AbstractConnector.doStart Started [email protected]:58183 [junit4] 2> 190713 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 190714 T326 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 190714 T326 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-004 [junit4] 2> 190714 T326 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-004/' [junit4] 2> 190811 T326 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-004/solr.xml [junit4] 2> 190842 T326 oasc.CoreContainer.<init> New CoreContainer 1139280696 [junit4] 2> 190843 T326 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-004/] [junit4] 2> 190845 T326 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 190845 T326 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 190847 T326 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 190849 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 190849 T326 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 190849 T326 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 190849 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 190850 T326 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 190850 T326 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 190854 T326 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 190864 T326 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 190864 T326 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 190865 T326 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 190865 T326 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58175/solr [junit4] 2> 190865 T326 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 190867 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 190874 T386 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@149b82fb name:ZooKeeperConnection Watcher:127.0.0.1:58175 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 190874 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 190883 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 190886 T388 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@65878eaf name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 190890 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 190953 T326 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 191990 T326 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58183_ [junit4] 2> 191991 T326 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58183_ [junit4] 2> 192007 T326 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 192021 T390 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 192021 T390 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 192023 T390 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 192024 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 192030 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58183";, [junit4] 2> "node_name":"127.0.0.1:58183_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 192031 T364 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 192031 T364 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 192148 T375 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> 192148 T368 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> 192149 T389 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> 193025 T390 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 193026 T390 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 193027 T390 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 193028 T390 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 193030 T390 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 193031 T390 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-004/collection1/' [junit4] 2> 193060 T390 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 193073 T390 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 193077 T390 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 193084 T390 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 193265 T390 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 193268 T390 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 193268 T390 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 193270 T390 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 193270 T390 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 193270 T390 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 193271 T390 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-004/collection1/, dataDir=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1/ [junit4] 2> 193271 T390 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 193271 T390 oasc.CachingDirectoryFactory.get return new directory for ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1 [junit4] 2> 193272 T390 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1/index/ [junit4] 2> 193272 T390 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 193272 T390 oasc.CachingDirectoryFactory.get return new directory for ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1/index [junit4] 2> 193280 T390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1cbdce63 lockFactory=NativeFSLockFactory@./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1/index),segFN=segments_1,generation=1} [junit4] 2> 193281 T390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 193283 T390 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 193287 T390 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 193291 T390 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 193293 T390 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 193293 T390 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 193295 T390 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 193295 T390 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 193295 T390 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 193296 T390 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 193296 T390 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 193296 T390 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 193296 T390 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 193297 T390 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler [junit4] 2> 193307 T390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 193310 T390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 193311 T390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 193312 T390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 193313 T390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 193315 T390 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 193316 T390 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 193317 T390 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1cbdce63 lockFactory=NativeFSLockFactory@./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty1/index),segFN=segments_1,generation=1} [junit4] 2> 193317 T390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 193317 T390 oass.SolrIndexSearcher.<init> Opening Searcher@71607e31[collection1] main [junit4] 2> 193317 T390 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 193322 T390 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 193322 T390 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 193323 T390 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 193324 T390 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 193324 T390 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 193328 T390 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 193330 T390 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 193330 T390 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 193330 T390 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 193332 T391 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71607e31[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 193332 T390 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 193334 T394 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:58183 collection:collection1 shard:shard2 [junit4] 2> 193335 T326 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0 [junit4] 2> 193335 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 193345 T394 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 193380 T394 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 193389 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 193392 T394 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 193392 T394 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C138 name=collection1 org.apache.solr.core.SolrCore@399661c9 url=https://127.0.0.1:58183/collection1 node=127.0.0.1:58183_ C138_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:58183, node_name=127.0.0.1:58183_, state=down} [junit4] 2> 193392 T394 C138 P58183 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:58183/collection1/ [junit4] 2> 193395 T394 C138 P58183 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 193395 T394 C138 P58183 oasc.SyncStrategy.syncToMe https://127.0.0.1:58183/collection1/ has no replicas [junit4] 2> 193395 T394 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:58183/collection1/ shard2 [junit4] 2> 193395 T394 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 193434 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 193551 T368 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> 193552 T389 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> 193552 T375 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> 193596 T394 oasc.ZkController.register We are https://127.0.0.1:58183/collection1/ and leader is https://127.0.0.1:58183/collection1/ [junit4] 2> 193596 T394 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:58183 [junit4] 2> 193597 T394 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 193597 T394 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 193597 T394 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 193606 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 193617 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58183";, [junit4] 2> "node_name":"127.0.0.1:58183_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 193736 T375 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> 193736 T389 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> 193738 T368 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> 193751 T326 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 193751 T326 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 193761 T326 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 193764 T326 oejs.AbstractConnector.doStart Started [email protected]:58186 [junit4] 2> 193770 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 193770 T326 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 193770 T326 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-005 [junit4] 2> 193770 T326 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-005/' [junit4] 2> 193806 T326 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-005/solr.xml [junit4] 2> 193839 T326 oasc.CoreContainer.<init> New CoreContainer 1283439710 [junit4] 2> 193840 T326 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-005/] [junit4] 2> 193840 T326 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 193841 T326 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 193844 T326 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 193846 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 193847 T326 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 193847 T326 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 193847 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 193848 T326 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 193849 T326 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 193851 T326 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 193853 T326 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 193853 T326 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 193853 T326 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 193854 T326 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58175/solr [junit4] 2> 193854 T326 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 193857 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 193861 T405 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17288361 name:ZooKeeperConnection Watcher:127.0.0.1:58175 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 193862 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 193871 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 193875 T407 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7c65a209 name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 193875 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 193923 T326 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 194935 T326 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58186_ [junit4] 2> 194937 T326 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58186_ [junit4] 2> 194948 T326 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 194958 T409 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 194959 T409 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 194961 T409 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 194963 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 194967 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58186";, [junit4] 2> "node_name":"127.0.0.1:58186_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 194968 T364 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 194968 T364 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 195085 T389 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> 195085 T408 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> 195085 T368 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> 195085 T375 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> 195962 T409 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 195963 T409 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 195965 T409 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 195966 T409 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 195969 T409 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 195969 T409 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-005/collection1/' [junit4] 2> 195996 T409 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 196012 T409 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 196019 T409 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 196026 T409 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 196157 T409 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 196160 T409 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 196161 T409 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 196171 T409 oass.IndexSchema.loadCopyFields WARN Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 196178 T409 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 196178 T409 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 196179 T409 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-005/collection1/, dataDir=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2/ [junit4] 2> 196179 T409 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 196179 T409 oasc.CachingDirectoryFactory.get return new directory for ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2 [junit4] 2> 196180 T409 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2/index/ [junit4] 2> 196180 T409 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 196181 T409 oasc.CachingDirectoryFactory.get return new directory for ./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2/index [junit4] 2> 196183 T409 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3827f0f8 lockFactory=NativeFSLockFactory@./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2/index),segFN=segments_1,generation=1} [junit4] 2> 196184 T409 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 196186 T409 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 196186 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 196187 T409 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 196187 T409 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 196187 T409 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 196187 T409 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 196188 T409 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 196188 T409 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 196188 T409 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 196188 T409 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 196188 T409 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 196189 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 196189 T409 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: solr.PingRequestHandler [junit4] 2> 196200 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 196201 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 196203 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 196204 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 196205 T409 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 196213 T409 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 196214 T409 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 196217 T409 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3827f0f8 lockFactory=NativeFSLockFactory@./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-002/jetty2/index),segFN=segments_1,generation=1} [junit4] 2> 196217 T409 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 196217 T409 oass.SolrIndexSearcher.<init> Opening Searcher@65c05f7d[collection1] main [junit4] 2> 196217 T409 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 196224 T409 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 196225 T409 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 196227 T409 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 196227 T409 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 196227 T409 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 196232 T409 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 196234 T409 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 196235 T409 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 196235 T409 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 196240 T410 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@65c05f7d[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 196241 T409 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 196246 T413 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:58186 collection:collection1 shard:shard1 [junit4] 2> 196246 T326 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0 [junit4] 2> 196246 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 196250 T413 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 196283 T413 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 196295 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 196299 T413 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 196299 T413 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C139 name=collection1 org.apache.solr.core.SolrCore@ca839dc url=https://127.0.0.1:58186/collection1 node=127.0.0.1:58186_ C139_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:58186, node_name=127.0.0.1:58186_, state=down} [junit4] 2> 196300 T413 C139 P58186 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:58186/collection1/ [junit4] 2> 196301 T413 C139 P58186 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 196301 T413 C139 P58186 oasc.SyncStrategy.syncToMe https://127.0.0.1:58186/collection1/ has no replicas [junit4] 2> 196301 T413 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:58186/collection1/ shard1 [junit4] 2> 196301 T413 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 196337 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 196459 T389 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> 196459 T368 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> 196460 T375 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> 196460 T408 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> 196495 T413 oasc.ZkController.register We are https://127.0.0.1:58186/collection1/ and leader is https://127.0.0.1:58186/collection1/ [junit4] 2> 196495 T413 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:58186 [junit4] 2> 196495 T413 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 196496 T413 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 196496 T413 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 196502 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 196510 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58186";, [junit4] 2> "node_name":"127.0.0.1:58186_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 196589 T326 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 196589 T326 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 196597 T326 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 196600 T326 oejs.AbstractConnector.doStart Started [email protected]:58189 [junit4] 2> 196603 T326 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 196603 T326 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 196604 T326 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-006 [junit4] 2> 196604 T326 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-006/' [junit4] 2> 196627 T389 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> 196627 T375 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> 196627 T368 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> 196628 T408 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> 196675 T326 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-006/solr.xml [junit4] 2> 196701 T326 oasc.CoreContainer.<init> New CoreContainer 49397092 [junit4] 2> 196702 T326 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-006/] [junit4] 2> 196702 T326 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 196702 T326 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 196703 T326 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 196703 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 196703 T326 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 196704 T326 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 196704 T326 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 196704 T326 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 196704 T326 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 196707 T326 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 196710 T326 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 196711 T326 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 196711 T326 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 196711 T326 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58175/solr [junit4] 2> 196711 T326 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 196718 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 196719 T424 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f1958f4 name:ZooKeeperConnection Watcher:127.0.0.1:58175 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 196722 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 196742 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 196742 T426 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@712b92e6 name:ZooKeeperConnection Watcher:127.0.0.1:58175/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 196744 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 196787 T326 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 197799 T326 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58189_ [junit4] 2> 197801 T326 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58189_ [junit4] 2> 197820 T326 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 197834 T428 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 197834 T428 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 197837 T368 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 197837 T428 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 197847 T364 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:58189";, [junit4] 2> "node_name":"127.0.0.1:58189_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 197848 T364 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 197848 T364 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 197972 T375 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> 197973 T368 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> 197973 T427 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> 197975 T408 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> 197973 T389 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> 198841 T428 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 198842 T428 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 198843 T428 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 198844 T428 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 198847 T428 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 198847 T428 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001/tempDir-006/collection1/' [junit4] 2> 198899 T428 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.0 [junit4] 2> 198905 T428 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 198910 T428 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 198919 T428 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 199109 T428 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 199110 T428 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 199112 T428 oass.IndexSchema.loadCopyFields WARN Field lowerfilt1and2 is not multivalued and d [...truncated too long message...] .http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448) [junit4] > ... 49 more [junit4] 2> 427640 T326 oas.SolrTestCaseJ4.setUp ###Starting testShutdown [junit4] 2> 427642 T326 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 427644 T465 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 427744 T326 oasc.ZkTestServer.run start zk server on port:58210 [junit4] 2> 427747 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 427752 T471 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3bfe2ce7 name:ZooKeeperConnection Watcher:127.0.0.1:58210 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 427752 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 427753 T326 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 427765 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 427769 T473 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74d52e4c name:ZooKeeperConnection Watcher:127.0.0.1:58210/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 427769 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 427770 T326 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 427779 T326 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 427787 T326 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 427793 T326 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 427802 T326 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml [junit4] 2> 427803 T326 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 427811 T326 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 427812 T326 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 427867 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist [junit4] 2> 427868 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist [junit4] 2> 427869 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist [junit4] 2> 427869 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist [junit4] 2> 427870 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist [junit4] 2> 427870 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist [junit4] 2> 427871 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist [junit4] 2> 427871 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist [junit4] 2> 427871 T326 oasc.AbstractZkTestCase.putConfig skipping /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist [junit4] 2> 427883 T475 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e93983d name:ZooKeeperConnection Watcher:127.0.0.1:58210/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 427883 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 427884 T326 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 427913 T326 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 427915 T476 oaz.ClientCnxnSocketNIO.connect ERROR Unable to open socket to ff01::114/ff01:0:0:0:0:0:0:114:33332 [junit4] 2> 427915 T476 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:457) [junit4] 2> at sun.nio.ch.Net.connect(Net.java:449) [junit4] 2> at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:647) [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> 429019 T476 oaz.ClientCnxnSocketNIO.connect ERROR Unable to open socket to ff01::114/ff01:0:0:0:0:0:0:114:33332 [junit4] 2> 429122 T326 oas.SolrTestCaseJ4.tearDown ###Ending testShutdown [junit4] 2> 429124 T326 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:58210 58210 [junit4] 2> 429147 T465 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:58210 58210 [junit4] 2> 429366 T326 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solr.client.solrj.impl.CloudSolrServerTest-2F988AA3C8F590C1-001 [junit4] 2> 244906 T325 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene410: {_version_=Pulsing41(freqCutoff=16 minBlockSize=30 maxBlockSize=59), multiDefault=Pulsing41(freqCutoff=16 minBlockSize=30 maxBlockSize=59), a_t=PostingsFormat(name=SimpleText), intDefault=Pulsing41(freqCutoff=16 minBlockSize=30 maxBlockSize=59), range_facet_sl=PostingsFormat(name=TestBloomFilteredLucene41Postings), range_facet_si=PostingsFormat(name=SimpleText), id=PostingsFormat(name=TestBloomFilteredLucene41Postings), text=PostingsFormat(name=FSTPulsing41), range_facet_l=Pulsing41(freqCutoff=16 minBlockSize=30 maxBlockSize=59), timestamp=Pulsing41(freqCutoff=16 minBlockSize=30 maxBlockSize=59)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=sr_RS, timezone=America/El_Salvador [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_20 (64-bit)/cpus=2,threads=1,free=8382744,total=70893568 [junit4] 2> NOTE: All tests run in this JVM: [SolrExampleStreamingBinaryTest, TestUpdateRequestCodec, TestBatchUpdate, AnlysisResponseBaseTest, TestFastInputStream, SolrExampleEmbeddedTest, SolrExampleBinaryTest, TestSpellCheckResponse, TestEmbeddedSolrServer, SolrExampleJettyTest, FieldAnalysisResponseTest, MultiCoreEmbeddedTest, SolrExampleStreamingTest, TestDocumentObjectBinder, NamedListTest, SolrParamTest, TermsResponseTest, MultiCoreExampleJettyTest, LargeVolumeEmbeddedTest, TestCoreAdmin, LargeVolumeBinaryJettyTest, SolrExceptionTest, FacetFieldTest, SolrExampleXMLTest, LargeVolumeJettyTest, CloudSolrServerTest] [junit4] Completed in 245.48s, 3 tests, 1 error <<< FAILURES! [...truncated 84 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:474: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:454: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:45: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:202: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1351: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:966: There were test failures: 52 suites, 302 tests, 1 error Total time: 162 minutes 39 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
