Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java8/5/
2 tests failed. REGRESSION: org.apache.solr.TestDistributedGrouping.testDistribSearch Error Message: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://127.0.0.1:37975, https://[ff01::213]:33332] Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://[ff01::114]:33332, https://[ff01::083]:33332, https://127.0.0.1:37975, https://[ff01::213]:33332] at __randomizedtesting.SeedInfo.seed([103A566D0461F871:91DCD875733E984D]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:569) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211) at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91) at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301) at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:512) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:560) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:542) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:521) at org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:206) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:875) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.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:54) 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) FAILED: org.apache.solr.handler.TestSolrConfigHandlerConcurrent.testDistribSearch Error Message: [[size property not set, expected = 11, actual null, initialSize property not set, expected = 12, actual null, autowarmCount property not set, expected = 13, actual null], [], [], []] Stack Trace: java.lang.AssertionError: [[size property not set, expected = 11, actual null, initialSize property not set, expected = 12, actual null, autowarmCount property not set, expected = 13, actual null], [], [], []] at __randomizedtesting.SeedInfo.seed([103A566D0461F871:91DCD875733E984D]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.TestSolrConfigHandlerConcurrent.doTest(TestSolrConfigHandlerConcurrent.java:112) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.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:54) 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 13391 lines...] [junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerConcurrent [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/init-core-data-001 [junit4] 2> 409504 T2325 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 409505 T2325 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /orln/ [junit4] 2> 409508 T2325 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 409509 T2325 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 409510 T2326 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 409610 T2325 oasc.ZkTestServer.run start zk server on port:47646 [junit4] 2> 409611 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 409612 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 409616 T2333 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d627ba3 name:ZooKeeperConnection Watcher:127.0.0.1:47646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 409617 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 409617 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 409617 T2325 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 409620 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 409621 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 409622 T2336 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2458f3dd name:ZooKeeperConnection Watcher:127.0.0.1:47646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 409622 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 409623 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 409623 T2325 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 409625 T2325 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 409626 T2325 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 409627 T2325 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 409629 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 409630 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 409632 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 409632 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 409734 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 409735 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 409736 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 409737 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 409738 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 409739 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 409740 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 409741 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 409742 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 409743 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 409745 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 409745 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 409747 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 409747 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 409749 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 409750 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 409751 T2325 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 409751 T2325 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 410973 T2325 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 410994 T2325 oejs.AbstractConnector.doStart Started [email protected]:37737 [junit4] 2> 410994 T2325 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 410994 T2325 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 410995 T2325 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002 [junit4] 2> 410995 T2325 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/' [junit4] 2> 411025 T2325 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/solr.xml [junit4] 2> 411038 T2325 oasc.CoreContainer.<init> New CoreContainer 1421426377 [junit4] 2> 411039 T2325 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/] [junit4] 2> 411039 T2325 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 411040 T2325 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 411040 T2325 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 411040 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 411040 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 411040 T2325 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 411041 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 411041 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 411041 T2325 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 411041 T2325 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 411042 T2325 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 411042 T2325 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 411042 T2325 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 411043 T2325 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 411043 T2325 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:47646/solr [junit4] 2> 411043 T2325 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 411044 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 411044 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 411046 T2348 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5640bd9 name:ZooKeeperConnection Watcher:127.0.0.1:47646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 411047 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 411047 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 411049 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 411050 T2351 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@286c5bed name:ZooKeeperConnection Watcher:127.0.0.1:47646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 411051 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 411052 T2325 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 411053 T2325 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 411055 T2325 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 411056 T2325 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 411058 T2325 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 411060 T2325 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 411060 T2325 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37737_orln [junit4] 2> 411061 T2325 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37737_orln [junit4] 2> 411062 T2325 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 411063 T2325 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 411064 T2325 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 411065 T2325 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:37737_orln [junit4] 2> 411066 T2325 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 411067 T2325 oasc.Overseer.start Overseer (id=92837767038042115-127.0.0.1:37737_orln-n_0000000000) starting [junit4] 2> 411068 T2325 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 411072 T2325 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 411073 T2353 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 411073 T2325 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 411075 T2325 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 411075 T2325 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 411077 T2352 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 411079 T2355 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 411080 T2355 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 411080 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 411080 T2355 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 411082 T2352 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:37737/orln";, [junit4] 2> "node_name":"127.0.0.1:37737_orln", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 411082 T2352 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 411082 T2352 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1 [junit4] 2> 411082 T2352 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 411084 T2351 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> 412081 T2355 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 412082 T2355 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 412082 T2355 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 412083 T2355 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 412083 T2355 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 412083 T2355 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/collection1/' [junit4] 2> 412085 T2355 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 412086 T2355 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 412087 T2355 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 412119 T2355 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 412120 T2355 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 412134 T2355 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 412145 T2355 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 412246 T2355 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 412252 T2355 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 412366 T2355 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 412376 T2355 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 412378 T2355 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 412385 T2355 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 412388 T2355 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 412390 T2355 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 412391 T2355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 412392 T2355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 412392 T2355 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 412392 T2355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 412393 T2355 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 412393 T2355 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 412393 T2355 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 412393 T2355 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-002/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/control/data/ [junit4] 2> 412394 T2355 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@330fda4c [junit4] 2> 412394 T2355 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/control/data [junit4] 2> 412395 T2355 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/control/data/index/ [junit4] 2> 412395 T2355 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 412395 T2355 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/control/data/index [junit4] 2> 412396 T2355 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=64.537109375, floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=3.111209946074762, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 412397 T2355 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@17bfecc0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d315b92),segFN=segments_1,generation=1} [junit4] 2> 412397 T2355 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 412400 T2355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 412400 T2355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 412401 T2355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 412401 T2355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 412401 T2355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 412401 T2355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 412402 T2355 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 412402 T2355 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 412402 T2355 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 412403 T2355 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 412404 T2355 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 412404 T2355 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 412404 T2355 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 412405 T2355 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 412405 T2355 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 412405 T2355 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 412406 T2355 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 412406 T2355 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 412406 T2355 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 412407 T2355 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 412407 T2355 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 412417 T2355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 412418 T2355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 412420 T2355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 412421 T2355 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 412425 T2355 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 412425 T2355 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 412425 T2355 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=60.369140625, floorSegmentMB=1.87109375, forceMergeDeletesPctAllowed=16.64506491494333, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3115152765900482 [junit4] 2> 412426 T2355 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@17bfecc0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d315b92),segFN=segments_1,generation=1} [junit4] 2> 412426 T2355 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 412427 T2355 oass.SolrIndexSearcher.<init> Opening Searcher@48d7329e[collection1] main [junit4] 2> 412427 T2355 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 412428 T2355 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 412428 T2355 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 412428 T2355 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 412428 T2355 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 412429 T2355 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 412429 T2355 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 412429 T2355 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 412430 T2355 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 412431 T2355 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 412432 T2355 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 412432 T2355 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 412434 T2356 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@48d7329e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 412435 T2355 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 412436 T2359 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:37737/orln collection:control_collection shard:shard1 [junit4] 2> 412436 T2325 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3 [junit4] 2> 412436 T2325 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 412436 T2359 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 412437 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 412438 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 412440 T2362 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e3464f1 name:ZooKeeperConnection Watcher:127.0.0.1:47646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 412440 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 412440 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 412441 T2325 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 412441 T2359 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 412443 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 412443 T2359 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 412443 T2359 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C136 name=collection1 org.apache.solr.core.SolrCore@3982b644 url=http://127.0.0.1:37737/orln/collection1 node=127.0.0.1:37737_orln C136_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:37737/orln, node_name=127.0.0.1:37737_orln, state=down} [junit4] 2> 412443 T2359 C136 P37737 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37737/orln/collection1/ [junit4] 2> 412444 T2359 C136 P37737 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 412444 T2325 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 412444 T2359 C136 P37737 oasc.SyncStrategy.syncToMe http://127.0.0.1:37737/orln/collection1/ has no replicas [junit4] 2> 412445 T2325 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 412445 T2359 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37737/orln/collection1/ shard1 [junit4] 2> 412446 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 412446 T2359 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 412447 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 412448 T2365 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5249c5fa name:ZooKeeperConnection Watcher:127.0.0.1:47646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 412448 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 412449 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 412450 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 412453 T2352 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 412454 T2352 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 412454 T2352 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 412455 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 412455 T2352 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 412456 T2351 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> 412456 T2362 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> 412502 T2359 oasc.ZkController.register We are http://127.0.0.1:37737/orln/collection1/ and leader is http://127.0.0.1:37737/orln/collection1/ [junit4] 2> 412503 T2359 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37737/orln [junit4] 2> 412503 T2359 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 412503 T2359 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 412503 T2359 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 412504 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 412506 T2352 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:37737/orln";, [junit4] 2> "node_name":"127.0.0.1:37737_orln", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 412609 T2362 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> 412609 T2351 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> 413587 T2325 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 413587 T2325 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 413590 T2325 oejs.AbstractConnector.doStart Started [email protected]:35189 [junit4] 2> 413590 T2325 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 413591 T2325 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 413591 T2325 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003 [junit4] 2> 413591 T2325 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/' [junit4] 2> 413620 T2325 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/solr.xml [junit4] 2> 413633 T2325 oasc.CoreContainer.<init> New CoreContainer 751864104 [junit4] 2> 413634 T2325 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/] [junit4] 2> 413634 T2325 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 413634 T2325 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 413635 T2325 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 413635 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 413635 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 413635 T2325 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 413635 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 413636 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 413636 T2325 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 413636 T2325 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 413637 T2325 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 413637 T2325 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 413637 T2325 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 413637 T2325 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 413638 T2325 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:47646/solr [junit4] 2> 413638 T2325 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 413638 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 413639 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 413641 T2377 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b3bc7d name:ZooKeeperConnection Watcher:127.0.0.1:47646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 413641 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 413642 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 413644 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 413645 T2380 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ed9fdbd name:ZooKeeperConnection Watcher:127.0.0.1:47646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 413645 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 413649 T2325 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 414653 T2325 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35189_orln [junit4] 2> 414654 T2325 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35189_orln [junit4] 2> 414656 T2325 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 414662 T2381 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 414662 T2381 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 414663 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 414663 T2381 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 414663 T2381 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 414664 T2381 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 414665 T2381 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 414665 T2381 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 414668 T2352 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35189/orln";, [junit4] 2> "node_name":"127.0.0.1:35189_orln", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 414670 T2352 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 414671 T2352 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 414673 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 414674 T2352 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 414674 T2380 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 414675 T2380 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 415666 T2381 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 415667 T2381 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 415667 T2381 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 415667 T2381 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 415668 T2381 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 415668 T2381 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/collection1/' [junit4] 2> 415670 T2381 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 415670 T2381 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 415671 T2381 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 415702 T2381 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 415704 T2381 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 415718 T2381 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 415729 T2381 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 415831 T2381 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 415837 T2381 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 415952 T2381 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 415962 T2381 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 415964 T2381 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 415971 T2381 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 415974 T2381 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 415976 T2381 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 415977 T2381 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 415978 T2381 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 415978 T2381 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 415978 T2381 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 415979 T2381 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 415979 T2381 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 415979 T2381 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 415979 T2381 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-003/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty1/ [junit4] 2> 415980 T2381 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@330fda4c [junit4] 2> 415981 T2381 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty1 [junit4] 2> 415981 T2381 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty1/index/ [junit4] 2> 415981 T2381 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 415981 T2381 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty1/index [junit4] 2> 415982 T2381 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=64.537109375, floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=3.111209946074762, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 415983 T2381 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e365fa1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@66e9bbe),segFN=segments_1,generation=1} [junit4] 2> 415983 T2381 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 415987 T2381 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 415987 T2381 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 415987 T2381 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 415988 T2381 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 415988 T2381 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 415988 T2381 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 415989 T2381 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 415989 T2381 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 415989 T2381 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 415990 T2381 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 415990 T2381 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 415990 T2381 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 415991 T2381 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 415991 T2381 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 415992 T2381 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 415992 T2381 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 415992 T2381 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 415993 T2381 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 415993 T2381 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 415993 T2381 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 415994 T2381 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 416004 T2381 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 416005 T2381 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 416006 T2381 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 416008 T2381 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 416011 T2381 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 416011 T2381 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 416012 T2381 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=60.369140625, floorSegmentMB=1.87109375, forceMergeDeletesPctAllowed=16.64506491494333, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3115152765900482 [junit4] 2> 416012 T2381 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e365fa1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@66e9bbe),segFN=segments_1,generation=1} [junit4] 2> 416013 T2381 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 416013 T2381 oass.SolrIndexSearcher.<init> Opening Searcher@1e3921a8[collection1] main [junit4] 2> 416013 T2381 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 416014 T2381 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 416014 T2381 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 416014 T2381 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 416015 T2381 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 416015 T2381 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 416016 T2381 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 416016 T2381 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 416016 T2381 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 416016 T2381 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 416019 T2382 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e3921a8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 416020 T2381 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 416020 T2385 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:35189/orln collection:collection1 shard:shard2 [junit4] 2> 416021 T2325 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3 [junit4] 2> 416021 T2325 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 416021 T2385 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 416025 T2385 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 416026 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 416026 T2385 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 416026 T2385 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C137 name=collection1 org.apache.solr.core.SolrCore@4e55ac0b url=http://127.0.0.1:35189/orln/collection1 node=127.0.0.1:35189_orln C137_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:35189/orln, node_name=127.0.0.1:35189_orln, state=down} [junit4] 2> 416026 T2385 C137 P35189 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:35189/orln/collection1/ [junit4] 2> 416027 T2385 C137 P35189 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 416027 T2385 C137 P35189 oasc.SyncStrategy.syncToMe http://127.0.0.1:35189/orln/collection1/ has no replicas [junit4] 2> 416027 T2385 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:35189/orln/collection1/ shard2 [junit4] 2> 416027 T2385 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 416030 T2352 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 416030 T2380 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 416031 T2380 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 416035 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 416035 T2352 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 416035 T2380 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 416036 T2380 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 416081 T2385 oasc.ZkController.register We are http://127.0.0.1:35189/orln/collection1/ and leader is http://127.0.0.1:35189/orln/collection1/ [junit4] 2> 416082 T2385 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35189/orln [junit4] 2> 416082 T2385 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 416082 T2385 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 416082 T2385 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 416083 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 416085 T2385 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 416085 T2352 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35189/orln";, [junit4] 2> "node_name":"127.0.0.1:35189_orln", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 416088 T2352 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 416089 T2380 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 416089 T2380 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 417139 T2325 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 417140 T2325 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 417142 T2325 oejs.AbstractConnector.doStart Started [email protected]:28438 [junit4] 2> 417143 T2325 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 417143 T2325 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 417143 T2325 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004 [junit4] 2> 417143 T2325 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/' [junit4] 2> 417173 T2325 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/solr.xml [junit4] 2> 417186 T2325 oasc.CoreContainer.<init> New CoreContainer 967093745 [junit4] 2> 417187 T2325 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/] [junit4] 2> 417187 T2325 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 417187 T2325 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 417188 T2325 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 417188 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 417188 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 417188 T2325 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 417189 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 417189 T2325 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 417189 T2325 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 417189 T2325 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 417190 T2325 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 417190 T2325 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 417190 T2325 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 417190 T2325 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 417191 T2325 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:47646/solr [junit4] 2> 417191 T2325 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 417191 T2325 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 417192 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 417194 T2397 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d035dfa name:ZooKeeperConnection Watcher:127.0.0.1:47646 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 417195 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 417195 T2325 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 417197 T2325 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 417199 T2400 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f3d26d0 name:ZooKeeperConnection Watcher:127.0.0.1:47646/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 417199 T2325 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 417203 T2325 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 418206 T2325 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:28438_orln [junit4] 2> 418207 T2325 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:28438_orln [junit4] 2> 418210 T2325 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 418215 T2401 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 418215 T2401 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 418216 T2351 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 418216 T2401 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 418217 T2401 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 418217 T2401 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 418218 T2401 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 418218 T2401 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 418218 T2352 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:28438/orln";, [junit4] 2> "node_name":"127.0.0.1:28438_orln", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 418220 T2352 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 418220 T2352 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 418223 T2352 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 418224 T2400 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 418224 T2380 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 418224 T2400 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 418225 T2380 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 419218 T2401 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 419219 T2401 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 419219 T2401 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 419220 T2401 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 419220 T2401 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 419220 T2401 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/collection1/' [junit4] 2> 419222 T2401 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 419223 T2401 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 419224 T2401 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 419256 T2401 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 419258 T2401 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 419273 T2401 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 419285 T2401 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 419386 T2401 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 419392 T2401 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 419507 T2401 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 419517 T2401 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 419518 T2401 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 419526 T2401 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 419529 T2401 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 419531 T2401 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 419532 T2401 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 419533 T2401 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 419533 T2401 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 419533 T2401 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 419534 T2401 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 419534 T2401 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 419534 T2401 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 419534 T2401 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-004/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty2/ [junit4] 2> 419535 T2401 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@330fda4c [junit4] 2> 419536 T2401 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty2 [junit4] 2> 419536 T2401 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty2/index/ [junit4] 2> 419536 T2401 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 419537 T2401 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J3/temp/solr.handler.TestSolrConfigHandlerConcurrent-103A566D0461F871-001/tempDir-001/jetty2/index [junit4] 2> 419537 T2401 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=64.537109375, floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=3.111209946074762, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 419538 T2401 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ad441db lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@505677e5),segFN=segments_1,generation=1} [junit4] 2> 419538 T2401 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 419542 T2401 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 419543 T2401 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 419543 T2401 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 419543 T2401 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 419543 T2401 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 419544 T2401 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 419544 T2401 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 419544 T2401 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 419545 T2401 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 419545 T2401 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 419546 T2401 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 419546 T2401 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 419546 T2401 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 419547 T2401 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 419548 T2401 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 419548 T2401 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 419548 T2401 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 419548 T2401 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 419549 T2401 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 419549 T2401 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 419550 T2401 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 419560 T2401 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 419562 T2401 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 419563 T2401 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 419564 T2401 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 419567 T2401 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 419568 T2401 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 419569 T2401 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=60.369140625, floorSegmentMB=1.87109375, forceMergeDeletesPctAllowed=16.64506491494333, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3115152765900482 [junit4] 2> 419569 T2401 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4ad441db lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@505677e5),segFN=segments_1,generation=1} [junit4] 2> 419570 T2401 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 419570 T2401 oass.SolrIndexSearcher.<init> Opening Searcher@62dc7557[collection1] main [junit4] 2> 419570 T2401 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 419571 T2401 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 419571 T2401 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 419572 T2401 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 419572 T2401 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 419572 T2401 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 419573 T2401 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 419573 T2401 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 419573 T2401 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 419573 T2401 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 419577 T2402 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62dc7557[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 419577 T2401 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 419578 T2405 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:28438/orln collection:co [...truncated too long message...] -Tests-trunk-Java8/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/lib/protobuf-java-2.5.0.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/lib/slf4j-log4j12-1.7.6.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/lib/spatial4j-0.4.1.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/commons-io-2.4.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/httpclient-4.3.1.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/httpcore-4.3.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/httpmime-4.3.1.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/noggit-0.6.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/slf4j-api-1.7.6.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/wstx-asl-3.2.7.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/solrj/lib/zookeeper-3.4.6.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-continuation-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-deploy-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-http-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-io-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-jmx-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-security-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-server-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-servlet-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-util-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-webapp-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/jetty-xml-8.1.10.v20130312.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/server/lib/servlet-api-3.0.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/build/core/classes/java:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/test-framework/lib/junit-4.10.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/test-framework/lib/randomizedtesting-runner-2.1.9.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/cglib-nodep-2.2.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/commons-collections-3.2.1.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/easymock-3.0.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/hadoop-common-2.2.0-tests.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/hadoop-hdfs-2.2.0-tests.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/javax.servlet-api-3.0.1.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/jcl-over-slf4j-1.7.6.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/jersey-core-1.9.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/jetty-6.1.26.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/jetty-util-6.1.26.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/test-lib/objenesis-1.2.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/build/analysis/icu/lucene-analyzers-icu-6.0.0-SNAPSHOT.jar:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/contrib/solr-analysis-extras/classes/java:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/home/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/usr/local/openjdk8/lib/tools.jar:/home/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.9.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/temp/junit4-J2-20141121_173810_288.events @/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/temp/junit4-J2-20141121_173810_288.suites [junit4] ERROR: JVM J2 ended with an exception: Forked process returned with error code: 1. Very likely a JVM crash. Process output piped in logs above. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1481) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:515: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:463: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:61: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build.xml:186: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/common-build.xml:508: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/common-build.xml:1345: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/common-build.xml:952: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. Process output piped in logs above. Total time: 92 minutes 30 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
