Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11418/ Java: 32bit/jdk1.7.0_72 -server -XX:+UseParallelGC
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.DistribCursorPagingTest Error Message: Some resources were not closed, shutdown, or released. Stack Trace: java.lang.AssertionError: Some resources were not closed, shutdown, or released. at __randomizedtesting.SeedInfo.seed([5BF726B50F116C88]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:189) at sun.reflect.GeneratedMethodAccessor28.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.testDistribSearch Error Message: Could not get expected value P val for path [response, params, y, p] full output { "responseHeader":{ "status":0, "QTime":0}, "response":{ "znodeVersion":0, "params":{"x":{ "a":"A val", "b":"B val", "":{"v":0}}}}} Stack Trace: java.lang.AssertionError: Could not get expected value P val for path [response, params, y, p] full output { "responseHeader":{ "status":0, "QTime":0}, "response":{ "znodeVersion":0, "params":{"x":{ "a":"A val", "b":"B val", "":{"v":0}}}}} at __randomizedtesting.SeedInfo.seed([5BF726B50F116C88:DA11A8AD784E0CB4]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:259) at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:261) at org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:70) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) at sun.reflect.GeneratedMethodAccessor39.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.util.SimplePostToolTest.testTypeSupported Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([5BF726B50F116C88:CF431056182ECDE7]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.util.SimplePostToolTest.testTypeSupported(SimplePostToolTest.java:116) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9322 lines...] [junit4] Suite: org.apache.solr.cloud.DistribCursorPagingTest [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/init-core-data-001 [junit4] 2> 458119 T2708 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 458119 T2708 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 458122 T2708 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 458123 T2708 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 458123 T2709 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 458223 T2708 oasc.ZkTestServer.run start zk server on port:38958 [junit4] 2> 458224 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 458225 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 458228 T2716 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@582c36 name:ZooKeeperConnection Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 458229 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 458230 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 458230 T2708 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 458236 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 458237 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 458238 T2719 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b315fd name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 458239 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 458240 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 458240 T2708 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 458244 T2708 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 458248 T2708 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 458252 T2708 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 458256 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-deeppaging.xml to /configs/conf1/solrconfig.xml [junit4] 2> 458256 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 458262 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-sorts.xml to /configs/conf1/schema.xml [junit4] 2> 458263 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 458267 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 458267 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 458271 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 458272 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 458275 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 458276 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 458280 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 458280 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 458284 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 458285 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 458289 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 458289 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 458293 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 458294 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 458298 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 458298 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 458302 T2708 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 458303 T2708 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 458309 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 458310 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 458312 T2722 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5cb584 name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 458312 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 458313 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 458551 T2708 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 458683 T2708 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 458686 T2708 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:33115 [junit4] 2> 458688 T2708 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ed5d6 [junit4] 2> 458689 T2708 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 458689 T2708 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-002 [junit4] 2> 458689 T2708 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-002/' [junit4] 2> 458699 T2708 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-002/solr.xml [junit4] 2> 458734 T2708 oasc.CoreContainer.<init> New CoreContainer 4468161 [junit4] 2> 458735 T2708 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-002/] [junit4] 2> 458735 T2708 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 458735 T2708 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 458739 T2708 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 458740 T2708 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 458741 T2708 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 458741 T2708 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 458741 T2708 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38958/solr [junit4] 2> 458741 T2708 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 458742 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 458742 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 458744 T2734 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@31c0ba name:ZooKeeperConnection Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 458744 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 458745 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 458747 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 458748 T2737 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23bca7 name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 458748 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 458750 T2708 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 458755 T2708 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 458758 T2708 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 458762 T2708 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 458765 T2708 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 458770 T2708 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 458772 T2708 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33115_ [junit4] 2> 458773 T2708 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33115_ [junit4] 2> 458777 T2708 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 458779 T2708 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 458782 T2708 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 458784 T2708 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:33115_ [junit4] 2> 458785 T2708 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 458788 T2708 oasc.Overseer.start Overseer (id=93129743307636740-127.0.0.1:33115_-n_0000000000) starting [junit4] 2> 458791 T2708 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 458804 T2708 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 458805 T2739 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 458806 T2708 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 458809 T2708 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 458811 T2708 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 458817 T2738 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 458821 T2741 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 458821 T2741 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 458822 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 458822 T2741 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 458827 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:33115_", [junit4] 2> "base_url":"https://127.0.0.1:33115", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 458829 T2738 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:33115_", [junit4] 2> "base_url":"https://127.0.0.1:33115", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "roles":null} [junit4] 2> 458829 T2738 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 458829 T2738 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 458831 T2737 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> 459823 T2741 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 459824 T2741 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 459825 T2741 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 459825 T2741 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 459826 T2741 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 459827 T2741 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-002/collection1/' [junit4] 2> 459828 T2741 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 459828 T2741 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 459829 T2741 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 459849 T2741 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 459851 T2741 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 459857 T2741 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 459867 T2741 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 459896 T2741 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 459898 T2741 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 459902 T2741 oass.IndexSchema.readSchema [collection1] Schema name=sortable [junit4] 2> 460300 T2741 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 460301 T2741 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 460301 T2741 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 460301 T2741 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-002/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/control/data/ [junit4] 2> 460301 T2741 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 460302 T2741 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/control/data [junit4] 2> 460302 T2741 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/control/data/index/ [junit4] 2> 460302 T2741 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 460303 T2741 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/control/data/index [junit4] 2> 460303 T2741 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5160384261868395] [junit4] 2> 460304 T2741 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12882d6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a5c4b6),segFN=segments_1,generation=1} [junit4] 2> 460304 T2741 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 460304 T2741 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler [junit4] 2> 460308 T2741 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 460308 T2741 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler [junit4] 2> 460310 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 460311 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 460312 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 460313 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 460314 T2741 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 460315 T2741 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 460315 T2741 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 460316 T2741 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=98.9736328125, floorSegmentMB=1.4140625, forceMergeDeletesPctAllowed=2.6684977050046887, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8196520713965341 [junit4] 2> 460316 T2741 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12882d6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a5c4b6),segFN=segments_1,generation=1} [junit4] 2> 460316 T2741 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 460317 T2741 oass.SolrIndexSearcher.<init> Opening Searcher@1671c4c[collection1] main [junit4] 2> 460317 T2741 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 460318 T2741 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 460318 T2741 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 460319 T2741 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 460319 T2741 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 460319 T2741 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 460320 T2741 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 460321 T2741 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 460321 T2741 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 460321 T2741 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 460321 T2742 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1671c4c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 460321 T2741 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 460322 T2745 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:33115 collection:control_collection shard:shard1 [junit4] 2> 460323 T2708 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0 [junit4] 2> 460324 T2708 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 460324 T2745 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 460328 T2745 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 460328 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 460328 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 460328 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 460329 T2745 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 460329 T2745 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1670 name=collection1 org.apache.solr.core.SolrCore@1752fec url=https://127.0.0.1:33115/collection1 node=127.0.0.1:33115_ C1670_STATE=coll:control_collection core:collection1 props:{core=collection1, node_name=127.0.0.1:33115_, base_url=https://127.0.0.1:33115, state=down} [junit4] 2> 460329 T2745 C1670 P33115 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:33115/collection1/ [junit4] 2> 460329 T2745 C1670 P33115 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 460329 T2748 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c33920 name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 460329 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 460329 T2745 C1670 P33115 oasc.SyncStrategy.syncToMe https://127.0.0.1:33115/collection1/ has no replicas [junit4] 2> 460330 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 460330 T2745 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:33115/collection1/ shard1 [junit4] 2> 460330 T2745 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 460330 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 460331 T2708 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 460332 T2708 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 460333 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 460333 T2708 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 460333 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 460333 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:33115", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 460334 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 460334 T2737 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> 460334 T2751 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b69ca8 name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 460334 T2748 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> 460335 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 460336 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 460338 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":2} current state version: 2 [junit4] 2> 460338 T2738 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 460339 T2752 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 460339 T2738 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 460340 T2748 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> 460383 T2745 oasc.ZkController.register We are https://127.0.0.1:33115/collection1/ and leader is https://127.0.0.1:33115/collection1/ [junit4] 2> 460383 T2745 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:33115 [junit4] 2> 460383 T2745 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 460383 T2745 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 460384 T2745 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 460385 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 460386 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "node_name":"127.0.0.1:33115_", [junit4] 2> "base_url":"https://127.0.0.1:33115", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null} current state version: 3 [junit4] 2> 460387 T2738 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "node_name":"127.0.0.1:33115_", [junit4] 2> "base_url":"https://127.0.0.1:33115", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null} [junit4] 2> 460490 T2748 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> 460490 T2737 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> 460552 T2708 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 460552 T2708 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 460556 T2708 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 460558 T2708 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:36453 [junit4] 2> 460560 T2708 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ed5d6 [junit4] 2> 460560 T2708 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 460560 T2708 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-003 [junit4] 2> 460561 T2708 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-003/' [junit4] 2> 460571 T2708 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-003/solr.xml [junit4] 2> 460592 T2708 oasc.CoreContainer.<init> New CoreContainer 4569133 [junit4] 2> 460592 T2708 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-003/] [junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 460595 T2708 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 460595 T2708 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 460596 T2708 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 460598 T2708 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 460598 T2708 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 460598 T2708 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 460599 T2708 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38958/solr [junit4] 2> 460599 T2708 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 460599 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 460600 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 460601 T2764 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d360eb name:ZooKeeperConnection Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 460601 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 460602 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 460604 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 460605 T2767 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9d9660 name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 460605 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 460610 T2708 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 461613 T2708 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36453_ [junit4] 2> 461615 T2708 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36453_ [junit4] 2> 461622 T2708 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 461631 T2768 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 461631 T2768 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 461633 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 461634 T2768 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 461635 T2768 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 461635 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:36453_", [junit4] 2> "base_url":"https://127.0.0.1:36453", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "roles":null} current state version: 4 [junit4] 2> 461636 T2768 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 461637 T2738 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:36453_", [junit4] 2> "base_url":"https://127.0.0.1:36453", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "roles":null} [junit4] 2> 461637 T2738 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 461637 T2768 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 461637 T2738 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 461638 T2768 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 461743 T2738 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 461744 T2767 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 461745 T2767 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 462638 T2768 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 462639 T2768 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 462640 T2768 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 462640 T2768 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 462642 T2768 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 462642 T2768 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-003/collection1/' [junit4] 2> 462643 T2768 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 462643 T2768 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 462644 T2768 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 462660 T2768 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 462663 T2768 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 462669 T2768 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 462680 T2768 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 462700 T2768 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 462702 T2768 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 462706 T2768 oass.IndexSchema.readSchema [collection1] Schema name=sortable [junit4] 2> 462861 T2768 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 462862 T2768 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 462862 T2768 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 462862 T2768 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-003/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty1/ [junit4] 2> 462862 T2768 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 462863 T2768 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty1 [junit4] 2> 462863 T2768 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty1/index/ [junit4] 2> 462863 T2768 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 462864 T2768 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty1/index [junit4] 2> 462864 T2768 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5160384261868395] [junit4] 2> 462865 T2768 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@196cc8d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14f44f9),segFN=segments_1,generation=1} [junit4] 2> 462865 T2768 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 462865 T2768 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 462869 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler [junit4] 2> 462869 T2768 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 462869 T2768 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler [junit4] 2> 462872 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 462873 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 462874 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 462875 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 462876 T2768 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 462877 T2768 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 462877 T2768 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 462877 T2768 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=98.9736328125, floorSegmentMB=1.4140625, forceMergeDeletesPctAllowed=2.6684977050046887, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8196520713965341 [junit4] 2> 462878 T2768 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@196cc8d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14f44f9),segFN=segments_1,generation=1} [junit4] 2> 462878 T2768 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 462878 T2768 oass.SolrIndexSearcher.<init> Opening Searcher@1954cf[collection1] main [junit4] 2> 462878 T2768 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 462880 T2768 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 462880 T2768 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 462881 T2768 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 462881 T2768 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 462881 T2768 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 462882 T2768 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 462882 T2768 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 462882 T2768 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 462883 T2768 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 462883 T2769 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1954cf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 462883 T2768 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 462884 T2772 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:36453 collection:collection1 shard:shard1 [junit4] 2> 462885 T2708 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0 [junit4] 2> 462885 T2708 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 462886 T2772 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 462893 T2772 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 462894 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 462895 T2772 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 462895 T2772 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1671 name=collection1 org.apache.solr.core.SolrCore@8075a8 url=https://127.0.0.1:36453/collection1 node=127.0.0.1:36453_ C1671_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:36453_, base_url=https://127.0.0.1:36453, state=down} [junit4] 2> 462895 T2772 C1671 P36453 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:36453/collection1/ [junit4] 2> 462896 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 462896 T2772 C1671 P36453 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 462896 T2772 C1671 P36453 oasc.SyncStrategy.syncToMe https://127.0.0.1:36453/collection1/ has no replicas [junit4] 2> 462897 T2772 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:36453/collection1/ shard1 [junit4] 2> 462897 T2772 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 462897 T2738 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 462898 T2767 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 462899 T2767 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 462901 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"https://127.0.0.1:36453", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 462902 T2738 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 462902 T2767 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 462903 T2767 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 462904 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 462951 T2772 oasc.ZkController.register We are https://127.0.0.1:36453/collection1/ and leader is https://127.0.0.1:36453/collection1/ [junit4] 2> 462951 T2772 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:36453 [junit4] 2> 462951 T2772 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 462951 T2772 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 462951 T2772 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 462952 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 462953 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "node_name":"127.0.0.1:36453_", [junit4] 2> "base_url":"https://127.0.0.1:36453", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null} current state version: 4 [junit4] 2> 462954 T2738 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "node_name":"127.0.0.1:36453_", [junit4] 2> "base_url":"https://127.0.0.1:36453", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null} [junit4] 2> 462955 T2738 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 462955 T2772 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 462955 T2767 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 462956 T2767 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 463108 T2708 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 463108 T2708 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 463112 T2708 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] [junit4] 2> 463114 T2708 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:60319 [junit4] 2> 463116 T2708 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ed5d6 [junit4] 2> 463116 T2708 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 463117 T2708 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-004 [junit4] 2> 463117 T2708 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-004/' [junit4] 2> 463127 T2708 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-004/solr.xml [junit4] 2> 463146 T2708 oasc.CoreContainer.<init> New CoreContainer 24846494 [junit4] 2> 463147 T2708 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-004/] [junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 463150 T2708 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 463151 T2708 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 463153 T2708 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 463153 T2708 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 463153 T2708 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 463153 T2708 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38958/solr [junit4] 2> 463154 T2708 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 463154 T2708 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 463155 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 463156 T2784 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fc4a9a name:ZooKeeperConnection Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 463157 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 463157 T2708 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 463159 T2708 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 463160 T2787 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fa3f6 name:ZooKeeperConnection Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 463160 T2708 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 463167 T2708 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 464172 T2708 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60319_ [junit4] 2> 464173 T2708 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60319_ [junit4] 2> 464176 T2708 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 464181 T2788 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 464181 T2788 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 464182 T2737 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 464183 T2788 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 464183 T2788 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 464183 T2738 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:60319_", [junit4] 2> "base_url":"https://127.0.0.1:60319", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "roles":null} current state version: 4 [junit4] 2> 464183 T2788 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 464183 T2738 oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:60319_", [junit4] 2> "base_url":"https://127.0.0.1:60319", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "roles":null} [junit4] 2> 464184 T2738 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 464184 T2738 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 464184 T2788 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 464184 T2788 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 464184 T2738 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 464185 T2767 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 464185 T2787 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 464185 T2767 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 464185 T2787 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 465184 T2788 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 465185 T2788 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 465185 T2788 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 465185 T2788 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 465187 T2788 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 465187 T2788 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-004/collection1/' [junit4] 2> 465187 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 465188 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 465188 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 465198 T2788 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 465200 T2788 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 465205 T2788 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 465221 T2788 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 465242 T2788 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 465244 T2788 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 465249 T2788 oass.IndexSchema.readSchema [collection1] Schema name=sortable [junit4] 2> 465387 T2788 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 465388 T2788 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 465388 T2788 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 465388 T2788 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-004/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty2/ [junit4] 2> 465388 T2788 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1 [junit4] 2> 465389 T2788 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty2 [junit4] 2> 465389 T2788 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty2/index/ [junit4] 2> 465390 T2788 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 465390 T2788 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest 5BF726B50F116C88-001/tempDir-001/jetty2/index [junit4] 2> 465391 T2788 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5160384261868395] [junit4] 2> 465391 T2788 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@86bca7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cbb0a1),segFN=segments_1,generation=1} [junit4] 2> 465391 T2788 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 465392 T2788 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 465392 T2788 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 465396 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler [junit4] 2> 465396 T2788 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 465396 T2788 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler [junit4] 2> 465398 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 465399 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 465400 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 465401 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 465402 T2788 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 465403 T2788 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 465403 T2788 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 465404 T2788 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=98.9736328125, floorSegmentMB=1.4140625, forceMergeDeletesPctAllowed=2.6684977050046887, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8196520713965341 [junit4] 2> 465404 T2788 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@86bca7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cbb0a1),segFN=segments_1,generation=1} [junit4] 2> 465404 T2788 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 465404 T2788 oass.SolrIndexSearcher.<init> Opening Searcher@7dcd05[collection1] main [junit4] 2> 465405 T2788 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 465406 T2788 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 465406 T2788 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 465407 T2788 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 465407 T2788 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 465407 T2788 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 465408 T2788 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 465408 T2788 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 465409 [...truncated too long message...] 01/tempDir-001/jetty4/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud 5BF726B50F116C88-001/tempDir-001/jetty4/index;done=false>>] [junit4] 2> 1833161 T10671 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud 5BF726B50F116C88-001/tempDir-001/jetty4/index [junit4] 2> 1833163 T10671 oasc.Overseer.close Overseer (id=93129830915899406-127.0.0.1:42295_-n_0000000004) closing [junit4] 2> 1833163 T10893 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:42295_ [junit4] 2> 1834663 T10885 oascc.ZkStateReader$7.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 1834664 T10886 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK [junit4] 2> 1834686 T10671 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null} [junit4] 2> 1834875 T10671 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch [junit4] 2> 1834876 T10671 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:42970 42970 [junit4] 2> 1835582 T10672 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:42970 42970 [junit4] 2> 1835584 T10672 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 50 /solr/configs/conf1 [junit4] 2> 6 /solr/aliases.json [junit4] 2> 6 /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> 6 /solr/live_nodes [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 4 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=testDistribSearch -Dtests.seed=5BF726B50F116C88 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_ME -Dtests.timezone=Asia/Yekaterinburg -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 40.7s J0 | TestSolrConfigHandlerCloud.testDistribSearch <<< [junit4] > Throwable #1: java.lang.AssertionError: Could not get expected value P val for path [response, params, y, p] full output { [junit4] > "responseHeader":{ [junit4] > "status":0, [junit4] > "QTime":0}, [junit4] > "response":{ [junit4] > "znodeVersion":0, [junit4] > "params":{"x":{ [junit4] > "a":"A val", [junit4] > "b":"B val", [junit4] > "":{"v":0}}}}} [junit4] > at __randomizedtesting.SeedInfo.seed([5BF726B50F116C88:DA11A8AD784E0CB4]:0) [junit4] > at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:259) [junit4] > at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:261) [junit4] > at org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:70) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1835591 T10671 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud 5BF726B50F116C88-001 [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=sr_ME, timezone=Asia/Yekaterinburg [junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.7.0_72 (32-bit)/cpus=8,threads=1,free=156880232,total=440401920 [junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, ShardRoutingTest, TestExtendedDismaxParser, DistanceFunctionTest, SimpleFacetsTest, DateMathParserTest, UnloadDistributedZkTest, TestReplicationHandlerBackup, CoreAdminHandlerTest, PathHierarchyTokenizerFactoryTest, TestLMDirichletSimilarityFactory, TestStressRecovery, NoCacheHeaderTest, TestRequestStatusCollectionAPI, TestAnalyzeInfixSuggestions, DistributedQueryElevationComponentTest, AddBlockUpdateTest, CachingDirectoryFactoryTest, ResourceLoaderTest, CoreAdminCreateDiscoverTest, DistributedQueueTest, SolrPluginUtilsTest, TestBlendedInfixSuggestions, TestDefaultStatsCache, SuggestComponentTest, TestCollationField, BasicDistributedZkTest, TestShardHandlerFactory, SolrInfoMBeanTest, SolrIndexConfigTest, DistributedFacetPivotSmallAdvancedTest, TestSolrCoreProperties, TestExceedMaxTermLength, TestSerializedLuceneMatchVersion, HdfsSyncSliceTest, SolrCoreCheckLockOnStartupTest, DeleteReplicaTest, TestReplicaProperties, XmlUpdateRequestHandlerTest, FacetPivotSmallTest, UUIDFieldTest, TestDynamicLoading, OpenExchangeRatesOrgProviderTest, TestCloudSchemaless, HdfsWriteToMultipleCollectionsTest, PreAnalyzedFieldTest, HdfsCollectionsAPIDistributedZkTest, DistribCursorPagingTest, CoreMergeIndexesAdminHandlerTest, AlternateDirectoryTest, TestFunctionQuery, TestSolr4Spatial2, TestRawResponseWriter, TestWordDelimiterFilterFactory, CoreContainerCoreInitFailuresTest, EnumFieldTest, HdfsLockFactoryTest, SolrCoreTest, FieldAnalysisRequestHandlerTest, TestSchemaManager, MinimalSchemaTest, DistributedFacetPivotLongTailTest, TestChildDocTransformer, TestFieldCollectionResource, TestSolrXmlPersistor, ZkControllerTest, SystemInfoHandlerTest, TestGroupingSearch, TestNRTOpen, TestCursorMarkWithoutUniqueKey, TestCoreContainer, TestCSVLoader, HardAutoCommitTest, AnalysisAfterCoreReloadTest, TestUpdate, TestOverriddenPrefixQueryForCustomFieldType, TestStressLucene, TestUniqueKeyFieldResource, TestReloadAndDeleteDocs, SuggesterWFSTTest, TestFastWriter, TriLevelCompositeIdRoutingTest, HdfsBasicDistributedZkTest, TestSolrDynamicMBean, TestFieldTypeResource, TestDocBasedVersionConstraints, TestSweetSpotSimilarityFactory, TestIndexSearcher, SuggesterFSTTest, TestSchemaResource, TestBinaryResponseWriter, DistribDocExpirationUpdateProcessorTest, TestComplexPhraseQParserPlugin, TestBlobHandler, TestManagedResourceStorage, TestExactStatsCache, RequestHandlersTest, TestExactSharedStatsCache, PrimUtilsTest, TestJmxIntegration, HdfsChaosMonkeySafeLeaderTest, TestCloudManagedSchemaConcurrent, TestQueryTypes, BadCopyFieldTest, TestStressReorder, SimpleMLTQParserTest, TestRandomDVFaceting, NumericFieldsTest, DeleteInactiveReplicaTest, TestComponentsName, OutOfBoxZkACLAndCredentialsProvidersTest, TestManagedResource, RecoveryZkTest, ClusterStateUpdateTest, LeaderElectionTest, TestFaceting, TestHashPartitioner, TestStressVersions, PeerSyncTest, ConvertedLegacyTest, TestFiltering, ShowFileRequestHandlerTest, SolrIndexSplitterTest, SuggesterTest, SpatialFilterTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, TestRemoteStreaming, CacheHeaderTest, LukeRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, TestValueSourceCache, TestIndexingPerformance, TestArbitraryIndexDir, IndexSchemaTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestAnalyzedSuggestions, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, HighlighterConfigTest, TestQuerySenderListener, TestQuerySenderNoQuery, CopyFieldTest, TestSolrDeletionPolicy2, SampleTest, TestBinaryField, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestPhraseSuggestions, SynonymTokenizerTest, TestDFRSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, LegacyHTMLStripCharFilterTest, ScriptEngineTest, PluginInfoTest, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, SliceStateTest, FileUtilsTest, TestRTGBase, SolrTestCaseJ4Test, TestDistributedMissingSort, TestHighlightDedupGrouping, AliasIntegrationTest, ConnectionManagerTest, CustomCollectionTest, ExternalCollectionsTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, MultiThreadedOCPTest, OverseerStatusTest, RollingRestartTest, SSLMigrationTest, ShardSplitTest, TestSolrConfigHandlerCloud] [junit4] Completed on J0 in 40.71s, 1 test, 1 failure <<< FAILURES! [...truncated 152 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970: There were test failures: 460 suites, 1871 tests, 1 suite-level error, 2 failures, 47 ignored (22 assumptions) Total time: 64 minutes 45 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.7.0_72 -server -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