Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4428/
Java: 64bit/jdk1.8.0_20 -XX:-UseCompressedOops -XX:+UseSerialGC (asserts: true)
3 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([76D30CF49A66D538:F73582ECED39B504]: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.GeneratedMethodAccessor40.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
REGRESSION: org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([76D30CF49A66D538]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandlerBackup
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([76D30CF49A66D538]:0)
Build Log:
[...truncated 11341 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\init-core-data-001
[junit4] 2> 2525298 T6371 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (false)
[junit4] 2> 2525298 T6371
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /l_qyp/y
[junit4] 2> 2525303 T6371 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 2525305 T6371 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2525307 T6372 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2525412 T6371 oasc.ZkTestServer.run start zk server on
port:64981
[junit4] 2> 2525412 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2525414 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2525420 T6378 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@119fd064
name:ZooKeeperConnection Watcher:127.0.0.1:64981 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2525421 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2525421 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2525422 T6371 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2525427 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2525427 T6373 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x149a289017c0000, 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> 2525431 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2525434 T6380 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@100c8362
name:ZooKeeperConnection Watcher:127.0.0.1:64981/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2525434 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2525434 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2525434 T6371 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 2525440 T6371 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 2525447 T6371 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 2525450 T6371 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 2525454 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 2525454 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 2525461 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 2525473 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 2525478 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2525478 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2525495 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 2525496 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 2525498 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 2525499 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 2525503 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 2525503 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 2525506 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 2525506 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 2525510 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2525510 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 2525512 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2525512 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2525516 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 2525516 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 2525519 T6371 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 2525519 T6371 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 2525523 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2525526 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2525529 T6382 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2528558b
name:ZooKeeperConnection Watcher:127.0.0.1:64981/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2525530 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2525530 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2527433 T6371 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2527445 T6371 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2527450 T6371 oejs.AbstractConnector.doStart Started
[email protected]:64991
[junit4] 2> 2527455 T6371 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2527456 T6371 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2527457 T6371 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-002
[junit4] 2> 2527457 T6371 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-002\'
[junit4] 2> 2527502 T6371 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-002\solr.xml
[junit4] 2> 2527517 T6371 oasc.CoreContainer.<init> New CoreContainer
2053424295
[junit4] 2> 2527518 T6371 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-002\]
[junit4] 2> 2527518 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2527519 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2527519 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2527519 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2527520 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2527520 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2527520 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2527520 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2527521 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2527521 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2527527 T6371 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2527532 T6371 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2527533 T6371 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2527533 T6371 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2527534 T6371 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:64981/solr
[junit4] 2> 2527534 T6371 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2527535 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2527538 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2527541 T6393 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2defe92f
name:ZooKeeperConnection Watcher:127.0.0.1:64981 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2527543 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2527543 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2527549 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2527550 T6395 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@365e8f8b
name:ZooKeeperConnection Watcher:127.0.0.1:64981/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2527551 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2527553 T6371 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2527558 T6371 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2527564 T6371 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2527568 T6371 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2527573 T6371 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2527583 T6371 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2527586 T6371 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64991_l_qyp%2Fy
[junit4] 2> 2527588 T6371 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:64991_l_qyp%2Fy
[junit4] 2> 2527593 T6371 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2527597 T6371 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2527601 T6371 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2527604 T6371 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:64991_l_qyp%2Fy
[junit4] 2> 2527604 T6371 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2527608 T6371 oasc.Overseer.start Overseer
(id=92783976656928772-127.0.0.1:64991_l_qyp%2Fy-n_0000000000) starting
[junit4] 2> 2527613 T6371 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2527631 T6371 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 2527635 T6397 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2527636 T6371 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2527641 T6371 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2527644 T6371
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2527651 T6396 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2527656 T6399 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 2527656 T6399 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2527657 T6399 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2527657 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2527663 T6396 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:64991/l_qyp/y";,
[junit4] 2> "node_name":"127.0.0.1:64991_l_qyp%2Fy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 2527663 T6396
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
control_collection with shards [shard1]
[junit4] 2> 2527664 T6396
oasc.Overseer$ClusterStateUpdater.createCollection state version
control_collection 1
[junit4] 2> 2527664 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2527668 T6400 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> 2528702 T6399 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2528702 T6399 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 2528705 T6399 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2528705 T6399 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 2528709 T6399 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 2528709 T6399 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-002\collection1\'
[junit4] 2> 2528715 T6399 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 2528715 T6399 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 2528715 T6399 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 2528775 T6399 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2528806 T6399 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2528809 T6399 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 2528814 T6399 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2528951 T6399 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2528952 T6399 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2528953 T6399 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2528958 T6399 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2528995 T6399 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 2528995 T6399 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 2528996 T6399 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-002\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001/control/data\
[junit4] 2> 2528996 T6399 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f818fcd
[junit4] 2> 2529000 T6399 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\control\data
[junit4] 2> 2529001 T6399 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001/control/data\index/
[junit4] 2> 2529001 T6399 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\control\data\index'
doesn't exist. Creating new index...
[junit4] 2> 2529002 T6399 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\control\data\index
[junit4] 2> 2529003 T6399 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=45, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=84.1591796875,
floorSegmentMB=0.26953125, forceMergeDeletesPctAllowed=13.782608745745149,
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8733411152871143
[junit4] 2> 2529008 T6399 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2529008 T6399 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2529012 T6399 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2529012 T6399 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2529012 T6399 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2529013 T6399 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2529013 T6399 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2529013 T6399 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2529014 T6399 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2529014 T6399 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2529015 T6399 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2529016 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 2529017 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2529017 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2529018 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2529020 T6399 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2529020 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 2529021 T6399 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2529021 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 2529022 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 2529022 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2529023 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2529023 T6399 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2529030 T6399 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2529031 T6399 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2529033 T6399 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2529034 T6399 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2529036 T6399 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2529036 T6399 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2529037 T6399 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.20999221229009796]
[junit4] 2> 2529038 T6399 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\control\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2529038 T6399 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2529039 T6399 oass.SolrIndexSearcher.<init> Opening
Searcher@768a403f[collection1] main
[junit4] 2> 2529039 T6399 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 2529041 T6399 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 2529041 T6399 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2529042 T6399
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2529042 T6399 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2529043 T6399 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2529044 T6399
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 2529044 T6399 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2529046 T6399 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 2529050 T6399
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 2529050 T6399 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2529051 T6399 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2529053 T6401 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@768a403f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2529055 T6399 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2529057 T6405 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:64991/l_qyp/y
collection:control_collection shard:shard1
[junit4] 2> 2529058 T6371 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2529058 T6371 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2529060 T6405 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2529068 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2529070 T6405 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 2529071 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2529073 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2529073 T6407 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3f8f4717
name:ZooKeeperConnection Watcher:127.0.0.1:64981/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2529074 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2529074 T6405
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2529075 T6405 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 2529074 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> ASYNC NEW_CORE C2387 name=collection1
org.apache.solr.core.SolrCore@3985889
url=https://127.0.0.1:64991/l_qyp/y/collection1 node=127.0.0.1:64991_l_qyp%2Fy
C2387_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:64991/l_qyp/y, node_name=127.0.0.1:64991_l_qyp%2Fy,
state=down}
[junit4] 2> 2529075 T6405 C2387 P64991 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:64991/l_qyp/y/collection1/
[junit4] 2> 2529076 T6405 C2387 P64991 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2529076 T6405 C2387 P64991 oasc.SyncStrategy.syncToMe
https://127.0.0.1:64991/l_qyp/y/collection1/ has no replicas
[junit4] 2> 2529076 T6405 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:64991/l_qyp/y/collection1/ shard1
[junit4] 2> 2529076 T6405 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 2529077 T6371
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2529086 T6371 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 2529086 T6371 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 2529086 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2529088 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2529091 T6409 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4bcfbf28
name:ZooKeeperConnection Watcher:127.0.0.1:64981/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2529092 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2529092 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2529096 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2529101 T6396
oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection:
collection1
[junit4] 2> 2529101 T6396
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1]
[junit4] 2> 2529101 T6396
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 2529106 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2529106 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 2529110 T6400 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> 2529111 T6410 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> 2529153 T6405 oasc.ZkController.register We are
https://127.0.0.1:64991/l_qyp/y/collection1/ and leader is
https://127.0.0.1:64991/l_qyp/y/collection1/
[junit4] 2> 2529153 T6405 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:64991/l_qyp/y
[junit4] 2> 2529153 T6405 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2529153 T6405 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 2529153 T6405 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2529155 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2529163 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 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:64991/l_qyp/y";,
[junit4] 2> "node_name":"127.0.0.1:64991_l_qyp%2Fy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 2529274 T6410 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> 2529275 T6400 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> 2530971 T6371 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 2530972 T6371 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2530986 T6371 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2530989 T6371 oejs.AbstractConnector.doStart Started
[email protected]:65006
[junit4] 2> 2530993 T6371 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2531000 T6371 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2531000 T6371 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-003
[junit4] 2> 2531000 T6371 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-003\'
[junit4] 2> 2531039 T6371 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-003\solr.xml
[junit4] 2> 2531054 T6371 oasc.CoreContainer.<init> New CoreContainer
446283704
[junit4] 2> 2531055 T6371 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-003\]
[junit4] 2> 2531056 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2531056 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2531056 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2531057 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2531057 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2531057 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2531058 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2531058 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2531058 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2531059 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2531072 T6371 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2531074 T6371 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2531074 T6371 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2531074 T6371 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2531075 T6371 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:64981/solr
[junit4] 2> 2531075 T6371 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2531075 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2531076 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2531082 T6421 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7fbe39eb
name:ZooKeeperConnection Watcher:127.0.0.1:64981 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2531083 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2531083 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2531087 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2531088 T6423 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@53791fe9
name:ZooKeeperConnection Watcher:127.0.0.1:64981/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2531089 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2531104 T6371
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2532160 T6371 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65006_l_qyp%2Fy
[junit4] 2> 2532164 T6371 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:65006_l_qyp%2Fy
[junit4] 2> 2532179 T6371 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2532197 T6425 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2532197 T6425 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2532200 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2532202 T6425 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 2532202 T6425 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 2532203 T6425 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 2532206 T6425 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 2532206 T6425 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2532218 T6396 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:65006/l_qyp/y";,
[junit4] 2> "node_name":"127.0.0.1:65006_l_qyp%2Fy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2532224 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=1
[junit4] 2> 2532226 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2532235 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2532235 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 2532237 T6424 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2532238 T6424 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 2533252 T6425 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2533253 T6425 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 2533255 T6425 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2533255 T6425 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2533259 T6425 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2533259 T6425 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-003\collection1\'
[junit4] 2> 2533266 T6425 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 2533270 T6425 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 2533272 T6425 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 2533345 T6425 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2533358 T6425 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2533361 T6425 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 2533365 T6425 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2533519 T6425 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2533520 T6425 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2533521 T6425 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2533526 T6425 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2533568 T6425 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 2533568 T6425 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 2533570 T6425 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-003\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001/jetty1\
[junit4] 2> 2533570 T6425 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f818fcd
[junit4] 2> 2533570 T6425 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty1
[junit4] 2> 2533572 T6425 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001/jetty1\index/
[junit4] 2> 2533572 T6425 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty1\index'
doesn't exist. Creating new index...
[junit4] 2> 2533574 T6425 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty1\index
[junit4] 2> 2533575 T6425 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=45, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=84.1591796875,
floorSegmentMB=0.26953125, forceMergeDeletesPctAllowed=13.782608745745149,
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8733411152871143
[junit4] 2> 2533579 T6425 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2533579 T6425 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2533582 T6425 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2533582 T6425 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2533583 T6425 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2533583 T6425 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2533583 T6425 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2533583 T6425 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2533585 T6425 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2533585 T6425 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2533585 T6425 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2533585 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 2533586 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2533586 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2533587 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2533587 T6425 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2533588 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 2533588 T6425 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2533588 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 2533588 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 2533589 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2533589 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2533589 T6425 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2533595 T6425 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2533596 T6425 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2533597 T6425 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2533600 T6425 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2533603 T6425 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2533603 T6425 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2533604 T6425 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.20999221229009796]
[junit4] 2> 2533605 T6425 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty1\index,segFN=segments_1,generation=1}
[junit4] 2> 2533606 T6425 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2533606 T6425 oass.SolrIndexSearcher.<init> Opening
Searcher@1f9b6a[collection1] main
[junit4] 2> 2533606 T6425 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2533608 T6425 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2533608 T6425 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2533609 T6425
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2533609 T6425 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2533609 T6425 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2533618 T6425
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 2533618 T6425 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2533618 T6425 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 2533618 T6425 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2533622 T6425 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2533622 T6426 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1f9b6a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2533624 T6429 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:65006/l_qyp/y collection:collection1
shard:shard1
[junit4] 2> 2533624 T6371 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2533625 T6371 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2533626 T6429 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2533635 T6429 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 2533638 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2533639 T6429
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 2533639 T6429 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2388 name=collection1
org.apache.solr.core.SolrCore@71b3508a
url=https://127.0.0.1:65006/l_qyp/y/collection1 node=127.0.0.1:65006_l_qyp%2Fy
C2388_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:65006/l_qyp/y, node_name=127.0.0.1:65006_l_qyp%2Fy,
state=down}
[junit4] 2> 2533639 T6429 C2388 P65006 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:65006/l_qyp/y/collection1/
[junit4] 2> 2533639 T6429 C2388 P65006 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 2533640 T6429 C2388 P65006 oasc.SyncStrategy.syncToMe
https://127.0.0.1:65006/l_qyp/y/collection1/ has no replicas
[junit4] 2> 2533640 T6429 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:65006/l_qyp/y/collection1/ shard1
[junit4] 2> 2533640 T6429 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 2533658 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 2533660 T6424 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2533661 T6424 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 2533675 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2533675 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 2533676 T6424 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2533677 T6424 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 2533709 T6429 oasc.ZkController.register We are
https://127.0.0.1:65006/l_qyp/y/collection1/ and leader is
https://127.0.0.1:65006/l_qyp/y/collection1/
[junit4] 2> 2533710 T6429 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:65006/l_qyp/y
[junit4] 2> 2533710 T6429 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 2533710 T6429 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 2533710 T6429 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2533728 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2533739 T6429 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 2533740 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 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:65006/l_qyp/y";,
[junit4] 2> "node_name":"127.0.0.1:65006_l_qyp%2Fy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2533750 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 2533752 T6424 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2533753 T6424 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 2535397 T6371 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 2535397 T6371 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2535406 T6371 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 2535409 T6371 oejs.AbstractConnector.doStart Started
[email protected]:65015
[junit4] 2> 2535413 T6371 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 2535413 T6371 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 2535413 T6371 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-004
[junit4] 2> 2535413 T6371 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-004\'
[junit4] 2> 2535446 T6371 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-004\solr.xml
[junit4] 2> 2535479 T6371 oasc.CoreContainer.<init> New CoreContainer
2009563371
[junit4] 2> 2535480 T6371 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-004\]
[junit4] 2> 2535480 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2535480 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2535481 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2535481 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2535481 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2535481 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2535481 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2535481 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2535483 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2535483 T6371 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2535486 T6371 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 2535490 T6371 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2535490 T6371 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2535490 T6371 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2535492 T6371 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:64981/solr
[junit4] 2> 2535493 T6371 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 2535493 T6371
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 2535495 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2535498 T6440 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@42d88452
name:ZooKeeperConnection Watcher:127.0.0.1:64981 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2535504 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2535504 T6371 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 2535509 T6371 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2535514 T6442 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3d2828c name:ZooKeeperConnection
Watcher:127.0.0.1:64981/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 2535514 T6371 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2535524 T6371
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2536483 T6371 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65015_l_qyp%2Fy
[junit4] 2> 2536486 T6371 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:65015_l_qyp%2Fy
[junit4] 2> 2536500 T6371 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 2536511 T6444 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 2536511 T6444 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 2536514 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2536515 T6444 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 2536516 T6444 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 2536517 T6444 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 2536520 T6444 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 2536520 T6444 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 2536523 T6396 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:65015/l_qyp/y";,
[junit4] 2> "node_name":"127.0.0.1:65015_l_qyp%2Fy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2536528 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=1
[junit4] 2> 2536530 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2536539 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 2536540 T6443 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2536540 T6424 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2536542 T6424 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 2536542 T6443 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 2537460 T6444 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 2537460 T6444 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 2537463 T6444 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 2537463 T6444 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2537467 T6444 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2537468 T6444 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-004\collection1\'
[junit4] 2> 2537473 T6444 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 2537477 T6444 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 2537478 T6444 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 2537527 T6444 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2537539 T6444 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2537543 T6444 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 2537548 T6444 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 2537666 T6444 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 2537668 T6444 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2537669 T6444 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2537672 T6444 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 2537708 T6444 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 2537709 T6444 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 2537709 T6444 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-004\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001/jetty2\
[junit4] 2> 2537709 T6444 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@f818fcd
[junit4] 2> 2537712 T6444 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty2
[junit4] 2> 2537713 T6444 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001/jetty2\index/
[junit4] 2> 2537714 T6444 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty2\index'
doesn't exist. Creating new index...
[junit4] 2> 2537716 T6444 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty2\index
[junit4] 2> 2537716 T6444 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=45, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=84.1591796875,
floorSegmentMB=0.26953125, forceMergeDeletesPctAllowed=13.782608745745149,
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8733411152871143
[junit4] 2> 2537744 T6444 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2537745 T6444 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2537762 T6444 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 2537762 T6444 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 2537763 T6444 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2537763 T6444 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 2537763 T6444 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2537764 T6444 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2537764 T6444 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2537764 T6444 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2537764 T6444 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2537765 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 2537765 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2537766 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2537766 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 2537767 T6444 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 2537768 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 2537768 T6444 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2537768 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 2537769 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 2537769 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2537769 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2537770 T6444 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 2537773 T6444 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2537775 T6444 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2537776 T6444 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2537778 T6444 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2537781 T6444 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 2537781 T6444 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 2537783 T6444 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.20999221229009796]
[junit4] 2> 2537784 T6444 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ChaosMonkeySafeLeaderTest-76D30CF49A66D538-001\tempDir-001\jetty2\index,segFN=segments_1,generation=1}
[junit4] 2> 2537784 T6444 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 2537784 T6444 oass.SolrIndexSearcher.<init> Opening
Searcher@5b541e77[collection1] main
[junit4] 2> 2537784 T6444 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 2537787 T6444 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 2537787 T6444 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 2537788 T6444
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2537788 T6444 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 2537788 T6444 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2537790 T6444
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 2537790 T6444 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2537790 T6444 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 2537791 T6444 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 2537795 T6445 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5b541e77[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2537797 T6444 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 2537798 T6448 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:65015/l_qyp/y collection:collection1
shard:shard1
[junit4] 2> 2537799 T6371 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2537799 T6371 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 2537805 T6448 oasc.ZkController.register We are
https://127.0.0.1:65015/l_qyp/y/collection1/ and leader is
https://127.0.0.1:65006/l_qyp/y/collection1/
[junit4] 2> 2537806 T6448 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:65015/l_qyp/y
[junit4] 2> 2537806 T6448 oasc.ZkController.checkRecovery Core needs to
recover:collection1
[junit4] 2> 2537806 T6448 oasu.DefaultSolrCoreState.doRecovery Running
recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C2389 name=collection1
org.apache.solr.core.SolrCore@5144c815
url=https://127.0.0.1:65015/l_qyp/y/collection1 node=127.0.0.1:65015_l_qyp%2Fy
C2389_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:65015/l_qyp/y, node_name=127.0.0.1:65015_l_qyp%2Fy,
state=down}
[junit4] 2> 2537807 T6449 C2389 P65015 oasc.RecoveryStrategy.run Starting
recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 2537807 T6449 C2389 P65015 oasc.RecoveryStrategy.doRecovery
###### startupVersions=[]
[junit4] 2> 2537808 T6449 C2389 P65015 oasc.RecoveryStrategy.doRecovery
Publishing state of core collection1 as recovering, leader is
https://127.0.0.1:65006/l_qyp/y/collection1/ and I am
https://127.0.0.1:65015/l_qyp/y/collection1/
[junit4] 2> 2537808 T6449 C2389 P65015 oasc.ZkController.publish
publishing core=collection1 state=recovering collection=collection1
[junit4] 2> 2537808 T6449 C2389 P65015 oasc.ZkController.publish numShards
not found on descriptor - reading it from system property
[junit4] 2> 2537811 T6400 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 2537816 T6449 C2389 P65015
oasc.RecoveryStrategy.sendPrepRecoveryCmd Sending prep recovery command to
https://127.0.0.1:65006/l_qyp/y; WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1%3A65015_l_qyp%252Fy&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true
[junit4] 2> 2537822 T6371
oasc.AbstractFullDistribZkTestBase.randomlyEnableAutoSoftCommit Not turning on
auto soft commit
[junit4] 2> 2537826 T6448 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 2537838 T6396 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:65015/l_qyp/y";,
[junit4] 2> "node_name":"127.0.0.1:65015_l_qyp%2Fy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"recovering",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 2537851 T6418 oasha.CoreAdminHandler.handleWaitForStateAction
Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true,
onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> ASYNC NEW_CORE C2390 name=collection1
org.apache.solr.core.SolrCore@3985889
url=https://127.0.0.1:64991/l_qyp/y/collection1 node=127.0.0.1:64991_l_qyp%2Fy
C2390_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:64991/l_qyp/y, node_name=127.0.0.1:64991_l_qyp%2Fy,
state=active, leader=true}
[junit4] 2> 2537873 T6386 C2390 P64991 oasup.LogUpdateProcessor.finish
[collection1] webapp=/l_qyp/y path=/update params={wt=javabin&version=2}
{deleteByQuery=*:* (-1484543639672586240)} 0 7
[junit4] 2> 2537874 T6418 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 2537874 T6418 oasha.CoreAdminHandler.handleWaitForStateAction
Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have
state: recovering
[junit4] 2> 2537874 T6418 oasha.CoreAdminHandler.handleWaitForStateAction
In WaitForState(recovering): collection=collection1, shard=shard1,
thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true,
live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:65015_l_qyp%2Fy, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"https://127.0.0.1:65015/l_qyp/y","node_name":"127.0.0.1:65015_l_qyp%2Fy","state":"down"}
[junit4] 2> 2537884 T6396 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 2537885 T6443 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2537885 T6424 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 2537887 T6443 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 2537887 T6424 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> C2389_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=https://127.0.0.1:65015/l_qyp/y,
node_name=127.0.0.1:65015_l_qyp%2Fy, state=recovering}
[junit4] 2> 2537937 T6433 C2389 P65015 oasup.LogUpdateProcessor.finish
[collection1] webapp=/l_qyp/y path=/update
params={update.distrib=FROMLEADER&_version_=-1484543639709286400&distrib.from=https://127.0.0.1:65006/l_qyp/y/collection1/&wt=javabin&version=2}
{deleteByQuery=*:* (-1484543639709286400)} 0 7
[junit4] 2> ASYNC NEW_CORE C2391 name=collection1
org.apache.solr.core.SolrCore@71b3508a url=https://127.0.0.1:65006/l_qyp
[...truncated too long message...]
essScriptUpdateProcessorFactoryTest, DateMathParserTest, RankQueryTest,
TestZkChroot, TestPhraseSuggestions, CachingDirectoryFactoryTest,
IndexBasedSpellCheckerTest, StressHdfsTest, TestFastWriter,
TestSweetSpotSimilarityFactory, DisMaxRequestHandlerTest, BadCopyFieldTest,
HdfsBasicDistributedZk2Test, TestStandardQParsers, DeleteInactiveReplicaTest,
TestDocBasedVersionConstraints, SampleTest, TestComplexPhraseQParserPlugin,
TestCSVLoader, TestGroupingSearch, AnalyticsMergeStrategyTest,
CurrencyFieldXmlFileTest, TestSolrIndexConfig, TestInfoStreamLogging,
PluginInfoTest, SearchHandlerTest, TestDynamicFieldResource,
TestFreeTextSuggestions, InfoHandlerTest, TestCursorMarkWithoutUniqueKey,
DistributedFacetPivotSmallTest, TestSchemaSimilarityResource,
DirectUpdateHandlerTest, AliasIntegrationTest, DistributedDebugComponentTest,
OutputWriterTest, BJQParserTest, AnalysisAfterCoreReloadTest,
TestBinaryResponseWriter, SortByFunctionTest, TestAnalyzeInfixSuggestions,
TestSystemIdResolver, ClusterStateTest, DateRangeFieldTest,
SimpleCollectionCreateDeleteTest, DistributedSpellCheckComponentTest,
MBeansHandlerTest, HighlighterTest, SynonymTokenizerTest, EnumFieldTest,
SuggesterTSTTest, XmlUpdateRequestHandlerTest, TestUniqueKeyFieldResource,
DistributedFacetPivotLargeTest, IndexSchemaTest, TestTrie, TestCoreDiscovery,
FacetPivotSmallTest, DOMUtilTest, TestNonNRTOpen, SolrCmdDistributorTest,
TestBulkSchemaConcurrent, CursorPagingTest, DocValuesMultiTest,
TestExtendedDismaxParser, SpatialFilterTest, TestIndexSearcher, TestJoin,
TestRawResponseWriter, TestExpandComponent, ZkNodePropsTest,
TestFieldTypeResource, TestSolrCoreProperties, TestReloadAndDeleteDocs,
TestTolerantSearch, TestRandomMergePolicy, TestQuerySenderListener,
SolrIndexConfigTest, DistributedExpandComponentTest, CircularListTest,
StandardRequestHandlerTest, MigrateRouteKeyTest,
SharedFSAutoReplicaFailoverTest, TestSolrDeletionPolicy1,
TestFuzzyAnalyzedSuggestions, TestRecoveryHdfs, AlternateDirectoryTest,
SolrInfoMBeanTest, PeerSyncTest, TestSimpleQParserPlugin, TestDocumentBuilder,
BinaryUpdateRequestHandlerTest, AtomicUpdatesTest, TestHashPartitioner,
ShardSplitTest, TestIBSimilarityFactory, PrimUtilsTest, TestSchemaManager,
DistribDocExpirationUpdateProcessorTest, SuggestComponentTest, DateFieldTest,
ResponseLogComponentTest, TestBinaryField, TestSortingResponseWriter,
TestJmxIntegration, BasicDistributedZkTest, TestDistributedSearch,
CoreContainerCoreInitFailuresTest, PolyFieldTest, MultiTermTest,
OverriddenZkACLAndCredentialsProvidersTest, SolrRequestParserTest,
CoreMergeIndexesAdminHandlerTest, TestMissingGroups, SolrPluginUtilsTest,
DistributedQueueTest, TestManagedSchemaFieldTypeResource,
BasicFunctionalityTest, TestBlendedInfixSuggestions, TestReRankQParserPlugin,
TestCloudInspectUtil, CloudExitableDirectoryReaderTest, EchoParamsTest,
TestFunctionQuery, TestFoldingMultitermQuery, TimeZoneUtilsTest,
TestMaxScoreQueryParser, NumericFieldsTest, TestChildDocTransformer,
ResponseHeaderTest, TestLFUCache, FileBasedSpellCheckerTest,
UUIDUpdateProcessorFallbackTest, MinimalSchemaTest, UUIDFieldTest,
SimpleMLTQParserTest, RollingRestartTest, TestRemoteStreaming,
TestDFRSimilarityFactory, TestReversedWildcardFilterFactory,
TestLuceneMatchVersion, DistanceFunctionTest, TestRandomFaceting,
SimplePostToolTest, UpdateRequestProcessorFactoryTest, TestRecovery,
QueryEqualityTest, SpellPossibilityIteratorTest, RegexBoostProcessorTest,
PingRequestHandlerTest, AnalyticsQueryTest, TestReplicationHandler,
TestOmitPositions, FileUtilsTest, TestManagedResourceStorage,
TestStressReorder, SolrTestCaseJ4Test, ChaosMonkeySafeLeaderTest,
TestFieldTypeCollectionResource, BasicDistributedZk2Test,
TestManagedSynonymFilterFactory, TestSolrConfigHandlerConcurrent,
ChaosMonkeyNothingIsSafeTest, TestFastOutputStream, CSVRequestHandlerTest,
URLClassifyProcessorTest, DefaultValueUpdateProcessorTest,
DistributedQueryComponentOptimizationTest, ReplicationFactorTest,
TestInitQParser, TestPseudoReturnFields, TestSchemaVersionResource,
TestCloudManagedSchemaConcurrent, UnloadDistributedZkTest,
CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest,
OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest,
RecoveryZkTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest,
ZkSolrClientTest, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest,
TestDistributedGrouping, TermVectorComponentDistributedTest, ZkControllerTest,
TestRealTimeGet, TestMultiCoreConfBootstrap, TestReload, TestStressVersions,
HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery,
SimpleFacetsTest, StatsComponentTest, SpellCheckComponentTest,
QueryElevationComponentTest, BadIndexSchemaTest, TestSort, TestFiltering,
TestLazyCores, OverseerCollectionProcessorTest, SoftAutoCommitTest,
ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest,
DistributedQueryElevationComponentTest, SignatureUpdateProcessorFactoryTest,
DocValuesTest, SuggesterTest, TestStressLucene, SuggesterWFSTTest,
NoCacheHeaderTest, WordBreakSolrSpellCheckerTest,
SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest,
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases,
TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest,
XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest,
TestSurroundQueryParser, LukeRequestHandlerTest, TestQueryUtils,
DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes,
PrimitiveFieldTypeTest, TermsComponentTest, RequestHandlersTest,
DocumentBuilderTest, TestValueSourceCache, TermVectorComponentTest,
TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest,
FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir,
FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest,
TestCollationField, ReturnFieldsTest, JSONWriterTest, QueryParsingTest,
TestAnalyzedSuggestions, JsonLoaderTest, TestPartialUpdateDeduplication,
TestPHPSerializedResponseWriter, TestComponentsName, HighlighterConfigTest,
SOLR749Test, UpdateParamsTest, TestQuerySenderNoQuery, BadComponentTest,
TestStressRecovery, TestMergePolicyConfig, TestDocSet, TestSearchPerf,
TestElisionMultitermQuery, TestConfig, TestPostingsSolrHighlighter,
DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestCodecSupport,
TestXIncludeConfig, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory,
TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory,
TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest,
TestJmxMonitoredMap, ScriptEngineTest, OpenExchangeRatesOrgProviderTest,
TestFastLRUCache, PreAnalyzedFieldTest, TestSuggestSpellingConverter,
SpellingQueryConverterTest, RAMDirectoryFactoryTest, TestUtils, SliceStateTest,
SystemInfoHandlerTest, TestRTGBase, DistributedIntervalFacetingTest,
TestDistributedMissingSort, TestHighlightDedupGrouping, AssignTest,
AsyncMigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest,
ConnectionManagerTest, CustomCollectionTest, DeleteReplicaTest,
DeleteShardTest, LeaderFailoverAfterPartitionTest,
LeaderInitiatedRecoveryOnCommitTest, MultiThreadedOCPTest,
OutOfBoxZkACLAndCredentialsProvidersTest, OverseerStatusTest,
RemoteQueryErrorTest, SSLMigrationTest, SharedFSAutoReplicaFailoverUtilsTest,
SolrXmlInZkTest, TestDistribDocBasedVersion, TestLeaderElectionZkExpiry,
TestMiniSolrCloudCluster, TestModifyConfFiles, TestReplicaProperties,
TestRequestStatusCollectionAPI, TestShortCircuitedRequests,
TriLevelCompositeIdRoutingTest, VMParamsZkACLAndCredentialsProvidersTest,
HdfsBasicDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, HdfsRecoveryZkTest,
HdfsSyncSliceTest, HdfsUnloadDistributedZkTest,
HdfsWriteToMultipleCollectionsTest, QueryResultKeyTest, TestConfigOverlay,
TestImplicitCoreProperties, TestInitParams, TestShardHandlerFactory,
TestSolrConfigHandler, TestSolrXml, TestSolrXmlPersistence,
TestSolrXmlPersistor, TestReplicationHandlerBackup]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestReplicationHandlerBackup -Dtests.seed=76D30CF49A66D538
-Dtests.slow=true -Dtests.locale=es_UY -Dtests.timezone=Europe/Belfast
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s | TestReplicationHandlerBackup (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([76D30CF49A66D538]:0)
[junit4] Completed in 7291.51s, 1 test, 2 errors <<< FAILURES!
[...truncated 179 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:525: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:473: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:186:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:508:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1358:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:965:
There were test failures: 447 suites, 1820 tests, 1 suite-level error, 1
error, 1 failure, 54 ignored (30 assumptions)
Total time: 283 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_20
-XX:-UseCompressedOops -XX:+UseSerialGC (asserts: true)
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]
