Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/639/
2 tests failed. REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDistribSearch Error Message: Error executing query Stack Trace: org.apache.solr.client.solrj.SolrServerException: Error executing query at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:100) at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301) at org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:223) at org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:165) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrServer(FullSolrCloudDistribCmdsTest.java:414) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.doTest(FullSolrCloudDistribCmdsTest.java:144) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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.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.http.ParseException: Invalid content type: at org.apache.http.entity.ContentType.parse(ContentType.java:233) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:502) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:212) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:208) at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91) ... 46 more FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch Error Message: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:212) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:208) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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.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) Build Log: [...truncated 11830 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/init-core-data-001 [junit4] 2> 346782 T1037 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 346782 T1037 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 346795 T1037 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 346797 T1037 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 346798 T1038 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 346898 T1037 oasc.ZkTestServer.run start zk server on port:24069 [junit4] 2> 346899 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 346901 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 346906 T1044 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@43e18e5a name:ZooKeeperConnection Watcher:127.0.0.1:24069 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 346907 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 346907 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 346908 T1037 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 346912 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 346914 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 346915 T1046 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@792ab607 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 346916 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 346916 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 346917 T1037 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 346919 T1037 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 346921 T1037 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 346923 T1037 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 346926 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 346927 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 346931 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 346932 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 346935 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 346936 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 346939 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 346940 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 346942 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 346943 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 346946 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 346947 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 346950 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 346950 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 346953 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 346954 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 346957 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 346958 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 346961 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 346962 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 346964 T1037 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 346966 T1037 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 346968 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 346969 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 346971 T1048 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@394b5c1e name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 346972 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 346972 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> Using new style solr.xml [junit4] 2> 349012 T1037 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 349020 T1037 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 349029 T1037 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:26594 [junit4] 2> 349035 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 349036 T1037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 349036 T1037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002 [junit4] 2> 349036 T1037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/' [junit4] 2> 349114 T1037 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solr.xml [junit4] 2> 349208 T1037 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002 [junit4] 2> 349209 T1037 oasc.CoreContainer.<init> New CoreContainer 1996485309 [junit4] 2> 349209 T1037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/] [junit4] 2> 349211 T1037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 349211 T1037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 349212 T1037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 349212 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 349213 T1037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 349213 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 349214 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 349214 T1037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 349215 T1037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 349220 T1037 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 349225 T1037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 349226 T1037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 349226 T1037 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 349227 T1037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:24069/solr [junit4] 2> 349228 T1037 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 349228 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 349229 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 349231 T1059 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5aca4765 name:ZooKeeperConnection Watcher:127.0.0.1:24069 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 349232 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 349233 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 349235 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 349237 T1061 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56979ff0 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 349237 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 349239 T1037 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 349242 T1037 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 349245 T1037 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 349248 T1037 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 349251 T1037 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 349255 T1037 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 349257 T1037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:26594_ [junit4] 2> 349258 T1037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:26594_ [junit4] 2> 349261 T1037 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 349263 T1037 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 349265 T1037 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 349268 T1037 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:26594_ [junit4] 2> 349268 T1037 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 349270 T1037 oasc.Overseer.start Overseer (id=92518105655541764-127.0.0.1:26594_-n_0000000000) starting [junit4] 2> 349274 T1037 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 349283 T1037 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 349285 T1063 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 349286 T1037 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 349290 T1037 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 349291 T1037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 349295 T1062 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 349299 T1037 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002 [junit4] 2> 350912 T1037 oasc.CorePropertiesLocator.discoverUnder Found core conf in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/conf/ [junit4] 2> 350917 T1037 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 350918 T1037 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3 [junit4] 2> 350919 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 350928 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 350929 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 350931 T1067 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d45b726 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 350931 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 350932 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 350933 T1037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 350936 T1037 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 350937 T1037 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 350938 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 350942 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 350946 T1069 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@60e6cfb3 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 350946 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 350946 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 350948 T1070 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 350951 T1062 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 350951 T1062 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 350952 T1062 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 350954 T1062 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 350956 T1070 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> 350969 T1071 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> 352979 T1037 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 352980 T1037 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 352991 T1037 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 352996 T1037 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:31688 [junit4] 2> 353001 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 353002 T1037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 353003 T1037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003 [junit4] 2> 353003 T1037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/' [junit4] 2> 353070 T1037 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solr.xml [junit4] 2> 353164 T1037 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003 [junit4] 2> 353165 T1037 oasc.CoreContainer.<init> New CoreContainer 256180486 [junit4] 2> 353165 T1037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/] [junit4] 2> 353167 T1037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 353168 T1037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 353168 T1037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 353169 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 353169 T1037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 353170 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 353170 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 353171 T1037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 353172 T1037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 353176 T1037 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 353180 T1037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 353180 T1037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 353181 T1037 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 353181 T1037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:24069/solr [junit4] 2> 353182 T1037 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 353182 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 353183 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 353185 T1082 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@670e65e5 name:ZooKeeperConnection Watcher:127.0.0.1:24069 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 353186 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 353187 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 353189 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 353191 T1084 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9da2dc8 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 353191 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 353198 T1037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 354204 T1037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:31688_ [junit4] 2> 354205 T1037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:31688_ [junit4] 2> 354211 T1037 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 354218 T1037 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003 [junit4] 2> 355721 T1037 oasc.CorePropertiesLocator.discoverUnder Found core conf in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/conf/ [junit4] 2> 355722 T1037 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 355723 T1037 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3 [junit4] 2> 355724 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 357776 T1037 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 357781 T1037 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 357796 T1037 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 357801 T1037 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:37852 [junit4] 2> 357807 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 357808 T1037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 357809 T1037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004 [junit4] 2> 357809 T1037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/' [junit4] 2> 357877 T1037 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solr.xml [junit4] 2> 357969 T1037 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004 [junit4] 2> 357970 T1037 oasc.CoreContainer.<init> New CoreContainer 1877643730 [junit4] 2> 357970 T1037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/] [junit4] 2> 357972 T1037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 357973 T1037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 357973 T1037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 357974 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 357974 T1037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 357975 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 357975 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 357976 T1037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 357976 T1037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 357981 T1037 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 357985 T1037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 357986 T1037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 357986 T1037 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 357987 T1037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:24069/solr [junit4] 2> 357987 T1037 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 357987 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 357989 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 357990 T1097 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@702d405a name:ZooKeeperConnection Watcher:127.0.0.1:24069 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 357992 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 357993 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 357995 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 357996 T1099 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4849e41e name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 357997 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 358004 T1037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 359016 T1037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37852_ [junit4] 2> 359017 T1037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37852_ [junit4] 2> 359022 T1037 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 359032 T1037 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004 [junit4] 2> 360345 T1037 oasc.CorePropertiesLocator.discoverUnder Found core conf in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/conf/ [junit4] 2> 360590 T1037 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 360591 T1037 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3 [junit4] 2> 360592 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 362694 T1037 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 362698 T1037 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 362706 T1037 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 362709 T1037 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:44211 [junit4] 2> 362715 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 362716 T1037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 362716 T1037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005 [junit4] 2> 362717 T1037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/' [junit4] 2> 362791 T1037 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/solr.xml [junit4] 2> 362880 T1037 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005 [junit4] 2> 362881 T1037 oasc.CoreContainer.<init> New CoreContainer 78079296 [junit4] 2> 362882 T1037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/] [junit4] 2> 362883 T1037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 362883 T1037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 362884 T1037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 362884 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 362884 T1037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 362885 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 362885 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 362886 T1037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 362886 T1037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 362891 T1037 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 362895 T1037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 362896 T1037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 362897 T1037 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 362897 T1037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:24069/solr [junit4] 2> 362898 T1037 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 362898 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 362899 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 362901 T1112 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a1ce70a name:ZooKeeperConnection Watcher:127.0.0.1:24069 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 362902 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 362903 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 362905 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 362907 T1114 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@68e52e6 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 362907 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 362914 T1037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 363920 T1037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44211_ [junit4] 2> 363921 T1037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44211_ [junit4] 2> 363926 T1037 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 363932 T1037 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005 [junit4] 2> 365218 T1037 oasc.CorePropertiesLocator.discoverUnder Found core conf in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/conf/ [junit4] 2> 365397 T1037 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 365399 T1037 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3 [junit4] 2> 365399 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 367565 T1037 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4 [junit4] 2> 367566 T1037 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 367578 T1037 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 367583 T1037 oejs.AbstractConnector.doStart Started SslSocketConnector@127.0.0.1:50737 [junit4] 2> 367589 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 367589 T1037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 367590 T1037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006 [junit4] 2> 367590 T1037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006/' [junit4] 2> 367657 T1037 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006/solr.xml [junit4] 2> 367751 T1037 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006 [junit4] 2> 367752 T1037 oasc.CoreContainer.<init> New CoreContainer 322846844 [junit4] 2> 367752 T1037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006/] [junit4] 2> 367754 T1037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 367755 T1037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 367755 T1037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 367756 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 367756 T1037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 367757 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 367757 T1037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 367758 T1037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 367758 T1037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 367764 T1037 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 367769 T1037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 367769 T1037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 367770 T1037 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 367770 T1037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:24069/solr [junit4] 2> 367771 T1037 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 367771 T1037 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 367772 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 367774 T1127 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17b1eea5 name:ZooKeeperConnection Watcher:127.0.0.1:24069 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 367776 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 367777 T1037 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 367779 T1037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 367781 T1129 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63195110 name:ZooKeeperConnection Watcher:127.0.0.1:24069/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 367781 T1037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 367788 T1037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 368794 T1037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50737_ [junit4] 2> 368795 T1037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50737_ [junit4] 2> 368800 T1037 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 368807 T1037 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006 [junit4] 2> 370108 T1037 oasc.CorePropertiesLocator.discoverUnder Found core conf in /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-006/conf/ [junit4] 2> 370206 T1037 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 370207 T1037 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3 [junit4] 2> 370208 T1037 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 370387 T1077 oasha.CollectionsHandler.handleCreateAction Creating Collection : version=2&wt=javabin&replicationFactor=2&router.field=myOwnField&numShards=2&name=solrj_collection&action=CREATE&collection.configName=conf1 [junit4] 2> 370389 T1070 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged [junit4] 2> 370390 T1063 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "operation":"create", [junit4] 2> "fromApi":"true", [junit4] 2> "name":"solrj_collection", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "router.field":"myOwnField"} [junit4] 2> 370392 T1133 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : create , { [junit4] 2> "operation":"create", [junit4] 2> "fromApi":"true", [junit4] 2> "name":"solrj_collection", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "router.field":"myOwnField"} [junit4] 2> 370394 T1133 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection [junit4] 2> 370395 T1133 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection [junit4] 2> 370398 T1070 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 370402 T1062 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection [junit4] 2> 370402 T1062 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2] [junit4] 2> 370402 T1062 oasc.Overseer$ClusterStateUpdater.createCollection state version solrj_collection 1 [junit4] 2> 370405 T1071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370405 T1100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370405 T1130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370405 T1115 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370405 T1070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370405 T1085 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370499 T1133 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection solrj_collection, shardNames [shard1, shard2] , replicationFactor : 2 [junit4] 2> 370500 T1133 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:26594_ [junit4] 2> 370501 T1133 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:37852_ [junit4] 2> 370504 T1133 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:31688_ [junit4] 2> 370506 T1133 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:44211_ [junit4] 2> 370663 T1105 oasha.CoreAdminHandler.handleCreateAction core create command version=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&shard=shard2&numShards=2&name=solrj_collection_shard2_replica2&action=CREATE&collection.configName=conf1 [junit4] 2> 370666 T1091 oasha.CoreAdminHandler.handleCreateAction core create command version=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&shard=shard1&numShards=2&name=solrj_collection_shard1_replica2&action=CREATE&collection.configName=conf1 [junit4] 2> 370666 T1075 oasha.CoreAdminHandler.handleCreateAction core create command version=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&shard=shard2&numShards=2&name=solrj_collection_shard2_replica1&action=CREATE&collection.configName=conf1 [junit4] 2> 370668 T1052 oasha.CoreAdminHandler.handleCreateAction core create command version=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&shard=shard1&numShards=2&name=solrj_collection_shard1_replica1&action=CREATE&collection.configName=conf1 [junit4] 2> 370670 T1105 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down collection=solrj_collection [junit4] 2> 370670 T1091 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down collection=solrj_collection [junit4] 2> 370671 T1075 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down collection=solrj_collection [junit4] 2> 370671 T1105 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 370672 T1091 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 370671 T1070 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 370672 T1052 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down collection=solrj_collection [junit4] 2> 370672 T1075 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 370674 T1052 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 370676 T1062 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:44211_", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "shard":"shard2", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:44211", [junit4] 2> "core":"solrj_collection_shard2_replica2"} [junit4] 2> 370679 T1062 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:37852_", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:37852", [junit4] 2> "core":"solrj_collection_shard1_replica2"} [junit4] 2> 370682 T1070 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 370683 T1062 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:31688_", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "shard":"shard2", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:31688", [junit4] 2> "core":"solrj_collection_shard2_replica1"} [junit4] 2> 370686 T1062 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:26594_", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:26594", [junit4] 2> "core":"solrj_collection_shard1_replica1"} [junit4] 2> 370688 T1070 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 370689 T1100 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370690 T1071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370690 T1085 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370689 T1070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370689 T1130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 370689 T1115 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 371672 T1105 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2 [junit4] 2> 371673 T1105 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 371673 T1091 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2 [junit4] 2> 371674 T1075 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1 [junit4] 2> 371675 T1091 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 371675 T1052 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1 [junit4] 2> 371675 T1075 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 371677 T1091 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 371677 T1075 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 371678 T1075 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 371676 T1052 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 371676 T1105 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 371680 T1052 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 371677 T1091 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 371681 T1052 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 371680 T1105 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 371680 T1075 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 371683 T1091 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 371683 T1105 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 371684 T1052 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 371683 T1091 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/' [junit4] 2> 371683 T1075 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/' [junit4] 2> 371685 T1052 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/' [junit4] 2> 371684 T1105 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/solrj_collection_shard2_replica2/' [junit4] 2> 372061 T1052 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 372062 T1091 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 372063 T1075 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 372070 T1105 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 372169 T1052 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 372202 T1075 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 372203 T1052 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 372205 T1075 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 372212 T1105 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 372213 T1091 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 372216 T1105 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 372221 T1052 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test [junit4] 2> 372230 T1105 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test [junit4] 2> 372232 T1075 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test [junit4] 2> 372247 T1091 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 372270 T1091 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test [junit4] 2> 373068 T1052 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 373094 T1052 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 373099 T1075 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 373099 T1052 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 373113 T1075 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 373120 T1075 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 373132 T1091 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 373135 T1105 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 373138 T1052 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373145 T1091 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 373148 T1052 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373148 T1105 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 373150 T1091 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 373154 T1105 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 373155 T1075 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373157 T1052 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373160 T1052 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373163 T1052 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373164 T1075 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373164 T1052 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373167 T1052 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373168 T1052 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373169 T1052 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using configuration from collection solrj_collection [junit4] 2> 373169 T1052 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 373170 T1052 oasc.SolrCore.<init> [solrj_collection_shard1_replica1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/data/ [junit4] 2> 373170 T1052 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17cd4b11 [junit4] 2> 373172 T1052 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/data [junit4] 2> 373173 T1052 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/data/index/ [junit4] 2> 373174 T1052 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/data/index' doesn't exist. Creating new index... [junit4] 2> 373173 T1075 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373175 T1052 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-002/solrj_collection_shard1_replica1/data/index [junit4] 2> 373176 T1052 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6c17f63e [junit4] 2> 373177 T1075 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373178 T1052 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory(id=lucene-5f01aa21))),segFN=segments_1,generation=1} [junit4] 2> 373178 T1075 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373178 T1052 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 373179 T1075 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373181 T1075 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373181 T1075 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373182 T1075 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using configuration from collection solrj_collection [junit4] 2> 373182 T1075 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 373183 T1091 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373183 T1105 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373183 T1075 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/data/ [junit4] 2> 373184 T1075 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17cd4b11 [junit4] 2> 373186 T1075 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/data [junit4] 2> 373186 T1052 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 373187 T1075 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/data/index/ [junit4] 2> 373187 T1052 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 373188 T1075 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/data/index' doesn't exist. Creating new index... [junit4] 2> 373188 T1052 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 373189 T1052 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 373190 T1075 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-003/solrj_collection_shard2_replica1/data/index [junit4] 2> 373190 T1052 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 373192 T1091 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373190 T1075 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@59ced1e9 [junit4] 2> 373192 T1052 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 373194 T1105 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 373194 T1052 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 373195 T1075 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory(id=lucene-4719f96b))),segFN=segments_1,generation=1} [junit4] 2> 373195 T1052 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 373195 T1075 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 373196 T1052 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 373197 T1052 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 373198 T1052 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 373199 T1052 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 373199 T1091 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373200 T1105 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373200 T1052 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 373202 T1052 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 373202 T1052 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 373203 T1075 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 373203 T1052 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 373203 T1075 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 373203 T1091 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373204 T1105 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373204 T1075 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 373204 T1052 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 373206 T1075 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 373207 T1052 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 373205 T1105 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373208 T1052 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 373205 T1091 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373209 T1052 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 373208 T1105 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373207 T1075 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 373210 T1091 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 373211 T1075 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 373213 T1075 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 373213 T1075 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 373214 T1075 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 373214 T1091 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373215 T1105 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 373215 T1091 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373215 T1075 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 373216 T1091 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using configuration from collection solrj_collection [junit4] 2> 373216 T1105 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 373217 T1091 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 373217 T1075 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 373219 T1091 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/data/ [junit4] 2> 373218 T1105 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica2' using configuration from collection solrj_collection [junit4] 2> 373220 T1091 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17cd4b11 [junit4] 2> 373219 T1075 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 373221 T1105 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 373222 T1091 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/data [junit4] 2> 373224 T1091 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/data/index/ [junit4] 2> 373224 T1091 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica2] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/data/index' doesn't exist. Creating new index... [junit4] 2> 373222 T1105 oasc.SolrCore.<init> [solrj_collection_shard2_replica2] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/solrj_collection_shard2_replica2/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/solrj_collection_shard2_replica2/data/ [junit4] 2> 373222 T1075 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 373227 T1105 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17cd4b11 [junit4] 2> 373226 T1091 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-004/solrj_collection_shard1_replica2/data/index [junit4] 2> 373228 T1075 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 373230 T1075 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 373231 T1075 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 373231 T1075 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 373232 T1075 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 373233 T1075 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 373233 T1075 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 373235 T1105 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/solrj_collection_shard2_replica2/data [junit4] 2> 373235 T1091 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@a173ddc [junit4] 2> 373236 T1105 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-8C83B33DBA7C8E1C-001/tempDir-005/solrj_collection_shard2_replica2/data/index/ [junit4] 2> 373237 T1105 oasc.SolrCore.initIndex WARN [solrj_collection_ [...truncated too long message...] kRandom), 20parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 2child1_s=PostingsFormat(name=Memory doPackFST= false), 22parent_f2_s=PostingsFormat(name=MockRandom), 2parent_f1_s=OrdsLucene41(blocksize=128), 5grand3_s=PostingsFormat(name=Lucene41WithOrds), rnd_b=PostingsFormat(name=Memory doPackFST= false), _version_=OrdsLucene41(blocksize=128), 43parent_f1_s=PostingsFormat(name=MockRandom), 32parent_f2_s=PostingsFormat(name=Lucene41WithOrds), id=PostingsFormat(name=Memory doPackFST= false), 18parent_f1_s=PostingsFormat(name=MockRandom), 51parent_f2_s=PostingsFormat(name=MockRandom), 6grand3_s=PostingsFormat(name=MockRandom), 8grand6_s=PostingsFormat(name=Lucene41WithOrds), 5parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 9grand0_s=PostingsFormat(name=MockRandom), 6parent_f1_s=OrdsLucene41(blocksize=128), 10parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 47parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 36parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 35parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 28parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 21parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 28parent_f1_s=OrdsLucene41(blocksize=128), 3grand1_s=PostingsFormat(name=Lucene41WithOrds), 8grand0_s=OrdsLucene41(blocksize=128), 25parent_f1_s=PostingsFormat(name=MockRandom), 33parent_f2_s=PostingsFormat(name=MockRandom), 9grand1_s=PostingsFormat(name=Lucene41WithOrds), 50parent_f1_s=PostingsFormat(name=MockRandom), 32parent_f1_s=PostingsFormat(name=MockRandom), 10parent_f1_s=PostingsFormat(name=MockRandom), 29parent_f1_s=PostingsFormat(name=MockRandom), 5parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 8parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 39parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 4grand0_s=OrdsLucene41(blocksize=128), 9parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 3grand2_s=PostingsFormat(name=MockRandom), 26parent_f2_s=PostingsFormat(name=MockRandom), 9parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 5child3_s=OrdsLucene41(blocksize=128), _root_=OrdsLucene41(blocksize=128), 2parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 7grand5_s=PostingsFormat(name=Lucene41WithOrds), 9grand5_s=PostingsFormat(name=Lucene41WithOrds), 26parent_f1_s=OrdsLucene41(blocksize=128), 23parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 37parent_f1_s=OrdsLucene41(blocksize=128), 8grand5_s=PostingsFormat(name=MockRandom), 8parent_f2_s=PostingsFormat(name=MockRandom), 41parent_f2_s=OrdsLucene41(blocksize=128), 7grand0_s=PostingsFormat(name=Memory doPackFST= false), 44parent_f1_s=OrdsLucene41(blocksize=128), 5grand1_s=OrdsLucene41(blocksize=128), 3parent_f2_s=OrdsLucene41(blocksize=128), 21parent_f1_s=PostingsFormat(name=MockRandom), 5grand2_s=PostingsFormat(name=Memory doPackFST= false), 4child0_s=PostingsFormat(name=Lucene41WithOrds), 52parent_f2_s=OrdsLucene41(blocksize=128), 2grand1_s=PostingsFormat(name=Memory doPackFST= false), 51parent_f1_s=OrdsLucene41(blocksize=128), 29parent_f2_s=OrdsLucene41(blocksize=128), 1grand0_s=PostingsFormat(name=MockRandom), 3parent_f1_s=PostingsFormat(name=MockRandom), 17parent_f1_s=OrdsLucene41(blocksize=128), 34parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 47parent_f1_s=PostingsFormat(name=MockRandom), 23parent_f2_s=OrdsLucene41(blocksize=128), 6grand4_s=PostingsFormat(name=Lucene41WithOrds), 4grand3_s=PostingsFormat(name=Memory doPackFST= false), 15parent_f2_s=PostingsFormat(name=MockRandom), 7parent_f2_s=OrdsLucene41(blocksize=128), 41parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 7grand4_s=PostingsFormat(name=Memory doPackFST= false), 48parent_f1_s=OrdsLucene41(blocksize=128), 6grand0_s=PostingsFormat(name=Lucene41WithOrds), 46parent_f2_s=PostingsFormat(name=Memory doPackFST= false), a_t=PostingsFormat(name=Lucene41WithOrds), 4parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 6parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 5grand0_s=PostingsFormat(name=MockRandom), 24parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 18parent_f2_s=OrdsLucene41(blocksize=128), 31parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 34parent_f2_s=OrdsLucene41(blocksize=128), 9grand2_s=PostingsFormat(name=Memory doPackFST= false), 43parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 5child2_s=PostingsFormat(name=Memory doPackFST= false), 7grand6_s=PostingsFormat(name=MockRandom), 24parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 12parent_f2_s=OrdsLucene41(blocksize=128), 7grand2_s=PostingsFormat(name=MockRandom), 27parent_f2_s=OrdsLucene41(blocksize=128), 35parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 6grand5_s=PostingsFormat(name=Memory doPackFST= false), 5child0_s=PostingsFormat(name=MockRandom), 49parent_f2_s=OrdsLucene41(blocksize=128), 38parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 36parent_f1_s=PostingsFormat(name=MockRandom), 5child4_s=PostingsFormat(name=MockRandom), 45parent_f2_s=OrdsLucene41(blocksize=128), 14parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 12parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 30parent_f2_s=OrdsLucene41(blocksize=128), 1child0_s=PostingsFormat(name=MockRandom), 53parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 39parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 7grand3_s=OrdsLucene41(blocksize=128), 42parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 9grand7_s=OrdsLucene41(blocksize=128), 4parent_f2_s=PostingsFormat(name=MockRandom), 2grand0_s=OrdsLucene41(blocksize=128), 3child1_s=PostingsFormat(name=Lucene41WithOrds), 8grand3_s=PostingsFormat(name=Memory doPackFST= false), text_t=OrdsLucene41(blocksize=128), 8grand4_s=OrdsLucene41(blocksize=128), 19parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 4child2_s=OrdsLucene41(blocksize=128), 37parent_f2_s=PostingsFormat(name=MockRandom), 9grand4_s=PostingsFormat(name=MockRandom), 30parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 5grand4_s=PostingsFormat(name=MockRandom), 38parent_f2_s=OrdsLucene41(blocksize=128), 13parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 7parent_f1_s=PostingsFormat(name=MockRandom), 15parent_f1_s=OrdsLucene41(blocksize=128), 50parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 7grand1_s=PostingsFormat(name=Lucene41WithOrds), 25parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 40parent_f1_s=OrdsLucene41(blocksize=128), 16parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 45parent_f1_s=PostingsFormat(name=Memory doPackFST= false), 19parent_f2_s=PostingsFormat(name=MockRandom), 0parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 20parent_f2_s=PostingsFormat(name=Memory doPackFST= false), 31parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 4child1_s=PostingsFormat(name=MockRandom), 40parent_f2_s=PostingsFormat(name=MockRandom), 6grand1_s=PostingsFormat(name=Memory doPackFST= false), 46parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 0parent_f2_s=PostingsFormat(name=MockRandom), 3child0_s=PostingsFormat(name=Memory doPackFST= false), 3grand0_s=PostingsFormat(name=Memory doPackFST= false), type_s=OrdsLucene41(blocksize=128), 6grand2_s=OrdsLucene41(blocksize=128), 8grand1_s=PostingsFormat(name=MockRandom), 9grand3_s=OrdsLucene41(blocksize=128), 14parent_f1_s=PostingsFormat(name=MockRandom)}, docValues:{}, sim=DefaultSimilarity, locale=sv, timezone=Atlantic/Jan_Mayen [junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=171661496,total=483917824 [junit4] 2> NOTE: All tests run in this JVM: [ShowFileRequestHandlerTest, TestFieldCollectionResource, ResponseHeaderTest, TestHighFrequencyDictionaryFactory, TestZkChroot, TestMissingGroups, DistributedDebugComponentTest, SolrIndexConfigTest, ZkControllerTest, TestConfig, OpenCloseCoreStressTest, DirectSolrSpellCheckerTest, TestFieldSortValues, TestStressVersions, ClusterStateTest, TestMaxScoreQueryParser, TestFastOutputStream, VMParamsZkACLAndCredentialsProvidersTest, TestRequestStatusCollectionAPI, ExitableDirectoryReaderTest, AnalyticsMergeStrategyTest, ExternalFileFieldSortTest, PrimitiveFieldTypeTest, TestDefaultSearchFieldResource, CurrencyFieldOpenExchangeTest, RequiredFieldsTest, TestRecovery, TestSolrXmlPersistence, HdfsUnloadDistributedZkTest, FullSolrCloudDistribCmdsTest] [junit4] Completed on J1 in 345.34s, 1 test, 1 error <<< FAILURES! [...truncated 946 lines...] BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:531: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:472: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:189: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:496: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1359: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:961: There were test failures: 432 suites, 1778 tests, 2 errors, 54 ignored (10 assumptions) Total time: 219 minutes 5 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-NightlyTests-trunk #636 Archived 5 artifacts Archive block size is 32768 Received 0 blocks and 529924994 bytes Compression is 0.0% Took 1 min 32 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org