You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/09/25 21:05:39 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_67) - Build #
4336 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4336/
Java: 64bit/jdk1.7.0_67 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
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([15DD7DA4AFA95479:943BF3BCD8F63445]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:212)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:208)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.GeneratedMethodAccessor60.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java: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 11310 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-15DD7DA4AFA95479-001\init-core-data-001
[junit4] 2> 4034289 T9078 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 4034289 T9078 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 4034295 T9078 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 4034297 T9078 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4034298 T9079 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 4034392 T9078 oasc.ZkTestServer.run start zk server on port:53784
[junit4] 2> 4034392 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4034395 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4034399 T9085 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2fcfe5d2 name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4034399 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4034400 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4034400 T9078 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 4034405 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4034408 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4034409 T9087 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d62b21e name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4034410 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4034410 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4034410 T9078 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 4034414 T9078 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 4034417 T9078 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 4034420 T9078 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 4034424 T9078 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> 4034425 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 4034429 T9078 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> 4034429 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 4034434 T9078 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> 4034434 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4034441 T9078 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> 4034441 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 4034444 T9078 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> 4034444 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 4034449 T9078 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> 4034449 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 4034454 T9078 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> 4034454 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 4034459 T9078 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> 4034459 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 4034463 T9078 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> 4034464 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4034467 T9078 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> 4034467 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 4034470 T9078 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> 4034470 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 4034477 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4034480 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4034480 T9089 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a937577 name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4034481 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4034481 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> Using new style solr.xml
[junit4] 2> 4034484 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4034486 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4034487 T9091 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a69e61 name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4034489 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4034489 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4034489 T9078 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 4034493 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4034494 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4034496 T9093 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41743df3 name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4034496 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4034497 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4034498 T9078 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> 4034499 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 4034504 T9078 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> 4034504 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 4034507 T9078 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> 4034507 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4034513 T9078 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> 4034513 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 4034518 T9078 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> 4034518 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 4034521 T9078 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> 4034522 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 4034526 T9078 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> 4034526 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 4034531 T9078 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> 4034532 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 4034536 T9078 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> 4034536 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 4034541 T9078 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> 4034541 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 4034545 T9078 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> 4034545 T9078 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 4035923 T9078 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4035932 T9078 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4035938 T9078 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53800
[junit4] 2> 4035941 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4035941 T9078 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4035941 T9078 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-15DD7DA4AFA95479-001\tempDir-002
[junit4] 2> 4035941 T9078 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-002\'
[junit4] 2> 4035997 T9078 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-15DD7DA4AFA95479-001\tempDir-002\solr.xml
[junit4] 2> 4036056 T9078 oasc.CorePropertiesLocator.<init> Config-defined core root directory: 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-15DD7DA4AFA95479-001\tempDir-002
[junit4] 2> 4036056 T9078 oasc.CoreContainer.<init> New CoreContainer 1765748611
[junit4] 2> 4036056 T9078 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-15DD7DA4AFA95479-001\tempDir-002\]
[junit4] 2> 4036058 T9078 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4036058 T9078 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4036058 T9078 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4036058 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4036058 T9078 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4036060 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4036060 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4036060 T9078 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4036060 T9078 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4036066 T9078 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 4036069 T9078 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4036069 T9078 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4036069 T9078 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4036069 T9078 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53784/solr
[junit4] 2> 4036069 T9078 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4036072 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4036074 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4036076 T9104 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@393237d7 name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4036077 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4036077 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4036081 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4036082 T9106 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12547e3e name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4036082 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4036085 T9078 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 4036089 T9078 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 4036094 T9078 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 4036096 T9078 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 4036100 T9078 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 4036105 T9078 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 4036107 T9078 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53800_
[junit4] 2> 4036108 T9078 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53800_
[junit4] 2> 4036112 T9078 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 4036115 T9078 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 4036118 T9078 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4036120 T9078 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53800_
[junit4] 2> 4036120 T9078 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 4036123 T9078 oasc.Overseer.start Overseer (id=92515105244708870-127.0.0.1:53800_-n_0000000000) starting
[junit4] 2> 4036127 T9078 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 4036139 T9078 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 4036140 T9108 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 4036141 T9078 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 4036145 T9078 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 4036149 T9078 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4036157 T9107 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 4036160 T9078 oasc.CorePropertiesLocator.discover Looking for core definitions underneath 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-15DD7DA4AFA95479-001\tempDir-002
[junit4] 2> 4037180 T9078 oasc.CorePropertiesLocator.discoverUnder Found core conf in 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-15DD7DA4AFA95479-001\tempDir-002\conf\
[junit4] 2> 4037359 T9078 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4037360 T9078 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4037360 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4037369 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4037372 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4037373 T9112 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f97793b name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4037374 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4037374 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4037376 T9078 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4037382 T9078 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 4037382 T9078 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2
[junit4] 2> 4037382 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4037384 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4037385 T9114 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12c8b282 name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4037385 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4037385 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4037388 T9115 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4037389 T9080 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148adff80780008, 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> 4037394 T9107 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1
[junit4] 2> 4037394 T9107 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 4037394 T9107 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 4037397 T9107 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json
[junit4] 2> 4037398 T9115 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> 4037404 T9116 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> 4038781 T9078 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 4038781 T9078 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4038790 T9078 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4038792 T9078 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53815
[junit4] 2> 4038798 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4038798 T9078 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4038798 T9078 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-15DD7DA4AFA95479-001\tempDir-003
[junit4] 2> 4038798 T9078 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-003\'
[junit4] 2> 4038837 T9078 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-15DD7DA4AFA95479-001\tempDir-003\solr.xml
[junit4] 2> 4038880 T9078 oasc.CorePropertiesLocator.<init> Config-defined core root directory: 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-15DD7DA4AFA95479-001\tempDir-003
[junit4] 2> 4038880 T9078 oasc.CoreContainer.<init> New CoreContainer 1127754247
[junit4] 2> 4038880 T9078 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-15DD7DA4AFA95479-001\tempDir-003\]
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4038883 T9078 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4038887 T9078 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 4038891 T9078 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4038891 T9078 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4038891 T9078 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4038891 T9078 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53784/solr
[junit4] 2> 4038891 T9078 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4038894 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4038895 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4038897 T9127 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53b74ac0 name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4038898 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4038898 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4038900 T9080 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148adff80780009, 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> 4038902 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4038903 T9129 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3cd0035a name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4038904 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4038914 T9078 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4039968 T9078 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53815_
[junit4] 2> 4039969 T9078 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53815_
[junit4] 2> 4039981 T9078 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4039988 T9078 oasc.CorePropertiesLocator.discover Looking for core definitions underneath 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-15DD7DA4AFA95479-001\tempDir-003
[junit4] 2> 4041074 T9078 oasc.CorePropertiesLocator.discoverUnder Found core conf in 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-15DD7DA4AFA95479-001\tempDir-003\conf\
[junit4] 2> 4041257 T9078 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4041260 T9078 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4041260 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4042886 T9078 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 4042886 T9078 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4042896 T9078 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4042898 T9078 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53824
[junit4] 2> 4042904 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4042904 T9078 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4042905 T9078 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-15DD7DA4AFA95479-001\tempDir-004
[junit4] 2> 4042905 T9078 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-15DD7DA4AFA95479-001\tempDir-004\'
[junit4] 2> 4042968 T9078 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-15DD7DA4AFA95479-001\tempDir-004\solr.xml
[junit4] 2> 4043020 T9078 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-004
[junit4] 2> 4043021 T9078 oasc.CoreContainer.<init> New CoreContainer 335314853
[junit4] 2> 4043021 T9078 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-15DD7DA4AFA95479-001\tempDir-004\]
[junit4] 2> 4043022 T9078 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4043022 T9078 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4043023 T9078 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4043023 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4043023 T9078 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4043024 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4043024 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4043024 T9078 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4043024 T9078 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4043028 T9078 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 4043031 T9078 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4043031 T9078 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4043032 T9078 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4043032 T9078 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53784/solr
[junit4] 2> 4043032 T9078 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4043032 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4043035 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4043037 T9142 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72b2abe9 name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4043038 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4043038 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4043042 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4043044 T9144 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1467ba08 name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4043044 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4043054 T9078 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4044111 T9078 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53824_
[junit4] 2> 4044113 T9078 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53824_
[junit4] 2> 4044124 T9078 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4044131 T9078 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-004
[junit4] 2> 4044732 T9078 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-004\conf\
[junit4] 2> 4044838 T9078 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4044841 T9078 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4044841 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4046437 T9078 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 4046438 T9078 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4046450 T9078 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4046452 T9078 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53833
[junit4] 2> 4046457 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4046457 T9078 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4046457 T9078 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-15DD7DA4AFA95479-001\tempDir-005
[junit4] 2> 4046457 T9078 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-005\'
[junit4] 2> 4046497 T9078 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-15DD7DA4AFA95479-001\tempDir-005\solr.xml
[junit4] 2> 4046539 T9078 oasc.CorePropertiesLocator.<init> Config-defined core root directory: 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-15DD7DA4AFA95479-001\tempDir-005
[junit4] 2> 4046539 T9078 oasc.CoreContainer.<init> New CoreContainer 34502834
[junit4] 2> 4046540 T9078 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-15DD7DA4AFA95479-001\tempDir-005\]
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4046542 T9078 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4046546 T9078 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 4046549 T9078 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4046549 T9078 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4046549 T9078 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4046549 T9078 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53784/solr
[junit4] 2> 4046549 T9078 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4046549 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4046553 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4046555 T9157 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@46cb04ff name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4046556 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4046556 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4046558 T9080 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148adff8078000d, 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> 4046559 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4046561 T9159 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b92969b name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4046562 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4046573 T9078 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4047630 T9078 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53833_
[junit4] 2> 4047634 T9078 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53833_
[junit4] 2> 4047655 T9078 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4047661 T9078 oasc.CorePropertiesLocator.discover Looking for core definitions underneath 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-15DD7DA4AFA95479-001\tempDir-005
[junit4] 2> 4048687 T9078 oasc.CorePropertiesLocator.discoverUnder Found core conf in 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-15DD7DA4AFA95479-001\tempDir-005\conf\
[junit4] 2> 4048898 T9078 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4048899 T9078 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4048899 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4050714 T9078 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 4050714 T9078 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4050733 T9078 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4050737 T9078 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:53842
[junit4] 2> 4050745 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4050745 T9078 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4050745 T9078 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-15DD7DA4AFA95479-001\tempDir-006
[junit4] 2> 4050745 T9078 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-15DD7DA4AFA95479-001\tempDir-006\'
[junit4] 2> 4050800 T9078 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-15DD7DA4AFA95479-001\tempDir-006\solr.xml
[junit4] 2> 4050852 T9078 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-006
[junit4] 2> 4050853 T9078 oasc.CoreContainer.<init> New CoreContainer 295471069
[junit4] 2> 4050853 T9078 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-15DD7DA4AFA95479-001\tempDir-006\]
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4050855 T9078 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4050862 T9078 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 4050866 T9078 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4050866 T9078 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4050866 T9078 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4050866 T9078 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53784/solr
[junit4] 2> 4050866 T9078 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4050866 T9078 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4050870 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4050872 T9172 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@154af893 name:ZooKeeperConnection Watcher:127.0.0.1:53784 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4050875 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4050875 T9078 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4050877 T9080 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148adff8078000f, 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> 4050879 T9078 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4050881 T9174 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a41deac name:ZooKeeperConnection Watcher:127.0.0.1:53784/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4050884 T9078 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4050899 T9078 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4052061 T9078 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53842_
[junit4] 2> 4052062 T9078 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53842_
[junit4] 2> 4052074 T9078 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4052086 T9078 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-006
[junit4] 2> 4052846 T9078 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-006\conf\
[junit4] 2> 4052973 T9078 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4052977 T9078 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 4052977 T9078 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4053020 T9125 oasha.CollectionsHandler.handleCreateAction Creating Collection : version=2&action=CREATE&replicationFactor=2&collection.configName=conf1&numShards=2&wt=javabin&name=solrj_collection&router.field=myOwnField
[junit4] 2> 4053022 T9115 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4053024 T9108 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> 4053025 T9177 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> 4053031 T9177 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection
[junit4] 2> 4053032 T9177 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 4053040 T9115 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4053048 T9107 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection
[junit4] 2> 4053048 T9107 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2]
[junit4] 2> 4053049 T9107 oasc.Overseer$ClusterStateUpdater.createCollection state version solrj_collection 1
[junit4] 2> 4053055 T9116 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> 4053056 T9175 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> 4053057 T9160 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> 4053058 T9115 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> 4053058 T9145 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> 4053058 T9130 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> 4053156 T9177 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection solrj_collection, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 4053156 T9177 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:53824_
[junit4] 2> 4053159 T9177 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:53833_
[junit4] 2> 4053164 T9177 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:53815_
[junit4] 2> 4053201 T9154 oasha.CoreAdminHandler.handleCreateAction core create command version=2&action=CREATE&collection.configName=conf1&numShards=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&name=solrj_collection_shard1_replica2&shard=shard1
[junit4] 2> 4053202 T9137 oasha.CoreAdminHandler.handleCreateAction core create command version=2&action=CREATE&collection.configName=conf1&numShards=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&name=solrj_collection_shard1_replica1&shard=shard1
[junit4] 2> 4053204 T9177 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:53800_
[junit4] 2> 4053211 T9137 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down collection=solrj_collection
[junit4] 2> 4053213 T9154 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down collection=solrj_collection
[junit4] 2> 4053215 T9154 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4053216 T9137 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4053216 T9115 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4053248 T9123 oasha.CoreAdminHandler.handleCreateAction core create command version=2&action=CREATE&collection.configName=conf1&numShards=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&name=solrj_collection_shard2_replica1&shard=shard2
[junit4] 2> 4053248 T9098 oasha.CoreAdminHandler.handleCreateAction core create command version=2&action=CREATE&collection.configName=conf1&numShards=2&collection=solrj_collection&wt=javabin&qt=/admin/cores&name=solrj_collection_shard2_replica2&shard=shard2
[junit4] 2> 4053253 T9123 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down collection=solrj_collection
[junit4] 2> 4053257 T9123 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4053257 T9098 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down collection=solrj_collection
[junit4] 2> 4053258 T9107 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "node_name":"127.0.0.1:53824_",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:53824",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 4053258 T9098 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4053264 T9107 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "node_name":"127.0.0.1:53833_",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:53833",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 4053268 T9115 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4053270 T9107 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "node_name":"127.0.0.1:53815_",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:53815",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 4053275 T9115 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4053276 T9107 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "node_name":"127.0.0.1:53800_",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:53800",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state"}
[junit4] 2> 4053397 T9175 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> 4053398 T9116 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> 4053400 T9145 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> 4053400 T9130 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> 4053401 T9160 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> 4053401 T9115 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> 4054366 T9154 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 4054366 T9154 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 4054368 T9154 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4054368 T9154 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 4054372 T9154 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4054372 T9154 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-005\solrj_collection_shard1_replica2\'
[junit4] 2> 4054379 T9137 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 4054379 T9137 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 4054407 T9123 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 4054407 T9123 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 4054424 T9137 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4054424 T9137 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 4054425 T9123 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4054425 T9123 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 4054429 T9137 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4054429 T9137 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-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\'
[junit4] 2> 4054437 T9098 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 4054437 T9098 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 4054438 T9123 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4054438 T9123 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\'
[junit4] 2> 4054496 T9098 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4054496 T9098 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 4054504 T9098 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4054504 T9098 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-002\solrj_collection_shard2_replica2\'
[junit4] 2> 4054791 T9154 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4054865 T9154 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4054920 T9137 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4054994 T9137 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4055036 T9154 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4055045 T9137 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4055054 T9154 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 4055063 T9137 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 4055070 T9123 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4055252 T9098 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4055290 T9123 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4055328 T9123 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4055346 T9123 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 4055418 T9098 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4055473 T9098 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4055500 T9098 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 4056375 T9137 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4056503 T9123 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4056526 T9123 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4056529 T9123 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4056534 T9137 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4056538 T9137 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4056558 T9154 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4056569 T9154 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4056576 T9154 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4056678 T9098 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4056687 T9098 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4056692 T9098 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4056814 T9137 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056831 T9137 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056837 T9123 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056848 T9137 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4056861 T9123 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056865 T9154 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056867 T9137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4056867 T9137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4056867 T9137 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4056880 T9123 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4056890 T9154 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056892 T9137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4056892 T9137 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4056892 T9137 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using configuration from collection solrj_collection
[junit4] 2> 4056892 T9137 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4056894 T9137 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-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\data\
[junit4] 2> 4056894 T9137 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1792dc2b
[junit4] 2> 4056895 T9137 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-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\data\
[junit4] 2> 4056895 T9137 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-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\data\index/
[junit4] 2> 4056895 T9137 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 4056898 T9137 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-15DD7DA4AFA95479-001\tempDir-004\solrj_collection_shard1_replica1\data\index
[junit4] 2> 4056899 T9137 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 4056900 T9137 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-642e3dd0)),segFN=segments_1,generation=1}
[junit4] 2> 4056900 T9137 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4056908 T9137 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 4056909 T9137 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 4056910 T9137 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 4056910 T9137 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 4056911 T9137 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 4056912 T9137 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4056912 T9137 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4056913 T9137 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4056913 T9137 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4056914 T9137 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4056916 T9137 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4056916 T9137 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4056918 T9137 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 4056918 T9137 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 4056918 T9137 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 4056918 T9137 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 4056920 T9137 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 4056920 T9137 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 4056921 T9137 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 4056922 T9137 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 4056939 T9137 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4056943 T9137 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4056946 T9137 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4056950 T9137 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4056958 T9137 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 4056958 T9137 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 4056959 T9137 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 4056959 T9137 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-642e3dd0)),segFN=segments_1,generation=1}
[junit4] 2> 4056959 T9137 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4056962 T9137 oass.SolrIndexSearcher.<init> Opening Searcher@280a801d[solrj_collection_shard1_replica1] main
[junit4] 2> 4056962 T9137 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 4056967 T9098 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056968 T9123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4056968 T9123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4056968 T9123 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4056977 T9154 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4056982 T9123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4056983 T9123 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4056983 T9123 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using configuration from collection solrj_collection
[junit4] 2> 4056984 T9123 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4056985 T9123 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\, dataDir=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-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\data\
[junit4] 2> 4056985 T9123 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1792dc2b
[junit4] 2> 4056986 T9123 oasc.CachingDirectoryFactory.get return new directory for 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-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\data\
[junit4] 2> 4056987 T9123 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=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-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\data\index/
[junit4] 2> 4056987 T9123 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\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\data\index' doesn't exist. Creating new index...
[junit4] 2> 4056989 T9123 oasc.CachingDirectoryFactory.get return new directory for 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-15DD7DA4AFA95479-001\tempDir-003\solrj_collection_shard2_replica1\data\index
[junit4] 2> 4056990 T9123 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 4056990 T9154 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4056991 T9154 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4056991 T9154 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4056989 T9137 oascc.ZkStateReader.readConfigName path=/collections/solrj_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4056993 T9137 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 4056989 T9098 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4056996 T9137 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4056996 T9137 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 4056998 T9137 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4056998 T9154 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4056999 T9154 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4056999 T9154 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using configuration from collection solrj_collection
[junit4] 2> 4057000 T9154 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4056999 T9123 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-25cbeb36)),segFN=segments_1,generation=1}
[junit4] 2> 4057000 T9154 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-15DD7DA4AFA95479-001\tempDir-005\solrj_collection_shard1_replica2\, dataDir=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-15DD7DA4AFA95479-001\tempDir-005\solrj_collection_shard1_replica2\data\
[junit4] 2> 4057000 T9154 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1792dc2b
[junit4] 2> 40570
[...truncated too long message...]
ng to 127.0.0.1:53784 53784
[junit4] 2> 4807535 T9303 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53784 53784
[junit4] 2> 4807540 T9303 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 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 children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=15DD7DA4AFA95479 -Dtests.slow=true -Dtests.locale=fr_CA -Dtests.timezone=Etc/GMT+6 -Dtests.file.encoding=US-ASCII
[junit4] ERROR 773s | 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([15DD7DA4AFA95479:943BF3BCD8F63445]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:212)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:208)
[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> 4807552 T9078 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-15DD7DA4AFA95479-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {intDefault=Lucene41(blocksize=128), _version_=Lucene41(blocksize=128), range_facet_l=Lucene41(blocksize=128), id=FST41, multiDefault=PostingsFormat(name=Lucene41VarGapFixedInterval), other_tl1=Lucene41(blocksize=128), text=PostingsFormat(name=Lucene41WithOrds), a_i1=Lucene41(blocksize=128), a_t=Lucene41(blocksize=128), timestamp=Lucene41(blocksize=128)}, docValues:{timestamp=DocValuesFormat(name=Lucene410)}, sim=DefaultSimilarity, locale=fr_CA, timezone=Etc/GMT+6
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_67 (64-bit)/cpus=2,threads=1,free=113979520,total=519438336
[junit4] 2> NOTE: All tests run in this JVM: [TestWordDelimiterFilterFactory, ChangedSchemaMergeTest, TestNonNRTOpen, UpdateParamsTest, BasicDistributedZk2Test, RequestHandlersTest, MBeansHandlerTest, TestCoreDiscovery, UUIDFieldTest, LegacyHTMLStripCharFilterTest, CollectionsAPIAsyncDistributedZkTest, TestManagedSchemaFieldTypeResource, TestRandomFaceting, ZkSolrClientTest, TestRandomDVFaceting, TestManagedSchemaFieldResource, TestSchemaResource, TestSolrJ, TestSchemaSimilarityResource, TestCopyFieldCollectionResource, TestLRUCache, TestFiltering, TermsComponentTest, XsltUpdateRequestHandlerTest, TestRangeQuery, TestCloudManagedSchemaConcurrent, TestStressUserVersions, ShardSplitTest, DirectSolrConnectionTest, TestRandomMergePolicy, PingRequestHandlerTest, TestGroupingSearch, TestExceedMaxTermLength, CSVRequestHandlerTest, SuggesterFSTTest, TestIndexingPerformance, TestAtomicUpdateErrorCases, TestDistributedSearch, ConnectionManagerTest, TestSort, TestFastOutputStream, CurrencyFieldXmlFileTest, RankQueryTest, TestTolerantSearch, QueryElevationComponentTest, TestFuzzyAnalyzedSuggestions, TestOverriddenPrefixQueryForCustomFieldType, ChaosMonkeyNothingIsSafeTest, DocExpirationUpdateProcessorFactoryTest, TestCoreContainer, LeaderElectionTest, TestSolrQueryParserDefaultOperatorResource, TestStandardQParsers, TestUpdate, TestLeaderElectionZkExpiry, BlockDirectoryTest, TestRecoveryHdfs, WordBreakSolrSpellCheckerTest, TestHighFrequencyDictionaryFactory, TimeZoneUtilsTest, UUIDUpdateProcessorFallbackTest, TestArbitraryIndexDir, NotRequiredUniqueKeyTest, TestFaceting, TestInitParams, RegexBoostProcessorTest, AnalysisErrorHandlingTest, IndexSchemaRuntimeFieldTest, TestRestManager, TestShardHandlerFactory, LoggingHandlerTest, TestPartialUpdateDeduplication, TestSolrXmlPersistence, DateFieldTest, TestIndexSearcher, HdfsSyncSliceTest, ChaosMonkeySafeLeaderTest, FieldAnalysisRequestHandlerTest, TestLazyCores, IndexSchemaTest, TestNonDefinedSimilarityFactory, TestDocumentBuilder, TestStressLucene, DistribCursorPagingTest, TestCloudManagedSchema, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestFunctionQuery, TestRecovery, TestMiniSolrCloudCluster, UnloadDistributedZkTest, TestSchemaNameResource, TestPhraseSuggestions, InfoHandlerTest, ReturnFieldsTest, TestReload, HdfsLockFactoryTest, ShowFileRequestHandlerTest, SolrCoreCheckLockOnStartupTest, TestReloadAndDeleteDocs, SliceStateTest, ConvertedLegacyTest, DocValuesTest, TestExpandComponent, CustomCollectionTest, VMParamsZkACLAndCredentialsProvidersTest, XmlUpdateRequestHandlerTest, TestFieldResource, HighlighterMaxOffsetTest, PrimitiveFieldTypeTest, PreAnalyzedFieldTest, TestTrie, TestSolrCoreProperties, DirectUpdateHandlerTest, BJQParserTest, TestPHPSerializedResponseWriter, SSLMigrationTest, LeaderElectionIntegrationTest, TestSystemIdResolver, TestFoldingMultitermQuery, TestCodecSupport, TestAddFieldRealTimeGet, ClusterStateTest, TestBM25SimilarityFactory, AsyncMigrateRouteKeyTest, TestNoOpRegenerator, CoreAdminRequestStatusTest, SpellCheckComponentTest, MoreLikeThisHandlerTest, SuggesterTSTTest, ZkNodePropsTest, LeaderFailoverAfterPartitionTest, CircularListTest, HdfsBasicDistributedZkTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrQueryParser, ExternalFileFieldSortTest, TestJoin, DeleteInactiveReplicaTest, MultiThreadedOCPTest, DistributedIntervalFacetingTest, ResourceLoaderTest, AssignTest, CursorMarkTest, TestManagedResourceStorage, TestXIncludeConfig, TestSearchPerf, TestRemoteStreaming, DateRangeFieldTest, TestImplicitCoreProperties, RequiredFieldsTest, SignatureUpdateProcessorFactoryTest, TriLevelCompositeIdRoutingTest, TestAnalyzeInfixSuggestions, ParsingFieldUpdateProcessorsTest, DistribDocExpirationUpdateProcessorTest, TestDynamicFieldResource, TestZkChroot, TestReplicationHandler, SpellCheckCollatorTest, TestDefaultSearchFieldResource, TestFreeTextSuggestions, SyncSliceTest, AliasIntegrationTest, AnalysisAfterCoreReloadTest, ResponseHeaderTest, TestFieldTypeResource, TestReversedWildcardFilterFactory, OverseerTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestSolrIndexConfig, SolrIndexConfigTest, TestBlendedInfixSuggestions, FullSolrCloudDistribCmdsTest, TestLuceneMatchVersion, DefaultValueUpdateProcessorTest, BadIndexSchemaTest, UniqFieldsUpdateProcessorFactoryTest, TestManagedSynonymFilterFactory, JSONWriterTest, SharedFSAutoReplicaFailoverUtilsTest, TestBinaryField, SolrCmdDistributorTest, TestSolrQueryParserResource, TestManagedStopFilterFactory, TestIBSimilarityFactory, TestCollationField, BlockCacheTest, SOLR749Test, ShardRoutingCustomTest, EchoParamsTest, SynonymTokenizerTest, PrimUtilsTest, QueryResultKeyTest, DeleteShardTest, TestCollationFieldDocValues, CursorPagingTest, FastVectorHighlighterTest, SharedFSAutoReplicaFailoverTest, SpellingQueryConverterTest, TestConfig, DirectSolrSpellCheckerTest, NumericFieldsTest, MultiTermTest, RemoteQueryErrorTest, TestCustomSort, SolrRequestParserTest, TestHashPartitioner, TestOrdValues, BasicDistributedZkTest, CollectionsAPIDistributedZkTest]
[junit4] Completed in 773.30s, 1 test, 1 error <<< FAILURES!
[...truncated 686 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: 430 suites, 1775 tests, 1 error, 53 ignored (31 assumptions)
Total time: 165 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0_67 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any