Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4257/
Java: 32bit/jdk1.8.0_20 -server -XX:+UseSerialGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch
Error Message:
expected:<0> but was:<1>
Stack Trace:
java.lang.AssertionError: expected:<0> but was:<1>
at
__randomizedtesting.SeedInfo.seed([41B5EB2B31D303AF:C0536533468C6393]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.doTest(ChaosMonkeySafeLeaderTest.java:153)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
REGRESSION:
org.apache.solr.cloud.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([41B5EB2B31D303AF:C0536533468C6393]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:570)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java: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 10885 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\init-core-data-001
[junit4] 2> 431714 T1179 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (true)
[junit4] 2> 431714 T1179 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 431721 T1179 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 431722 T1179 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 431724 T1180 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 431839 T1179 oasc.ZkTestServer.run start zk server on
port:65364
[junit4] 2> 431839 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 431841 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 431849 T1186 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1f7bc55 name:ZooKeeperConnection
Watcher:127.0.0.1:65364 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 431849 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 431850 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 431850 T1179 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 431857 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 431860 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 431862 T1188 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@d71c66 name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 431862 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 431862 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 431863 T1179 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 431867 T1179 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 431870 T1179 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 431873 T1179 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 431877 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 431877 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 431885 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 431885 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 431891 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 431891 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 431896 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 431896 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 431903 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 431903 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 431907 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 431928 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 431936 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 431937 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 431941 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 431941 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 431947 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 431947 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 431952 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 431952 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 431956 T1179 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 431956 T1179 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 431962 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 431965 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 431967 T1190 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4e66ae name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 431967 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 431967 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 433822 T1179 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 433838 T1179 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 433869 T1179 oejs.AbstractConnector.doStart Started
[email protected]:65374
[junit4] 2> 433879 T1179 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 433881 T1179 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 433881 T1179 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-002
[junit4] 2> 433882 T1179 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-002\'
[junit4] 2> 433917 T1179 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-002\solr.xml
[junit4] 2> 433931 T1179 oasc.CoreContainer.<init> New CoreContainer
21193127
[junit4] 2> 433932 T1179 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-002\]
[junit4] 2> 433933 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 433933 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 433933 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 433934 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 433934 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 433934 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 433934 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 433935 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 433935 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 433938 T1179 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 433941 T1179 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 433942 T1179 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 433942 T1179 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 433944 T1179 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:65364/solr
[junit4] 2> 433944 T1179 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 433944 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 433948 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 433951 T1201 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@462429 name:ZooKeeperConnection
Watcher:127.0.0.1:65364 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 433952 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 433953 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 433959 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 433961 T1203 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5c07fa name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 433961 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 433964 T1179 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 433969 T1179 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 433975 T1179 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 433979 T1179 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 433982 T1179 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 433986 T1179 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 433989 T1179 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65374_
[junit4] 2> 433992 T1179 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:65374_
[junit4] 2> 433997 T1179 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 434000 T1179 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 434004 T1179 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 434007 T1179 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:65374_
[junit4] 2> 434007 T1179 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 434010 T1179 oasc.Overseer.start Overseer
(id=92583895671701508-127.0.0.1:65374_-n_0000000000) starting
[junit4] 2> 434014 T1179 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 434030 T1179 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 434034 T1205 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 434035 T1204 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 434038 T1179 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 434044 T1179 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 434046 T1179
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 434058 T1207 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 434058 T1207 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 434059 T1207 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 434059 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 434065 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:65374";,
[junit4] 2> "node_name":"127.0.0.1:65374_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 434065 T1204
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
control_collection with shards [shard1]
[junit4] 2> 434065 T1204
oasc.Overseer$ClusterStateUpdater.createCollection state version
control_collection 1
[junit4] 2> 434065 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 434071 T1208 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> 435152 T1207 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 435152 T1207 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 435155 T1207 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 435155 T1207 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 435158 T1207 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 435158 T1207 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-002\collection1\'
[junit4] 2> 435162 T1207 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 435162 T1207 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 435166 T1207 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 435207 T1207 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 435215 T1207 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 435220 T1207 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 435224 T1207 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 435370 T1207 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 435370 T1207 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 435372 T1207 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 435378 T1207 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 435415 T1207 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 435415 T1207 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 435457 T1207 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-002\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001/control/data\
[junit4] 2> 435457 T1207 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@18c2848
[junit4] 2> 435461 T1207 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\control\data
[junit4] 2> 435462 T1207 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001/control/data\index/
[junit4] 2> 435462 T1207 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\control\data\index'
doesn't exist. Creating new index...
[junit4] 2> 435464 T1207 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\control\data\index
[junit4] 2> 435464 T1207 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=42460643,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 435475 T1207 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 435475 T1207 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 435480 T1207 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 435480 T1207 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 435481 T1207 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 435481 T1207 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 435481 T1207 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 435481 T1207 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 435482 T1207 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 435482 T1207 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 435482 T1207 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 435483 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 435483 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 435483 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 435483 T1207 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 435485 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 435485 T1207 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 435485 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 435485 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 435486 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 435486 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 435486 T1207 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 435492 T1207 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 435494 T1207 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 435495 T1207 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 435496 T1207 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 435499 T1207 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 435499 T1207 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 435502 T1207 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=20.9794921875,
floorSegmentMB=1.8603515625, forceMergeDeletesPctAllowed=1.897895524634312,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 435503 T1207 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 435503 T1207 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 435503 T1207 oass.SolrIndexSearcher.<init> Opening
Searcher@10959bf[collection1] main
[junit4] 2> 435503 T1207 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 435505 T1207 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 435505 T1207 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 435506 T1207
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 435506 T1207 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 435506 T1207 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 435507 T1207
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 435507 T1207 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 435508 T1207 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 435511 T1207
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 435511 T1207 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 435512 T1207 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 435516 T1209 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@10959bf[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 435517 T1207 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 435519 T1212 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:65374 collection:control_collection
shard:shard1
[junit4] 2> 435519 T1179 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 435519 T1179 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 435521 T1212 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 435529 T1212 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 435532 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 435533 T1212
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 435533 T1212 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C563 name=collection1
org.apache.solr.core.SolrCore@14272de url=https://127.0.0.1:65374/collection1
node=127.0.0.1:65374_ C563_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=https://127.0.0.1:65374,
node_name=127.0.0.1:65374_, state=down}
[junit4] 2> 435533 T1212 C563 P65374 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:65374/collection1/
[junit4] 2> 435535 T1212 C563 P65374 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 435535 T1212 C563 P65374 oasc.SyncStrategy.syncToMe
https://127.0.0.1:65374/collection1/ has no replicas
[junit4] 2> 435535 T1212 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:65374/collection1/ shard1
[junit4] 2> 435535 T1212 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 435533 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 435538 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 435541 T1214 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1063bd3 name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 435541 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 435542 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 435545 T1179
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 435550 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 435551 T1179 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 435552 T1179 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 435552 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 435553 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 435556 T1216 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@11d1fc6 name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 435556 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 435557 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 435560 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 435564 T1204 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: collection1
[junit4] 2> 435564 T1204
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 435564 T1204
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 435567 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 435569 T1208 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> 435572 T1217 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> 435608 T1212 oasc.ZkController.register We are
https://127.0.0.1:65374/collection1/ and leader is
https://127.0.0.1:65374/collection1/
[junit4] 2> 435608 T1212 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:65374
[junit4] 2> 435608 T1212 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 435608 T1212 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 435608 T1212 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 435611 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 435617 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:65374";,
[junit4] 2> "node_name":"127.0.0.1:65374_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 435730 T1217 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> 435731 T1208 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> 437429 T1179 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 437430 T1179 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 437448 T1179 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 437454 T1179 oejs.AbstractConnector.doStart Started
[email protected]:65389
[junit4] 2> 437463 T1179 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 437464 T1179 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 437464 T1179 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-003
[junit4] 2> 437464 T1179 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-003\'
[junit4] 2> 437512 T1179 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-003\solr.xml
[junit4] 2> 437545 T1179 oasc.CoreContainer.<init> New CoreContainer
3991353
[junit4] 2> 437545 T1179 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-003\]
[junit4] 2> 437547 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 437548 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 437553 T1179 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 437556 T1179 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 437556 T1179 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 437556 T1179 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 437556 T1179 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:65364/solr
[junit4] 2> 437556 T1179 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 437556 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 437560 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 437564 T1228 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@16c65d7 name:ZooKeeperConnection
Watcher:127.0.0.1:65364 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 437565 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 437565 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 437573 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 437577 T1230 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@b733ed name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 437577 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 437590 T1179
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 438588 T1179 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65389_
[junit4] 2> 438590 T1179 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:65389_
[junit4] 2> 438596 T1179 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 438606 T1232 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 438606 T1232 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 438609 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 438610 T1232 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 438610 T1232 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 438612 T1232 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 438615 T1232 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 438615 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:65389";,
[junit4] 2> "node_name":"127.0.0.1:65389_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 438615 T1232 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 438620 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 438622 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 438631 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 438632 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 438633 T1231 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 439599 T1232 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 439599 T1232 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 439600 T1232 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 439600 T1232 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 439602 T1232 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 439602 T1232 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-003\collection1\'
[junit4] 2> 439605 T1232 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 439606 T1232 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 439607 T1232 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 439640 T1232 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 439651 T1232 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 439654 T1232 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 439657 T1232 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 439773 T1232 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 439775 T1232 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 439777 T1232 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 439781 T1232 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 439815 T1232 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 439816 T1232 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 439816 T1232 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-003\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001/jetty1\
[junit4] 2> 439816 T1232 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@18c2848
[junit4] 2> 439821 T1232 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty1
[junit4] 2> 439824 T1232 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001/jetty1\index/
[junit4] 2> 439824 T1232 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty1\index'
doesn't exist. Creating new index...
[junit4] 2> 439825 T1232 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty1\index
[junit4] 2> 439827 T1232 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=42460643,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 439854 T1232 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 439854 T1232 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 439857 T1232 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 439857 T1232 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 439857 T1232 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 439857 T1232 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 439858 T1232 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 439858 T1232 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 439858 T1232 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 439859 T1232 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 439859 T1232 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 439859 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 439860 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 439860 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 439860 T1232 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 439861 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 439861 T1232 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 439861 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 439862 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 439862 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 439862 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 439863 T1232 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 439870 T1232 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 439872 T1232 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 439874 T1232 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 439875 T1232 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 439878 T1232 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 439878 T1232 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 439879 T1232 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=20.9794921875,
floorSegmentMB=1.8603515625, forceMergeDeletesPctAllowed=1.897895524634312,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 439879 T1232 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 439879 T1232 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 439879 T1232 oass.SolrIndexSearcher.<init> Opening
Searcher@16804fd[collection1] main
[junit4] 2> 439879 T1232 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 439882 T1232 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 439882 T1232 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 439883 T1232
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 439883 T1232 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 439883 T1232 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 439885 T1232
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 439886 T1232 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 439886 T1232 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 439886 T1232 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 439889 T1233 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@16804fd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 439892 T1232 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 439894 T1236 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:65389 collection:collection1
shard:shard2
[junit4] 2> 439894 T1179 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 439895 T1179 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 439896 T1236 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 439904 T1236 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 439906 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 439908 T1236
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 439908 T1236 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C564 name=collection1
org.apache.solr.core.SolrCore@c9d723 url=https://127.0.0.1:65389/collection1
node=127.0.0.1:65389_ C564_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=https://127.0.0.1:65389,
node_name=127.0.0.1:65389_, state=down}
[junit4] 2> 439908 T1236 C564 P65389 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:65389/collection1/
[junit4] 2> 439908 T1236 C564 P65389 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 439909 T1236 C564 P65389 oasc.SyncStrategy.syncToMe
https://127.0.0.1:65389/collection1/ has no replicas
[junit4] 2> 439909 T1236 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:65389/collection1/ shard2
[junit4] 2> 439909 T1236 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 439929 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 439930 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 439931 T1231 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 439943 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 439944 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 439945 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 439946 T1231 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 439978 T1236 oasc.ZkController.register We are
https://127.0.0.1:65389/collection1/ and leader is
https://127.0.0.1:65389/collection1/
[junit4] 2> 439978 T1236 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:65389
[junit4] 2> 439978 T1236 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 439978 T1236 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 439978 T1236 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 439981 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 439991 T1236 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 439991 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:65389";,
[junit4] 2> "node_name":"127.0.0.1:65389_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 440000 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 440001 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 440002 T1231 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 441561 T1179 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 441561 T1179 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 441573 T1179 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 441575 T1179 oejs.AbstractConnector.doStart Started
[email protected]:65398
[junit4] 2> 441579 T1179 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 441579 T1179 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 441579 T1179 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-004
[junit4] 2> 441580 T1179 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-004\'
[junit4] 2> 441612 T1179 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-004\solr.xml
[junit4] 2> 441625 T1179 oasc.CoreContainer.<init> New CoreContainer
18246116
[junit4] 2> 441626 T1179 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-004\]
[junit4] 2> 441627 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 441627 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 441628 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 441628 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 441628 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 441629 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 441629 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 441629 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 441630 T1179 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 441633 T1179 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 441635 T1179 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 441636 T1179 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 441636 T1179 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 441636 T1179 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:65364/solr
[junit4] 2> 441637 T1179 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 441637 T1179
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 441639 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 441641 T1247 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1691a34 name:ZooKeeperConnection
Watcher:127.0.0.1:65364 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 441642 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 441642 T1179 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 441646 T1181 oazs.NIOServerCnxn.doIO WARN caught end of stream
exception EndOfStreamException: Unable to read additional data from client
sessionid 0x148ec90056f0009, 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> 441647 T1179 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 441650 T1249 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@18095eb name:ZooKeeperConnection
Watcher:127.0.0.1:65364/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 441650 T1179 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 441660 T1179
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 442654 T1179 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65398_
[junit4] 2> 442656 T1179 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:65398_
[junit4] 2> 442669 T1179 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 442684 T1251 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 442684 T1251 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 442689 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 442691 T1251 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 442692 T1251 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 442694 T1251 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 442695 T1251 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 442695 T1251 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 442696 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:65398";,
[junit4] 2> "node_name":"127.0.0.1:65398_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 442703 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 442705 T1204 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 442719 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 442720 T1250 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 442720 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 442722 T1250 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 442723 T1231 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 443681 T1251 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 443681 T1251 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 443682 T1251 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 443682 T1251 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 443683 T1251 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 443683 T1251 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-004\collection1\'
[junit4] 2> 443686 T1251 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 443687 T1251 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 443689 T1251 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 443723 T1251 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 443744 T1251 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 443747 T1251 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 443751 T1251 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 443858 T1251 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 443860 T1251 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 443861 T1251 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 443865 T1251 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 443891 T1251 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 443891 T1251 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 443893 T1251 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-004\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001/jetty2\
[junit4] 2> 443893 T1251 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@18c2848
[junit4] 2> 443894 T1251 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty2
[junit4] 2> 443895 T1251 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001/jetty2\index/
[junit4] 2> 443896 T1251 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty2\index'
doesn't exist. Creating new index...
[junit4] 2> 443897 T1251 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty2\index
[junit4] 2> 443898 T1251 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=42460643,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 443903 T1251 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 443903 T1251 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 443906 T1251 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 443906 T1251 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 443906 T1251 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 443906 T1251 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 443906 T1251 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 443906 T1251 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 443907 T1251 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 443907 T1251 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 443907 T1251 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 443907 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 443908 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 443908 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 443909 T1251 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 443909 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 443909 T1251 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 443909 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 443910 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 443910 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 443910 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 443911 T1251 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 443917 T1251 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 443918 T1251 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 443919 T1251 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 443921 T1251 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 443922 T1251 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 443924 T1251 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 443927 T1251 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=20.9794921875,
floorSegmentMB=1.8603515625, forceMergeDeletesPctAllowed=1.897895524634312,
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 443927 T1251 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-41B5EB2B31D303AF-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 443927 T1251 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 443929 T1251 oass.SolrIndexSearcher.<init> Opening
Searcher@12ea67f[collection1] main
[junit4] 2> 443929 T1251 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 443930 T1251 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 443930 T1251 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 443931 T1251
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 443931 T1251 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 443931 T1251 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 443933 T1251
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 443933 T1251 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 443933 T1251 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 443933 T1251 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 443937 T1252 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@12ea67f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 443938 T1251 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 443940 T1255 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:65398 collection:collection1
shard:shard1
[junit4] 2> 443941 T1179 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 443941 T1179 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 443942 T1255 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 443949 T1255 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 443953 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 443954 T1255
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 443954 T1255 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C565 name=collection1
org.apache.solr.core.SolrCore@1faf338 url=https://127.0.0.1:65398/collection1
node=127.0.0.1:65398_ C565_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=https://127.0.0.1:65398,
node_name=127.0.0.1:65398_, state=down}
[junit4] 2> 443954 T1255 C565 P65398 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:65398/collection1/
[junit4] 2> 443954 T1255 C565 P65398 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 443954 T1255 C565 P65398 oasc.SyncStrategy.syncToMe
https://127.0.0.1:65398/collection1/ has no replicas
[junit4] 2> 443954 T1255 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:65398/collection1/ shard1
[junit4] 2> 443955 T1255 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 443970 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 443971 T1250 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 443972 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 443974 T1250 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 443974 T1231 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 443987 T1208 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 443987 T1204 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 443988 T1250 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 443989 T1231 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json,
[...truncated too long message...]
newcollection_2_shard1_replica1\data\;done=false>>]
[junit4] 2> 4043970 T8898 oasc.CachingDirectoryFactory.close Closing
directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-41B5EB2B31D303AF-001\tempDir-006\awholynewcollection_2_shard1_replica1\data\
[junit4] 2> 4043971 T8898 oasc.Overseer.close Overseer
(id=92584083748093967-127.0.0.1:53207_-n_0000000004) closing
[junit4] 2> 4043971 T9299 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:53207_
[junit4] 2> 4043977 T9118 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x148ecbbcfa9000f, 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> 4045355 T9298 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 4045367 T8898 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 4050183 T8898 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 4050185 T8898 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:53152 53152
[junit4] 2> 4052359 T9117 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:53152 53152
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch
-Dtests.seed=41B5EB2B31D303AF -Dtests.slow=true -Dtests.locale=sr_ME
-Dtests.timezone=America/Rainy_River -Dtests.file.encoding=US-ASCII
[junit4] ERROR 751s | 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([41B5EB2B31D303AF:C0536533468C6393]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:570)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 4052364 T8898 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CollectionsAPIDistributedZkTest-41B5EB2B31D303AF-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{other_tl1=PostingsFormat(name=SimpleText),
_version_=PostingsFormat(name=SimpleText), multiDefault=FSTOrd41,
a_t=PostingsFormat(name=SimpleText),
intDefault=PostingsFormat(name=SimpleText),
a_i1=PostingsFormat(name=SimpleText), id=PostingsFormat(name=Memory doPackFST=
true), text=PostingsFormat(name=Memory doPackFST= false),
range_facet_l=PostingsFormat(name=SimpleText),
timestamp=PostingsFormat(name=SimpleText)},
docValues:{timestamp=DocValuesFormat(name=SimpleText)}, sim=DefaultSimilarity,
locale=sr_ME, timezone=America/Rainy_River
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_20
(32-bit)/cpus=2,threads=1,free=94476040,total=282591232
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrDeletionPolicy2,
MergeStrategyTest, CustomCollectionTest,
TestSolrQueryParserDefaultOperatorResource, TestManagedSchemaFieldResource,
TestSolrQueryParser, JsonLoaderTest, TestDocBasedVersionConstraints,
DistributedFacetPivotLargeTest, BasicDistributedZkTest,
UpdateRequestProcessorFactoryTest, TestValueSourceCache,
ChaosMonkeySafeLeaderTest, DateRangeFieldTest, RegexBoostProcessorTest,
TestCoreContainer, SpatialFilterTest, BasicDistributedZk2Test,
SuggesterWFSTTest, HdfsChaosMonkeySafeLeaderTest, ShardRoutingCustomTest,
TestSchemaVersionResource, TestStressRecovery, TestImplicitCoreProperties,
TestDocSet, TestFastWriter, TestInfoStreamLogging,
SpellPossibilityIteratorTest, TestLazyCores, TestRealTimeGet,
TestSchemaManager, SliceStateTest, AnalyticsMergeStrategyTest,
ExternalFileFieldSortTest, FullSolrCloudDistribCmdsTest, TestRecoveryHdfs,
TestUniqueKeyFieldResource, TestBM25SimilarityFactory,
TestCopyFieldCollectionResource, TestClassNameShortening, TestCloudInspectUtil,
HdfsUnloadDistributedZkTest, TestCustomSort, LegacyHTMLStripCharFilterTest,
OverseerCollectionProcessorTest, TestSystemIdResolver, HardAutoCommitTest,
TestSolrQueryParserResource, TestHashPartitioner, ClusterStateTest,
TestJmxIntegration, TestLMDirichletSimilarityFactory, CoreAdminHandlerTest,
SolrCoreTest, UnloadDistributedZkTest, DirectSolrConnectionTest, RankQueryTest,
TestComponentsName, TestDistributedMissingSort, SpellCheckCollatorTest,
SimpleFacetsTest, TestCollapseQParserPlugin, ReturnFieldsTest,
TestSchemaSimilarityResource, TestReplicationHandlerBackup,
TestChildDocTransformer, TestManagedSchemaFieldTypeResource,
DeleteInactiveReplicaTest, TestPhraseSuggestions, DocValuesMissingTest,
ModifyConfFileTest, XsltUpdateRequestHandlerTest, SyncSliceTest,
TestPseudoReturnFields, TestFieldCollectionResource, TestManagedResource,
TestRecovery, TestQueryUtils, TestConfigSets, QueryParsingTest,
CircularListTest, OutputWriterTest, TestMultiCoreConfBootstrap,
TestAtomicUpdateErrorCases, DocumentAnalysisRequestHandlerTest,
DistribCursorPagingTest, SortByFunctionTest, DocumentBuilderTest,
TestCloudManagedSchemaConcurrent, DistributedDebugComponentTest,
TestComplexPhraseQParserPlugin, HdfsRecoveryZkTest,
TestSuggestSpellingConverter, TestPHPSerializedResponseWriter, TestLRUCache,
BinaryUpdateRequestHandlerTest, ParsingFieldUpdateProcessorsTest,
RequestHandlersTest, TestNRTOpen, TestFunctionQuery,
TestShortCircuitedRequests, HdfsWriteToMultipleCollectionsTest,
FastVectorHighlighterTest, EchoParamsTest, TestDFRSimilarityFactory,
DateMathParserTest, TestSchemaNameResource, TestSurroundQueryParser,
TestFieldTypeCollectionResource, TestFaceting, UpdateParamsTest,
ScriptEngineTest, SuggesterTSTTest, HttpPartitionTest,
QueryElevationComponentTest, TestSolr4Spatial,
CoreMergeIndexesAdminHandlerTest, TestDistributedGrouping,
CoreContainerCoreInitFailuresTest, CSVRequestHandlerTest,
TestBulkSchemaConcurrent, TestBinaryResponseWriter, TestRTGBase,
TestRandomFaceting, SearchHandlerTest, MoreLikeThisHandlerTest,
OpenCloseCoreStressTest, SpellCheckComponentTest, TestSolr4Spatial2,
PreAnalyzedUpdateProcessorTest, CoreAdminRequestStatusTest,
TestIndexingPerformance, TestXIncludeConfig,
DistributedQueryElevationComponentTest, DefaultValueUpdateProcessorTest,
DeleteLastCustomShardedReplicaTest, DOMUtilTest, ChangedSchemaMergeTest,
TestNonDefinedSimilarityFactory, TestCloudSchemaless, DeleteShardTest,
SpellingQueryConverterTest, CollectionsAPIAsyncDistributedZkTest,
TestAnalyzedSuggestions, XmlUpdateRequestHandlerTest, PingRequestHandlerTest,
TestFieldSortValues, TestReloadAndDeleteDocs, TestFiltering, TestInitParams,
TestCoreDiscovery, CurrencyFieldXmlFileTest, TestSolrXmlPersistence,
HdfsSyncSliceTest, TestReplicationHandler, TestDistributedSearch,
EnumFieldTest, ZkSolrClientTest, SSLMigrationTest,
TestManagedSynonymFilterFactory, AnalyticsQueryTest, TestSolrXmlPersistor,
TestDefaultSearchFieldResource, CloudExitableDirectoryReaderTest,
BadIndexSchemaTest, TestSerializedLuceneMatchVersion, StressHdfsTest,
TestManagedStopFilterFactory, TestRawResponseWriter, TermVectorComponentTest,
SolrXmlInZkTest, TestBlendedInfixSuggestions, AutoCommitTest, TestRangeQuery,
DocExpirationUpdateProcessorFactoryTest, HighlighterMaxOffsetTest,
TestManagedSchema, CollectionsAPIDistributedZkTest]
[junit4] Completed in 750.87s, 1 test, 1 error <<< FAILURES!
[...truncated 792 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:524: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:472: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:189: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:496:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1360:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:962:
There were test failures: 436 suites, 1784 tests, 1 error, 1 failure, 53
ignored (31 assumptions)
Total time: 164 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20 -server
-XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
