Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11495/ Java: 64bit/jdk1.8.0_40-ea-b09 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZkTest.testDistribSearch Error Message: commitWithin did not work on node: http://127.0.0.1:32923/collection1 expected:<68> but was:<67> Stack Trace: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:32923/collection1 expected:<68> but was:<67> at __randomizedtesting.SeedInfo.seed([A0482B7057D83484:21AEA568208754B8]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:345) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) REGRESSION: org.apache.solr.cloud.HttpPartitionTest.testDistribSearch Error Message: Doc with id=2 not found in http://127.0.0.1:45903/wk_c/c8n_1x2_leader_session_loss due to: Path not found: /id; rsp={doc=null} Stack Trace: java.lang.AssertionError: Doc with id=2 not found in http://127.0.0.1:45903/wk_c/c8n_1x2_leader_session_loss due to: Path not found: /id; rsp={doc=null} at __randomizedtesting.SeedInfo.seed([A0482B7057D83484:21AEA568208754B8]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.HttpPartitionTest.assertDocExists(HttpPartitionTest.java:435) at org.apache.solr.cloud.HttpPartitionTest.assertDocsExistInAllReplicas(HttpPartitionTest.java:396) at org.apache.solr.cloud.HttpPartitionTest.testLeaderZkSessionLoss(HttpPartitionTest.java:344) at org.apache.solr.cloud.HttpPartitionTest.doTest(HttpPartitionTest.java:119) 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:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11294 lines...] [junit4] Suite: org.apache.solr.cloud.HttpPartitionTest [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/init-core-data-001 [junit4] 2> 500340 T1599 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /wk_c/ [junit4] 2> 500343 T1599 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 500343 T1599 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 500344 T1600 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 500444 T1599 oasc.ZkTestServer.run start zk server on port:49519 [junit4] 2> 500444 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 500445 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 500447 T1606 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@520b3d3 name:ZooKeeperConnection Watcher:127.0.0.1:49519 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 500448 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 500448 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 500448 T1599 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 500451 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 500451 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 500452 T1608 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22f751c6 name:ZooKeeperConnection Watcher:127.0.0.1:49519/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 500452 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 500453 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 500453 T1599 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 500454 T1599 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 500455 T1599 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 500456 T1599 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 500457 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 500457 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 500459 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 500459 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 500460 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 500460 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 500461 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 500462 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 500463 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 500463 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 500464 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 500464 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 500465 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 500465 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 500466 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 500466 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 500467 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 500468 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 500469 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 500469 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 500470 T1599 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 500470 T1599 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 500707 T1599 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 500710 T1599 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47124 [junit4] 2> 500710 T1599 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 500711 T1599 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 500711 T1599 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002 [junit4] 2> 500711 T1599 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/' [junit4] 2> 500721 T1599 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/solr.xml [junit4] 2> 500736 T1599 oasc.CoreContainer.<init> New CoreContainer 1642254524 [junit4] 2> 500737 T1599 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/] [junit4] 2> 500737 T1599 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 500737 T1599 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 500737 T1599 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 500738 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 500738 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 500738 T1599 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 500738 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 500738 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 500739 T1599 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 500739 T1599 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 500739 T1599 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 500739 T1599 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 500740 T1599 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 500740 T1599 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 500740 T1599 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49519/solr [junit4] 2> 500740 T1599 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 500741 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 500741 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 500743 T1619 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@367ef4a4 name:ZooKeeperConnection Watcher:127.0.0.1:49519 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 500743 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 500744 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 500746 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 500747 T1621 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d960e83 name:ZooKeeperConnection Watcher:127.0.0.1:49519/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 500747 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 500748 T1599 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 500750 T1599 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 500751 T1599 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 500752 T1599 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 500753 T1599 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 500754 T1599 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 500755 T1599 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45903_wk_c [junit4] 2> 500755 T1599 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45903_wk_c [junit4] 2> 500756 T1599 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 500757 T1599 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 500759 T1599 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 500759 T1599 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:45903_wk_c [junit4] 2> 500760 T1599 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 500761 T1599 oasc.Overseer.start Overseer (id=92660464147496963-127.0.0.1:45903_wk_c-n_0000000000) starting [junit4] 2> 500762 T1599 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 500765 T1599 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 500765 T1623 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 500766 T1599 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 500767 T1599 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 500768 T1599 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 500769 T1622 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 500770 T1625 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 500770 T1625 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 500771 T1625 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 500771 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 500772 T1622 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:45903/wk_c", [junit4] 2> "node_name":"127.0.0.1:45903_wk_c", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 500772 T1622 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 500772 T1622 oasc.Overseer$ClusterStateUpdater.createCollection state version control_collection 1 [junit4] 2> 500773 T1622 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 500774 T1626 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> 501771 T1625 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 501771 T1625 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 501772 T1625 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 501772 T1625 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 501772 T1625 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 501772 T1625 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/collection1/' [junit4] 2> 501773 T1625 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 501773 T1625 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 501773 T1625 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 501791 T1625 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 501801 T1625 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 501802 T1625 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 501805 T1625 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 501897 T1625 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 501900 T1625 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 501903 T1625 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 501909 T1625 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 501911 T1625 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 501913 T1625 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 501914 T1625 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 501915 T1625 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 501915 T1625 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 501916 T1625 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 501916 T1625 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 501916 T1625 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 501916 T1625 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 501916 T1625 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/control/data/ [junit4] 2> 501917 T1625 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6401fcd5 [junit4] 2> 501917 T1625 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/control/data [junit4] 2> 501917 T1625 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/control/data/index/ [junit4] 2> 501918 T1625 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 501918 T1625 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/control/data/index [junit4] 2> 501919 T1625 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 501987 T1625 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-001 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-001),segFN=segments_1,generation=1} [junit4] 2> 501987 T1625 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 501990 T1625 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 501991 T1625 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 501991 T1625 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 501991 T1625 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 501991 T1625 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 501991 T1625 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 501992 T1625 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 501992 T1625 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 501992 T1625 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 501993 T1625 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 501993 T1625 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 501993 T1625 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 501993 T1625 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 501994 T1625 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 501994 T1625 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 501994 T1625 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 501994 T1625 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 501994 T1625 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 501995 T1625 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 501995 T1625 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 501997 T1625 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 501998 T1625 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 501999 T1625 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 502000 T1625 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 502001 T1625 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 502001 T1625 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 502002 T1625 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=70.08203125, floorSegmentMB=0.6015625, forceMergeDeletesPctAllowed=20.05196498721051, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 502003 T1625 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-001 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-001),segFN=segments_1,generation=1} [junit4] 2> 502004 T1625 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 502004 T1625 oass.SolrIndexSearcher.<init> Opening Searcher@3d288f75[collection1] main [junit4] 2> 502004 T1625 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 502005 T1625 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 502005 T1625 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 502006 T1625 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 502006 T1625 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 502006 T1625 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 502006 T1625 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 502007 T1625 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 502007 T1625 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 502009 T1625 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 502009 T1625 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 502009 T1625 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 502010 T1627 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d288f75[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 502012 T1625 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 502013 T1630 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:45903/wk_c collection:control_collection shard:shard1 [junit4] 2> 502013 T1599 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4] 2> 502013 T1599 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 502013 T1630 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 502017 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 502017 T1630 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 502017 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 502019 T1633 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@526750dd name:ZooKeeperConnection Watcher:127.0.0.1:49519/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 502019 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 502019 T1630 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 502019 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 502019 T1630 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 502020 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> ASYNC NEW_CORE C1751 name=collection1 org.apache.solr.core.SolrCore@188a3634 url=http://127.0.0.1:45903/wk_c/collection1 node=127.0.0.1:45903_wk_c C1751_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:45903/wk_c, node_name=127.0.0.1:45903_wk_c, state=down} [junit4] 2> 502020 T1630 C1751 P45903 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:45903/wk_c/collection1/ [junit4] 2> 502020 T1630 C1751 P45903 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 502021 T1630 C1751 P45903 oasc.SyncStrategy.syncToMe http://127.0.0.1:45903/wk_c/collection1/ has no replicas [junit4] 2> 502021 T1630 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:45903/wk_c/collection1/ shard1 [junit4] 2> 502021 T1599 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 502021 T1630 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 502023 T1599 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 502023 T1599 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 502023 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 502024 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 502024 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 502025 T1635 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7fa8ae04 name:ZooKeeperConnection Watcher:127.0.0.1:49519/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 502026 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 502026 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 502027 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 502028 T1622 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 502028 T1622 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 502029 T1622 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 502030 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 502030 T1626 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> 502031 T1636 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> 502075 T1630 oasc.ZkController.register We are http://127.0.0.1:45903/wk_c/collection1/ and leader is http://127.0.0.1:45903/wk_c/collection1/ [junit4] 2> 502075 T1630 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:45903/wk_c [junit4] 2> 502075 T1630 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 502075 T1630 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 502075 T1630 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 502076 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 502078 T1622 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:45903/wk_c", [junit4] 2> "node_name":"127.0.0.1:45903_wk_c", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 502180 T1626 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> 502180 T1636 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> 502260 T1599 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 502261 T1599 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 502263 T1599 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54045 [junit4] 2> 502264 T1599 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 502265 T1599 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 502265 T1599 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003 [junit4] 2> 502265 T1599 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/' [junit4] 2> 502277 T1599 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/solr.xml [junit4] 2> 502285 T1599 oasc.CoreContainer.<init> New CoreContainer 764726163 [junit4] 2> 502286 T1599 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/] [junit4] 2> 502286 T1599 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 502287 T1599 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 502287 T1599 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 502287 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 502287 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 502288 T1599 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 502288 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 502288 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 502288 T1599 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 502288 T1599 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 502289 T1599 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 502289 T1599 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 502289 T1599 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 502290 T1599 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 502290 T1599 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49519/solr [junit4] 2> 502290 T1599 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 502291 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 502291 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 502300 T1647 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e7886a5 name:ZooKeeperConnection Watcher:127.0.0.1:49519 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 502301 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 502302 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 502304 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 502305 T1649 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@59683aba name:ZooKeeperConnection Watcher:127.0.0.1:49519/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 502305 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 502309 T1599 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 503312 T1599 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50127_wk_c [junit4] 2> 503313 T1599 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50127_wk_c [junit4] 2> 503318 T1599 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 503324 T1651 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 503324 T1651 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 503326 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 503326 T1651 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 503327 T1651 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 503327 T1651 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 503329 T1622 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:50127/wk_c", [junit4] 2> "node_name":"127.0.0.1:50127_wk_c", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 503329 T1651 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 503330 T1651 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 503332 T1622 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 503333 T1622 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2 [junit4] 2> 503336 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 503336 T1650 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 503337 T1650 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 504330 T1651 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 504330 T1651 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 504331 T1651 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 504331 T1651 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 504332 T1651 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 504333 T1651 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/collection1/' [junit4] 2> 504334 T1651 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 504334 T1651 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 504334 T1651 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 504364 T1651 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 504376 T1651 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 504377 T1651 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 504381 T1651 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 504497 T1651 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 504501 T1651 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 504502 T1651 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 504511 T1651 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 504513 T1651 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 504516 T1651 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 504518 T1651 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 504519 T1651 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 504519 T1651 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 504520 T1651 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 504520 T1651 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 504520 T1651 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 504521 T1651 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 504521 T1651 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty1/ [junit4] 2> 504521 T1651 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6401fcd5 [junit4] 2> 504522 T1651 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty1 [junit4] 2> 504522 T1651 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty1/index/ [junit4] 2> 504522 T1651 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 504524 T1651 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty1/index [junit4] 2> 504524 T1651 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 504629 T1651 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-002 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-002),segFN=segments_1,generation=1} [junit4] 2> 504629 T1651 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 504631 T1651 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 504631 T1651 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 504631 T1651 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 504632 T1651 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 504632 T1651 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 504632 T1651 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 504632 T1651 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 504632 T1651 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 504632 T1651 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 504633 T1651 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 504633 T1651 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 504633 T1651 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 504633 T1651 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 504634 T1651 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 504634 T1651 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 504634 T1651 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 504634 T1651 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 504634 T1651 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 504635 T1651 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 504635 T1651 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 504636 T1651 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 504637 T1651 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 504637 T1651 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 504638 T1651 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 504639 T1651 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 504639 T1651 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 504640 T1651 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=70.08203125, floorSegmentMB=0.6015625, forceMergeDeletesPctAllowed=20.05196498721051, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 504641 T1651 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-002 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-002),segFN=segments_1,generation=1} [junit4] 2> 504641 T1651 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 504641 T1651 oass.SolrIndexSearcher.<init> Opening Searcher@11ce8f77[collection1] main [junit4] 2> 504641 T1651 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 504642 T1651 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 504642 T1651 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 504643 T1651 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 504643 T1651 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 504643 T1651 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 504644 T1651 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 504644 T1651 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 504644 T1651 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 504644 T1651 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 504646 T1652 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11ce8f77[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 504647 T1651 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 504648 T1655 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50127/wk_c collection:collection1 shard:shard2 [junit4] 2> 504648 T1599 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4] 2> 504649 T1599 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 504649 T1655 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 504652 T1655 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 504653 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 504653 T1655 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 504653 T1655 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1752 name=collection1 org.apache.solr.core.SolrCore@36465b03 url=http://127.0.0.1:50127/wk_c/collection1 node=127.0.0.1:50127_wk_c C1752_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:50127/wk_c, node_name=127.0.0.1:50127_wk_c, state=down} [junit4] 2> 504653 T1655 C1752 P50127 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50127/wk_c/collection1/ [junit4] 2> 504653 T1655 C1752 P50127 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 504653 T1655 C1752 P50127 oasc.SyncStrategy.syncToMe http://127.0.0.1:50127/wk_c/collection1/ has no replicas [junit4] 2> 504654 T1655 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50127/wk_c/collection1/ shard2 [junit4] 2> 504654 T1655 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 504657 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 504658 T1650 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 504658 T1650 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 504661 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 504662 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 504662 T1650 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 504662 T1650 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 504708 T1655 oasc.ZkController.register We are http://127.0.0.1:50127/wk_c/collection1/ and leader is http://127.0.0.1:50127/wk_c/collection1/ [junit4] 2> 504708 T1655 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50127/wk_c [junit4] 2> 504708 T1655 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 504709 T1655 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 504709 T1655 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 504710 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 504712 T1622 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:50127/wk_c", [junit4] 2> "node_name":"127.0.0.1:50127_wk_c", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 504712 T1655 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 504715 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 504715 T1650 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 504716 T1650 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 504899 T1599 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 504900 T1599 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 504902 T1599 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55846 [junit4] 2> 504903 T1599 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 504903 T1599 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 504903 T1599 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004 [junit4] 2> 504903 T1599 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/' [junit4] 2> 504914 T1599 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/solr.xml [junit4] 2> 504922 T1599 oasc.CoreContainer.<init> New CoreContainer 1101382049 [junit4] 2> 504922 T1599 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/] [junit4] 2> 504922 T1599 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 504923 T1599 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 504923 T1599 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 504923 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 504923 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 504923 T1599 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 504924 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 504924 T1599 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 504924 T1599 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 504925 T1599 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 504925 T1599 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 504925 T1599 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 504926 T1599 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 504926 T1599 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 504926 T1599 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49519/solr [junit4] 2> 504926 T1599 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 504927 T1599 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 504927 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 504929 T1667 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3259bf53 name:ZooKeeperConnection Watcher:127.0.0.1:49519 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 504929 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 504930 T1599 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 504932 T1599 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 504933 T1669 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b2f1995 name:ZooKeeperConnection Watcher:127.0.0.1:49519/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 504934 T1599 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 504938 T1599 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 505942 T1599 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33301_wk_c [junit4] 2> 505943 T1599 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33301_wk_c [junit4] 2> 505948 T1599 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 505955 T1671 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 505955 T1671 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 505957 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 505958 T1671 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 505958 T1671 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 505959 T1671 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 505960 T1671 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 505960 T1622 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:33301/wk_c", [junit4] 2> "node_name":"127.0.0.1:33301_wk_c", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 505960 T1671 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 505963 T1622 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2 [junit4] 2> 505964 T1622 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 505967 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 505968 T1670 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 505968 T1650 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 505968 T1670 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 505969 T1650 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 506961 T1671 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 506961 T1671 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 506962 T1671 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 506963 T1671 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 506964 T1671 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 506964 T1671 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/collection1/' [junit4] 2> 506965 T1671 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 506965 T1671 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 506966 T1671 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 507002 T1671 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 507016 T1671 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 507018 T1671 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 507022 T1671 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 507120 T1671 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 507124 T1671 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 507125 T1671 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 507133 T1671 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 507135 T1671 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 507136 T1671 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 507137 T1671 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 507138 T1671 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 507138 T1671 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 507139 T1671 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 507139 T1671 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 507139 T1671 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 507139 T1671 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 507139 T1671 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty2/ [junit4] 2> 507140 T1671 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6401fcd5 [junit4] 2> 507140 T1671 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty2 [junit4] 2> 507141 T1671 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty2/index/ [junit4] 2> 507141 T1671 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 507142 T1671 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/tempDir-001/jetty2/index [junit4] 2> 507142 T1671 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 507150 T1671 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-003 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-003),segFN=segments_1,generation=1} [junit4] 2> 507150 T1671 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 507154 T1671 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 507154 T1671 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 507155 T1671 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 507155 T1671 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 507155 T1671 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 507155 T1671 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 507156 T1671 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 507156 T1671 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 507156 T1671 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 507157 T1671 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 507157 T1671 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 507158 T1671 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 507158 T1671 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 507159 T1671 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 507159 T1671 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 507159 T1671 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 507160 T1671 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 507160 T1671 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 507161 T1671 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 507161 T1671 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 507164 T1671 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 507165 T1671 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 507166 T1671 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 507167 T1671 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 507169 T1671 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 507169 T1671 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 507170 T1671 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=70.08203125, floorSegmentMB=0.6015625, forceMergeDeletesPctAllowed=20.05196498721051, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 507171 T1671 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-003 lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-A0482B7057D83484-001/index-MMapDirectory-003),segFN=segments_1,generation=1} [junit4] 2> 507171 T1671 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 507171 T1671 oass.SolrIndexSearcher.<init> Opening Searcher@6620b11f[collection1] main [junit4] 2> 507172 T1671 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 507173 T1671 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 507173 T1671 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 507174 T1671 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 507174 T1671 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 507174 T1671 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 507175 T1671 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 507175 T1671 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 507175 T1671 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 507175 T1671 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 507178 T1672 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6620b11f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 507180 T1671 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 507181 T1675 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:33301/wk_c collection:collection1 shard:shard1 [junit4] 2> 507182 T1599 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0 [junit4] 2> 507182 T1599 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 507182 T1675 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 507187 T1675 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 507188 T1626 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 507189 T1675 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 507189 T1675 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1753 name=collection1 org.apache.solr.core.SolrCore@2635749a url=http://127.0.0.1:33301/wk_c/collection1 node=127.0.0.1:33301_wk_c C1753_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:33301/wk_c, node_name=127.0.0.1:33301_wk_c, state=down} [junit4] 2> 507189 T1675 C1753 P33301 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33301/wk_c/collection1/ [junit4] 2> 507189 T1675 C1753 P33301 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 507189 T1675 C1753 P33301 oasc.SyncStrategy.syncToMe http://127.0.0.1:33301/wk_c/collection1/ has no replicas [junit4] 2> 507190 T1675 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33301/wk_c/collection1/ shard1 [junit4] 2> 507190 T1675 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 507192 T1622 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/collection1/state.json [junit4] 2> 507192 T1670 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 507192 T1650 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 507192 T1670 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 6 [junit4] 2> 5071 [...truncated too long message...] rrently being tracked [junit4] 2> 1269055 T4124 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001/tempDir-001/jetty4/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001/tempDir-001/jetty4/index;done=false>>] [junit4] 2> 1269056 T4124 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001/tempDir-001/jetty4/index [junit4] 2> 1269056 T4124 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001/tempDir-001/jetty4 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001/tempDir-001/jetty4;done=false>>] [junit4] 2> 1269056 T4124 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001/tempDir-001/jetty4 [junit4] 2> 1269057 T4124 oasc.Overseer.close Overseer (id=92660511628394509-127.0.0.1:53199_-n_0000000004) closing [junit4] 2> 1269058 T4288 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:53199_ [junit4] 2> 1269085 T4283 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 1269112 T4124 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> 1274099 T4124 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 1274102 T4124 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:59927 59927 [junit4] 2> 1274681 T4125 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:59927 59927 [junit4] 2> 1274688 T4125 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 7 /solr/aliases.json [junit4] 2> 7 /solr/clusterstate.json [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 7 /solr/live_nodes [junit4] 2> 5 /solr/overseer/queue [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=A0482B7057D83484 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=cs_CZ -Dtests.timezone=Asia/Colombo -Dtests.file.encoding=UTF-8 [junit4] FAILURE 50.0s J1 | BasicDistributedZkTest.testDistribSearch <<< [junit4] > Throwable #1: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:32923/collection1 expected:<68> but was:<67> [junit4] > at __randomizedtesting.SeedInfo.seed([A0482B7057D83484:21AEA568208754B8]:0) [junit4] > at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:345) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1274787 T4124 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.BasicDistributedZkTest-A0482B7057D83484-001 [junit4] 2> 49995 T4123 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=PostingsFormat(name=Memory doPackFST= true), SubjectTerms_mfacet=PostingsFormat(name=Memory doPackFST= true), n_l1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), intDefault=PostingsFormat(name=Memory doPackFST= true), n_td1=PostingsFormat(name=Memory doPackFST= true), n_d1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), n_f1=PostingsFormat(name=Memory doPackFST= true), n_tl1=PostingsFormat(name=Memory doPackFST= true), oddField_s=PostingsFormat(name=Lucene41VarGapFixedInterval), n_tf1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), a_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), id=PostingsFormat(name=Lucene41VarGapFixedInterval), text=FSTOrd41, timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), foo_i=PostingsFormat(name=Memory doPackFST= true), n_dt1=PostingsFormat(name=Memory doPackFST= true), a_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), n_ti1=PostingsFormat(name=Lucene41VarGapFixedInterval), rnd_b=PostingsFormat(name=Lucene41VarGapFixedInterval), _version_=PostingsFormat(name=Memory doPackFST= true), foo_b=FSTOrd41, n_tdt1=PostingsFormat(name=Memory doPackFST= true), foo_d=PostingsFormat(name=Lucene41VarGapFixedInterval), foo_f=FSTOrd41}, docValues:{timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=cs_CZ, timezone=Asia/Colombo [junit4] 2> NOTE: Linux 3.13.0-37-generic amd64/Oracle Corporation 1.8.0_40-ea (64-bit)/cpus=8,threads=1,free=244492528,total=406847488 [junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryComponentCustomSortTest, TestSimpleQParserPlugin, SimplePostToolTest, SolrRequestParserTest, TestCollapseQParserPlugin, TestExtendedDismaxParser, TermVectorComponentTest, TestReplicaProperties, DistributedFacetPivotLargeTest, MultiThreadedOCPTest, TestShardHandlerFactory, AnalysisAfterCoreReloadTest, SolrCoreTest, OverseerStatusTest, TestMultiCoreConfBootstrap, ResourceLoaderTest, TestPhraseSuggestions, TestLFUCache, TestIBSimilarityFactory, TestFieldTypeResource, TestWriterPerf, FullSolrCloudDistribCmdsTest, TestRecovery, QueryParsingTest, BlockCacheTest, TestSchemaManager, SampleTest, BinaryUpdateRequestHandlerTest, DefaultValueUpdateProcessorTest, TestCoreContainer, HdfsRecoveryZkTest, ChaosMonkeySafeLeaderTest, DistributedFacetPivotSmallTest, DistributedDebugComponentTest, SchemaVersionSpecificBehaviorTest, SoftAutoCommitTest, FileBasedSpellCheckerTest, BadCopyFieldTest, RollingRestartTest, TestReloadAndDeleteDocs, TestReRankQParserPlugin, TestPerFieldSimilarity, TestBM25SimilarityFactory, EnumFieldTest, CacheHeaderTest, TestCSVLoader, DistribDocExpirationUpdateProcessorTest, TestLazyCores, CoreContainerCoreInitFailuresTest, TestBadConfig, TestCollationField, StatsComponentTest, TestCustomSort, ResponseHeaderTest, TestRandomMergePolicy, SortByFunctionTest, TermVectorComponentDistributedTest, RankQueryTest, RequestHandlersTest, SOLR749Test, NumericFieldsTest, AlternateDirectoryTest, TestZkChroot, TestRandomFaceting, TestSolrQueryParserResource, TestQueryUtils, TestBulkSchemaConcurrent, TestExceedMaxTermLength, SliceStateTest, DirectUpdateHandlerOptimizeTest, DocValuesTest, SharedFSAutoReplicaFailoverUtilsTest, AutoCommitTest, HardAutoCommitTest, CursorPagingTest, ClusterStateTest, SynonymTokenizerTest, MultiTermTest, LoggingHandlerTest, URLClassifyProcessorTest, ScriptEngineTest, ConvertedLegacyTest, TestRecoveryHdfs, TestPseudoReturnFields, TestCloudInspectUtil, ExternalCollectionsTest, TestFunctionQuery, OverseerRolesTest, SolrPluginUtilsTest, TestCloudPivotFacet, CollectionsAPIDistributedZkTest, TestSerializedLuceneMatchVersion, OutputWriterTest, DistributedExpandComponentTest, DateMathParserTest, TestXIncludeConfig, HdfsBasicDistributedZkTest, TestMiniSolrCloudCluster, UniqFieldsUpdateProcessorFactoryTest, TestFieldSortValues, TestSolrQueryParser, TestValueSourceCache, RAMDirectoryFactoryTest, DateFieldTest, BasicDistributedZkTest] [junit4] Completed on J1 in 50.28s, 1 test, 1 failure <<< FAILURES! [...truncated 773 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:524: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:472: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1359: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:961: There were test failures: 437 suites, 1796 tests, 2 failures, 44 ignored (21 assumptions) Total time: 101 minutes 42 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0_40-ea-b09 -XX:-UseCompressedOops -XX:+UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org