Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4354/ Java: 32bit/jdk1.8.0_40-ea-b04 -server -XX:+UseParallelGC
1 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch Error Message: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 at __randomizedtesting.SeedInfo.seed([35C691C0BDEDB058:B4201FD8CAB2D064]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:570) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.GeneratedMethodAccessor54.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11526 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\init-core-data-001 [junit4] 2> 3521013 T9981 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true) [junit4] 2> 3521013 T9981 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 3521020 T9981 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 3521021 T9981 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3521022 T9982 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3521162 T9981 oasc.ZkTestServer.run start zk server on port:60745 [junit4] 2> 3521166 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3521171 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3521177 T9988 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f8df37 name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3521177 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3521177 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3521177 T9981 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3521187 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3521190 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3521191 T9990 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13f64ad name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3521193 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3521193 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3521193 T9981 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3521199 T9981 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3521204 T9981 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3521208 T9981 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3521213 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3521215 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3521227 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4] 2> 3521227 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3521236 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3521238 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3521246 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3521246 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3521254 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3521254 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3521261 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 3521261 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3521267 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3521267 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3521274 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3521275 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3521279 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3521280 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3521285 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3521286 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3521291 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3521291 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> Using old style solr.xml [junit4] 2> 3521300 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3521302 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3521304 T9992 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11b3aca name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3521305 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3521305 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3521305 T9981 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3521309 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3521312 T9983 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148de0519ff0002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3521317 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3521320 T9994 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@193fed2 name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3521320 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3521322 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3521323 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 3521324 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml [junit4] 2> 3521331 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf2/schema.xml [junit4] 2> 3521333 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml [junit4] 2> 3521339 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3521339 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3521346 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 3521346 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt [junit4] 2> 3521352 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 3521352 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt [junit4] 2> 3521359 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf2/currency.xml [junit4] 2> 3521359 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml [junit4] 2> 3521364 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 3521364 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 3521370 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 3521370 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 3521375 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 3521375 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 3521381 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 3521381 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt [junit4] 2> 3521386 T9981 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 3521386 T9981 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml [junit4] 2> 3524378 T9981 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3524416 T9981 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60758 [junit4] 2> 3524417 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3524417 T9981 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3524417 T9981 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002 [junit4] 2> 3524419 T9981 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\' [junit4] 2> 3524477 T9981 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\solr.xml [junit4] 2> 3524501 T9981 oasc.CoreContainer.<init> New CoreContainer 24339068 [junit4] 2> 3524501 T9981 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\] [junit4] 2> 3524503 T9981 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000 [junit4] 2> 3524503 T9981 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 3524504 T9981 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3524504 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3524504 T9981 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3524505 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3524505 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3524505 T9981 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3524507 T9981 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3524507 T9981 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=3400000&connTimeout=45000&retry=false [junit4] 2> 3524507 T9981 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3524508 T9981 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3524508 T9981 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3524509 T9981 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60745/solr [junit4] 2> 3524509 T9981 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3524509 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3524512 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3524515 T10005 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17d95d4 name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3524516 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3524516 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3524522 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3524523 T10007 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ace800 name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3524525 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3524526 T9981 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3524533 T9981 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3524538 T9981 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3524544 T9981 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3524551 T9981 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3524556 T9981 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3524559 T9981 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60758_ [junit4] 2> 3524560 T9981 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60758_ [junit4] 2> 3524570 T9981 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3524573 T9981 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3524577 T9981 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3524579 T9981 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:60758_ [junit4] 2> 3524581 T9981 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3524585 T9981 oasc.Overseer.start Overseer (id=92567905853440005-127.0.0.1:60758_-n_0000000000) starting [junit4] 2> 3524589 T9981 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3524606 T9981 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3524607 T10009 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3524608 T9981 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3524612 T9981 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3524615 T9981 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3524623 T10008 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3524625 T9981 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3524626 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3524628 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3524629 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3524632 T10013 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f3ded5 name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3524632 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3524632 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3524634 T9981 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3524643 T9981 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3524643 T9981 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 3524643 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3524647 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3524650 T10015 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@453775 name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3524650 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3524650 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3524656 T10016 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3524666 T10008 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 3524667 T10008 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 3524667 T10008 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 3524673 T10008 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 3524677 T10016 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> 3524688 T10017 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> 3527339 T9981 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3527340 T9981 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3527347 T9981 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60773 [junit4] 2> 3527350 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3527350 T9981 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3527351 T9981 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-003 [junit4] 2> 3527351 T9981 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-003\' [junit4] 2> 3527405 T9981 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-003\solr.xml [junit4] 2> 3527431 T9981 oasc.CoreContainer.<init> New CoreContainer 5881953 [junit4] 2> 3527431 T9981 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-003\] [junit4] 2> 3527434 T9981 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000 [junit4] 2> 3527434 T9981 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 3527435 T9981 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3527435 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3527435 T9981 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3527435 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3527438 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3527438 T9981 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3527438 T9981 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3527438 T9981 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=3400000&connTimeout=45000&retry=false [junit4] 2> 3527438 T9981 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3527438 T9981 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3527441 T9981 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3527441 T9981 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60745/solr [junit4] 2> 3527441 T9981 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3527441 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3527446 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3527450 T10028 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c0687b name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3527450 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3527452 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3527454 T9983 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148de0519ff0008, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3527457 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3527461 T10030 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@299f9a name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3527463 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3527479 T9981 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3528840 T9981 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60773_ [junit4] 2> 3528841 T9981 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60773_ [junit4] 2> 3528850 T9981 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3528859 T9981 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3528859 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3531198 T9981 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3531199 T9981 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3531206 T9981 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60782 [junit4] 2> 3531208 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3531208 T9981 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3531209 T9981 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004 [junit4] 2> 3531210 T9981 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\' [junit4] 2> 3531263 T9981 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solr.xml [junit4] 2> 3531279 T9981 oasc.CoreContainer.<init> New CoreContainer 847623 [junit4] 2> 3531281 T9981 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\] [junit4] 2> 3531281 T9981 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000 [junit4] 2> 3531282 T9981 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 3531282 T9981 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3531282 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3531283 T9981 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3531283 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3531283 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3531284 T9981 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3531284 T9981 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3531286 T9981 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=3400000&connTimeout=45000&retry=false [junit4] 2> 3531286 T9981 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3531286 T9981 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3531287 T9981 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3531287 T9981 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60745/solr [junit4] 2> 3531287 T9981 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3531288 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3531290 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3531292 T10043 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1101c9d name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3531293 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3531293 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3531297 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3531298 T10045 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@186e34 name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3531299 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3531310 T9981 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3532585 T9981 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60782_ [junit4] 2> 3532587 T9981 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60782_ [junit4] 2> 3532597 T9981 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3532603 T9981 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3532603 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3534888 T9981 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 3534889 T9981 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3534895 T9981 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60791 [junit4] 2> 3534896 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3534897 T9981 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3534897 T9981 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005 [junit4] 2> 3534899 T9981 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\' [junit4] 2> 3534951 T9981 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solr.xml [junit4] 2> 3534986 T9981 oasc.CoreContainer.<init> New CoreContainer 1304946 [junit4] 2> 3534986 T9981 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\] [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3534990 T9981 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3534990 T9981 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=3400000&connTimeout=45000&retry=false [junit4] 2> 3534994 T9981 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3534994 T9981 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3534994 T9981 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3534994 T9981 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60745/solr [junit4] 2> 3534994 T9981 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3534994 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3535001 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3535005 T10058 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4eca20 name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3535005 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3535006 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3535014 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3535017 T10060 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47bf9 name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3535017 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3535031 T9981 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3536310 T9981 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60791_ [junit4] 2> 3536314 T9981 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60791_ [junit4] 2> 3536333 T9981 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3536343 T9981 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3536343 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3538694 T9981 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4 [junit4] 2> 3538695 T9981 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 3538700 T9981 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60800 [junit4] 2> 3538702 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 3538703 T9981 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3538703 T9981 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006 [junit4] 2> 3538705 T9981 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\' [junit4] 2> 3538748 T9981 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solr.xml [junit4] 2> 3538764 T9981 oasc.CoreContainer.<init> New CoreContainer 9083530 [junit4] 2> 3538764 T9981 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\] [junit4] 2> 3538766 T9981 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000 [junit4] 2> 3538766 T9981 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 3538766 T9981 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3538768 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3538768 T9981 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3538768 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3538769 T9981 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3538769 T9981 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3538770 T9981 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3538770 T9981 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=3400000&connTimeout=45000&retry=false [junit4] 2> 3538771 T9981 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3538771 T9981 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3538771 T9981 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 3538773 T9981 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60745/solr [junit4] 2> 3538773 T9981 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3538774 T9981 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3538775 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3538778 T10073 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f73f70 name:ZooKeeperConnection Watcher:127.0.0.1:60745 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3538778 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3538779 T9981 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3538782 T9981 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3538784 T10075 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5e790c name:ZooKeeperConnection Watcher:127.0.0.1:60745/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3538785 T9981 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3538796 T9981 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3540071 T9981 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60800_ [junit4] 2> 3540073 T9981 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60800_ [junit4] 2> 3540085 T9981 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3540091 T9981 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3540091 T9981 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3540097 T10022 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&collection.configName=conf1&router.field=myOwnField&name=solrj_collection&action=CREATE&numShards=2&wt=javabin&version=2 [junit4] 2> 3540099 T10016 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged [junit4] 2> 3540101 T10009 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "operation":"create", [junit4] 2> "fromApi":"true", [junit4] 2> "name":"solrj_collection", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "router.field":"myOwnField"} [junit4] 2> 3540102 T10078 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : create , { [junit4] 2> "operation":"create", [junit4] 2> "fromApi":"true", [junit4] 2> "name":"solrj_collection", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "router.field":"myOwnField"} [junit4] 2> 3540106 T10078 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection [junit4] 2> 3540106 T10078 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection [junit4] 2> 3540113 T10016 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3540122 T10008 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection [junit4] 2> 3540122 T10008 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2] [junit4] 2> 3540122 T10008 oasc.Overseer$ClusterStateUpdater.createCollection state version solrj_collection 1 [junit4] 2> 3540127 T10017 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540127 T10076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540127 T10031 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540128 T10046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540127 T10061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540129 T10016 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540242 T10078 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection solrj_collection, shardNames [shard1, shard2] , replicationFactor : 2 [junit4] 2> 3540242 T10078 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:60758_ [junit4] 2> 3540244 T10078 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:60800_ [junit4] 2> 3540246 T10078 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:60791_ [junit4] 2> 3540247 T9998 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard1_replica1&action=CREATE&numShards=2&collection=solrj_collection&shard=shard1&wt=javabin&version=2 [junit4] 2> 3540249 T10069 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard1_replica2&action=CREATE&numShards=2&collection=solrj_collection&shard=shard1&wt=javabin&version=2 [junit4] 2> 3540251 T10078 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:60782_ [junit4] 2> 3540254 T9998 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down collection=solrj_collection [junit4] 2> 3540257 T10055 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard2_replica1&action=CREATE&numShards=2&collection=solrj_collection&shard=shard2&wt=javabin&version=2 [junit4] 2> 3540258 T10041 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=solrj_collection_shard2_replica2&action=CREATE&numShards=2&collection=solrj_collection&shard=shard2&wt=javabin&version=2 [junit4] 2> 3540259 T9998 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3540259 T10069 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down collection=solrj_collection [junit4] 2> 3540260 T10016 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3540262 T10069 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3540262 T10055 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down collection=solrj_collection [junit4] 2> 3540263 T10041 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down collection=solrj_collection [junit4] 2> 3540265 T10055 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3540267 T10041 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3540270 T10008 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"solrj_collection_shard1_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60758", [junit4] 2> "node_name":"127.0.0.1:60758_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3540276 T10008 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"solrj_collection_shard1_replica2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60800", [junit4] 2> "node_name":"127.0.0.1:60800_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3540280 T10016 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3540283 T10008 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"solrj_collection_shard2_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60791", [junit4] 2> "node_name":"127.0.0.1:60791_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3540286 T10016 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 3540288 T10008 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "core":"solrj_collection_shard2_replica2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60782", [junit4] 2> "node_name":"127.0.0.1:60782_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"solrj_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3540421 T10061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540421 T10017 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540421 T10031 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540421 T10046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540421 T10016 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3540421 T10076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 3541465 T9998 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1 [junit4] 2> 3541465 T9998 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 3541467 T9998 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3541467 T9998 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 3541468 T10069 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2 [junit4] 2> 3541468 T10069 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 3541470 T10055 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1 [junit4] 2> 3541470 T10055 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 3541472 T10041 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2 [junit4] 2> 3541473 T10041 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection [junit4] 2> 3541472 T9998 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3541475 T10055 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3541472 T10069 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3541476 T10069 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 3541475 T10055 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 3541475 T9998 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\solrj_collection_shard1_replica1\' [junit4] 2> 3541476 T10041 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3541478 T10041 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 3541483 T10069 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3541484 T10069 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\' [junit4] 2> 3541495 T10055 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3541495 T10055 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\' [junit4] 2> 3541496 T10041 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3541497 T10041 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\' [junit4] 2> 3541566 T9998 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3541583 T9998 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3541589 T10041 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3541600 T10069 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3541604 T10041 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3541615 T10069 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3541618 T9998 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3541648 T10055 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3541648 T9998 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test [junit4] 2> 3541663 T10055 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3541665 T10041 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3541675 T10041 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test [junit4] 2> 3541746 T10055 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3541757 T10055 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test [junit4] 2> 3541814 T9998 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3541823 T9998 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3541825 T9998 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3541863 T10069 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 3541872 T10069 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test [junit4] 2> 3541916 T10055 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3541922 T10055 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3541924 T10055 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3541997 T10069 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3541999 T10041 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3542001 T10069 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3542002 T10041 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3542002 T10069 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3542002 T10041 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3542033 T10041 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542038 T9998 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542040 T10041 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542043 T9998 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542044 T10041 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542044 T10069 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542045 T10055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542048 T10041 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542052 T10041 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542052 T10041 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542054 T9998 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542054 T10069 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542058 T10041 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542058 T10041 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542058 T10041 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica2' using configuration from collection solrj_collection [junit4] 2> 3542058 T10041 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3542059 T10041 oasc.SolrCore.<init> [solrj_collection_shard2_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\data\ [junit4] 2> 3542060 T10041 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19028 [junit4] 2> 3542060 T9998 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542060 T9998 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542060 T10041 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\data\ [junit4] 2> 3542059 T10055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3542060 T9998 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542061 T10041 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\data\index/ [junit4] 2> 3542062 T10041 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\data\index' doesn't exist. Creating new index... [junit4] 2> 3542063 T10041 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-004\solrj_collection_shard2_replica2\data\index [junit4] 2> 3542063 T10041 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=1.4404296875, floorSegmentMB=0.703125, forceMergeDeletesPctAllowed=22.45054876096744, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2249908444663974 [junit4] 2> 3542064 T10069 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542069 T9998 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542069 T9998 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542069 T9998 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using configuration from collection solrj_collection [junit4] 2> 3542069 T9998 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3542070 T9998 oasc.SolrCore.<init> [solrj_collection_shard1_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\solrj_collection_shard1_replica1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\solrj_collection_shard1_replica1\data\ [junit4] 2> 3542070 T9998 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19028 [junit4] 2> 3542070 T10041 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-865b70)),segFN=segments_1,generation=1} [junit4] 2> 3542070 T10041 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3542070 T10055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542071 T9998 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-002\solrj_collection_shard1_replica1\data\ [junit4] 2> 3542070 T10069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542073 T10069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542073 T10069 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542075 T10041 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3542077 T10041 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3542077 T10041 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3542078 T10041 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3542078 T10041 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3542079 T10041 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3542079 T10041 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3542080 T10041 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3542080 T10041 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3542080 T10041 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542081 T10041 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542081 T10041 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542082 T10041 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3542082 T10041 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3542082 T10041 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3542083 T10041 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3542079 T10069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542102 T10069 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542102 T10069 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using configuration from collection solrj_collection [junit4] 2> 3542102 T10069 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3542104 T10069 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\data\ [junit4] 2> 3542106 T10069 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19028 [junit4] 2> 3542107 T10069 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\data\ [junit4] 2> 3542079 T10055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542108 T10055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542108 T10055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3542110 T10069 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\data\index/ [junit4] 2> 3542110 T10069 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica2] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\data\index' doesn't exist. Creating new index... [junit4] 2> 3542111 T10069 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-006\solrj_collection_shard1_replica2\data\index [junit4] 2> 3542112 T10069 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=1.4404296875, floorSegmentMB=0.703125, forceMergeDeletesPctAllowed=22.45054876096744, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2249908444663974 [junit4] 2> 3542113 T10069 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-5b13a9)),segFN=segments_1,generation=1} [junit4] 2> 3542113 T10069 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3542119 T10069 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3542120 T10069 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3542120 T10069 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3542121 T10069 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3542121 T10069 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3542121 T10069 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3542122 T10069 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3542123 T10069 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3542123 T10069 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3542124 T10069 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542124 T10069 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542126 T10069 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542127 T10069 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3542128 T10069 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3542128 T10069 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3542128 T10069 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3542129 T10069 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3542129 T10069 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3542130 T10069 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3542130 T10069 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3542137 T10069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542139 T10069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542141 T10069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542142 T10069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542146 T10069 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3542146 T10069 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3542148 T10069 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=96.25, floorSegmentMB=1.1533203125, forceMergeDeletesPctAllowed=15.87222114473133, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3542149 T10069 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-5b13a9)),segFN=segments_1,generation=1} [junit4] 2> 3542149 T10069 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3542149 T10069 oass.SolrIndexSearcher.<init> Opening Searcher@ad245b[solrj_collection_shard1_replica2] main [junit4] 2> 3542149 T10069 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection [junit4] 2> 3542154 T10055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3542154 T10055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3542154 T10055 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using configuration from collection solrj_collection [junit4] 2> 3542154 T10055 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3542156 T10055 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\data\ [junit4] 2> 3542156 T10055 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19028 [junit4] 2> 3542157 T10055 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\data\ [junit4] 2> 3542157 T10055 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\data\index/ [junit4] 2> 3542157 T10055 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\data\index' doesn't exist. Creating new index... [junit4] 2> 3542158 T10055 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001\tempDir-005\solrj_collection_shard2_replica1\data\index [junit4] 2> 3542159 T10055 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=1.4404296875, floorSegmentMB=0.703125, forceMergeDeletesPctAllowed=22.45054876096744, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2249908444663974 [junit4] 2> 3542159 T10055 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1d31f62)),segFN=segments_1,generation=1} [junit4] 2> 3542159 T10055 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3542163 T10055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3542163 T10055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3542164 T10055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3542164 T10055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3542164 T10055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3542166 T10055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3542166 T10055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3542166 T10055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3542166 T10055 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3542168 T10055 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542168 T10055 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542168 T10055 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3542169 T10055 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3542169 T10055 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 3542169 T10055 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 3542169 T10055 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 3542170 T10055 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 3542171 T10055 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 3542172 T10055 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3542172 T10055 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 3542174 T10055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542176 T10055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542178 T10055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542179 T10055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3542183 T10055 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3542183 T10055 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3542184 T10055 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=96. [...truncated too long message...] unit4] 2> 5 /solr/collections/awholynewstresscollection_collection1_0/state.json [junit4] 2> 5 /solr/collections/awholynewstresscollection_collection0_0/state.json [junit4] 2> 4 /solr/aliases.json [junit4] 2> 4 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/awholynewcollection_2/state.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection1_0/leader_elect/shard3/election/92567905853440015-core_node10-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 4 /solr/live_nodes [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=35C691C0BDEDB058 -Dtests.slow=true -Dtests.locale=ru_RU -Dtests.timezone=America/Winnipeg -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 869s | CollectionsAPIDistributedZkTest.testDistribSearch <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 [junit4] > at __randomizedtesting.SeedInfo.seed([35C691C0BDEDB058:B4201FD8CAB2D064]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:570) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 4390109 T9981 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-35C691C0BDEDB058-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=FST41, _version_=FST41, multiDefault=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Memory doPackFST= true), intDefault=FST41, a_i1=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=Memory doPackFST= false), text=PostingsFormat(name=MockRandom), range_facet_l=PostingsFormat(name=Memory doPackFST= true), timestamp=PostingsFormat(name=Memory doPackFST= true)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, locale=ru_RU, timezone=America/Winnipeg [junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40-ea (32-bit)/cpus=2,threads=1,free=75124624,total=436731904 [junit4] 2> NOTE: All tests run in this JVM: [ExternalCollectionsTest, StatelessScriptUpdateProcessorFactoryTest, TestBinaryField, MultiThreadedOCPTest, TestUtils, TestModifyConfFiles, TestPartialUpdateDeduplication, TestRandomDVFaceting, TestStressUserVersions, TestSolrXmlPersistence, SuggesterTest, PreAnalyzedUpdateProcessorTest, CacheHeaderTest, TestSolrDeletionPolicy2, TestFieldSortValues, HdfsBasicDistributedZkTest, SOLR749Test, TestIndexSearcher, TestStressRecovery, SharedFSAutoReplicaFailoverTest, TestSweetSpotSimilarityFactory, DeleteInactiveReplicaTest, MBeansHandlerTest, PreAnalyzedFieldTest, DistributedFacetPivotLargeTest, FieldMutatingUpdateProcessorTest, TestFieldCollectionResource, PolyFieldTest, TestNonNRTOpen, TestBM25SimilarityFactory, RegexBoostProcessorTest, BadComponentTest, TestCollapseQParserPlugin, AlternateDirectoryTest, StressHdfsTest, FieldAnalysisRequestHandlerTest, ExternalFileFieldSortTest, UpdateParamsTest, TestJmxMonitoredMap, BasicDistributedZk2Test, TestCollationFieldDocValues, TestCloudManagedSchemaConcurrent, SolrIndexSplitterTest, TestSolrJ, CoreAdminHandlerTest, MigrateRouteKeyTest, AddBlockUpdateTest, DocExpirationUpdateProcessorFactoryTest, TestDynamicFieldResource, IndexSchemaTest, CoreMergeIndexesAdminHandlerTest, SuggesterTSTTest, TestSolr4Spatial, ShardRoutingCustomTest, DocumentBuilderTest, SolrCoreTest, CustomCollectionTest, TestCloudInspectUtil, CloudExitableDirectoryReaderTest, ResponseHeaderTest, TestSolrQueryParserResource, TestManagedSchema, TestJmxIntegration, TermVectorComponentDistributedTest, BJQParserTest, TestQueryUtils, PingRequestHandlerTest, TestManagedSynonymFilterFactory, SchemaVersionSpecificBehaviorTest, LegacyHTMLStripCharFilterTest, TestOmitPositions, ZkSolrClientTest, LeaderInitiatedRecoveryOnCommitTest, TestUpdate, ZkNodePropsTest, BasicZkTest, TestManagedStopFilterFactory, TestManagedSchemaDynamicFieldResource, IndexBasedSpellCheckerTest, AnalysisErrorHandlingTest, BadIndexSchemaTest, TestAnalyzeInfixSuggestions, HdfsDirectoryTest, TestPostingsSolrHighlighter, BasicDistributedZkTest, BasicFunctionalityTest, MoreLikeThisHandlerTest, TestCharFilters, TestCoreDiscovery, SortByFunctionTest, OpenCloseCoreStressTest, ConnectionManagerTest, TestQuerySenderNoQuery, TestDistributedMissingSort, ReplicationFactorTest, TestSolrXmlPersistor, DateFieldTest, ScriptEngineTest, BinaryUpdateRequestHandlerTest, TestPivotHelperCode, TestRandomMergePolicy, EchoParamsTest, TestManagedResourceStorage, TestLFUCache, TestStressLucene, TestXIncludeConfig, XmlUpdateRequestHandlerTest, UnloadDistributedZkTest, TestCodecSupport, TestDFRSimilarityFactory, MultiTermTest, ZkCLITest, TestDistribDocBasedVersion, TestCollectionAPI, AsyncMigrateRouteKeyTest, TestRequestStatusCollectionAPI, TestConfigSets, TestReload, UniqFieldsUpdateProcessorFactoryTest, TestSchemaSimilarityResource, QueryEqualityTest, NoCacheHeaderTest, TestFieldTypeCollectionResource, CachingDirectoryFactoryTest, TestInitQParser, OutputWriterTest, SolrPluginUtilsTest, UUIDUpdateProcessorFallbackTest, TestFoldingMultitermQuery, DistributedQueryElevationComponentTest, TestRangeQuery, TestOverriddenPrefixQueryForCustomFieldType, EnumFieldTest, TestRecoveryHdfs, SolrCmdDistributorTest, DistributedFacetPivotSmallTest, LeaderFailoverAfterPartitionTest, DisMaxRequestHandlerTest, TestConfig, SampleTest, TestQueryTypes, TestIndexingPerformance, TestFiltering, TestAddFieldRealTimeGet, SSLMigrationTest, TestFastWriter, TestQuerySenderListener, TestHashPartitioner, TestDefaultSearchFieldResource, TestSolrQueryParserDefaultOperatorResource, ChangedSchemaMergeTest, TestExtendedDismaxParser, DistributedFacetPivotLongTailTest, TestStressVersions, CoreContainerCoreInitFailuresTest, TestCloudSchemaless, PathHierarchyTokenizerFactoryTest, TestSolrQueryParser, QueryElevationComponentTest, TestBlendedInfixSuggestions, TestSolrIndexConfig, SpellCheckComponentTest, TestComponentsName, VMParamsZkACLAndCredentialsProvidersTest, TestDefaultSimilarityFactory, TestRawResponseWriter, JSONWriterTest, TestNoOpRegenerator, HdfsUnloadDistributedZkTest, SolrTestCaseJ4Test, CurrencyFieldOpenExchangeTest, ShardRoutingTest, TestMultiCoreConfBootstrap, TestSort, MinimalSchemaTest, ResourceLoaderTest, ModifyConfFileTest, SuggestComponentTest, TestPerFieldSimilarity, TestIntervalFaceting, TestFunctionQuery, FastVectorHighlighterTest, TestZkChroot, SolrInfoMBeanTest, SoftAutoCommitTest, TestWriterPerf, SolrRequestParserTest, RecoveryZkTest, TestTolerantSearch, TestBadConfig, OverseerTest, InfoHandlerTest, SolrCoreCheckLockOnStartupTest, DOMUtilTest, ZkControllerTest, TestImplicitCoreProperties, TestNRTOpen, HdfsBasicDistributedZk2Test, TestSolrDeletionPolicy1, TestLRUCache, TestShortCircuitedRequests, AssignTest, TestManagedSchemaFieldTypeResource, HdfsRecoveryZkTest, TestCopyFieldCollectionResource, ChaosMonkeySafeLeaderTest, DefaultValueUpdateProcessorTest, OpenExchangeRatesOrgProviderTest, ChaosMonkeyNothingIsSafeTest, TestDistributedSearch, StandardRequestHandlerTest, TestPhraseSuggestions, CollectionsAPIDistributedZkTest] [junit4] Completed in 869.15s, 1 test, 1 error <<< FAILURES! [...truncated 663 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:524: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:472: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1359: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:961: There were test failures: 436 suites, 1784 tests, 1 error, 53 ignored (31 assumptions) Total time: 158 minutes 1 second Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.8.0_40-ea-b04 -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