Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/2383/
1 tests failed.
REGRESSION:
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.testDistribSearch
Error Message:
There were too many update fails - we expect it can happen, but shouldn't easily
Stack Trace:
java.lang.AssertionError: There were too many update fails - we expect it can
happen, but shouldn't easily
at
__randomizedtesting.SeedInfo.seed([D36030A63CD25D63:5286BEBE4B8D3D5F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertFalse(Assert.java:68)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:223)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9484 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/init-core-data-001
[junit4] 2> 731177 T1186 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /bs/gv
[junit4] 2> 731182 T1186 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 731183 T1186 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 731183 T1187 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 731283 T1186 oasc.ZkTestServer.run start zk server on
port:53879
[junit4] 2> 731284 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 731284 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 731289 T1194 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23e19613
name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 731289 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 731289 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 731289 T1186 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 731292 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 731293 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 731295 T1197 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2c119bd9
name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 731295 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 731295 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 731296 T1186 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 731298 T1186 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 731299 T1186 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 731300 T1186 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 731301 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 731302 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 731304 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 731305 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 731407 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 731407 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 731409 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 731409 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 731411 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 731411 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 731413 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 731413 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 731415 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 731415 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 731416 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 731417 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 731418 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 731419 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 731420 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 731421 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 731422 T1186 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 731423 T1186 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 732368 T1186 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 732373 T1186 oejs.AbstractConnector.doStart Started
[email protected]:53885
[junit4] 2> 732373 T1186 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@60da5686
[junit4] 2> 732373 T1186 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 732374 T1186 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002
[junit4] 2> 732374 T1186 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/'
[junit4] 2> 732404 T1186 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/solr.xml
[junit4] 2> 732458 T1186 oasc.CoreContainer.<init> New CoreContainer
1325555462
[junit4] 2> 732459 T1186 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/]
[junit4] 2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 732460 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 732461 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 732462 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 732462 T1186 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 732462 T1186 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 732463 T1186 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 732463 T1186 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 732463 T1186 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53879/solr
[junit4] 2> 732463 T1186 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 732464 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 732464 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 732466 T1209 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@40a4d689
name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 732467 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 732467 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 732469 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 732471 T1212 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@171e8560
name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 732471 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 732473 T1186 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 732475 T1186 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 732476 T1186 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 732478 T1186 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 732480 T1186 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 732482 T1186 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 732483 T1186 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53885_bs%2Fgv
[junit4] 2> 732483 T1186 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:53885_bs%2Fgv
[junit4] 2> 732485 T1186 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 732486 T1186 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 732487 T1186 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 732489 T1186 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:53885_bs%2Fgv
[junit4] 2> 732489 T1186 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 732490 T1186 oasc.Overseer.start Overseer
(id=93033784810799107-127.0.0.1:53885_bs%2Fgv-n_0000000000) starting
[junit4] 2> 732493 T1186 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 732498 T1186 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 732499 T1214 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 732499 T1186 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 732502 T1186 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 732504 T1186
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 732511 T1213 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 732518 T1216 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 732519 T1216 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 732521 T1216 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 732521 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 732524 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53885/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53885_bs%2Fgv",
[junit4] 2> "numShards":"1"} current state version: 0
[junit4] 2> 732524 T1213 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53885/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53885_bs%2Fgv",
[junit4] 2> "numShards":"1"}
[junit4] 2> 732524 T1213 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 732525 T1213 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 732526 T1212 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> 733522 T1216 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 733523 T1216 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 733523 T1216 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 733524 T1216 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 733524 T1216 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 733525 T1216 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/'
[junit4] 2> 733527 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 733528 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 733528 T1216 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 733563 T1216 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 733565 T1216 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 733600 T1216 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 733648 T1216 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 733749 T1216 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 733761 T1216 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 734128 T1216 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 734131 T1216 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 734133 T1216 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 734137 T1216 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 734156 T1216 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 734157 T1216 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 734228 T1216 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-002/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/
[junit4] 2> 734229 T1216 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@21993c80
[junit4] 2> 734232 T1216 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data
[junit4] 2> 734233 T1216 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index/
[junit4] 2> 734234 T1216 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index'
doesn't exist. Creating new index...
[junit4] 2> 734235 T1216 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index
[junit4] 2> 734236 T1216 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2895872467303534]
[junit4] 2> 734241 T1216 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 734241 T1216 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 734246 T1216 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 734246 T1216 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 734247 T1216 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 734247 T1216 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 734247 T1216 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 734248 T1216 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 734248 T1216 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 734248 T1216 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 734249 T1216 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 734249 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 734250 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 734250 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 734251 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 734251 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 734252 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 734252 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 734253 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 734253 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 734254 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 734255 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 734255 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 734256 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 734256 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 734257 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 734257 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 734258 T1216 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 734258 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 734258 T1216 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 734278 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 734280 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 734282 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 734284 T1216 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 734288 T1216 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 734288 T1216 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 734289 T1216 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27820321960034355]
[junit4] 2> 734291 T1216 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 734291 T1216 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 734292 T1216 oass.SolrIndexSearcher.<init> Opening
Searcher@59a26723[collection1] main
[junit4] 2> 734292 T1216 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 734293 T1216 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 734293 T1216 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 734294 T1216
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 734294 T1216 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 734294 T1216 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 734295 T1216
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 734295 T1216 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 734295 T1216 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 734296 T1216 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 734297 T1217 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@59a26723[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 734297 T1216 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 734298 T1220 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:53885/bs/gv
collection:control_collection shard:shard1
[junit4] 2> 734298 T1186 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1
[junit4] 2> 734299 T1186 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 734299 T1220 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 734299 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 734300 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 734302 T1223 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@424e885b
name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 734302 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 734303 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 734305 T1186
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 734305 T1220 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 734306 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 734306 T1220
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 734307 T1220 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C210 name=collection1
org.apache.solr.core.SolrCore@47da3c89
url=http://127.0.0.1:53885/bs/gv/collection1 node=127.0.0.1:53885_bs%2Fgv
C210_STATE=coll:control_collection core:collection1 props:{core=collection1,
state=down, base_url=http://127.0.0.1:53885/bs/gv,
node_name=127.0.0.1:53885_bs%2Fgv}
[junit4] 2> 734307 T1220 C210 P53885 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:53885/bs/gv/collection1/
[junit4] 2> 734307 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 734307 T1186 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 734307 T1220 C210 P53885 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 734308 T1186 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 734308 T1220 C210 P53885 oasc.SyncStrategy.syncToMe
http://127.0.0.1:53885/bs/gv/collection1/ has no replicas
[junit4] 2> 734308 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 734309 T1223 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> 734309 T1212 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> 734309 T1220 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:53885/bs/gv/collection1/ shard1
[junit4] 2> 734310 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 734310 T1220 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 734311 T1226 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@636a03ce
name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 734312 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 734312 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 734314 T1227 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 734316 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":2} current state version: 2
[junit4] 2> 734316 T1213 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 734318 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:53885/bs/gv";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 734319 T1213 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 734320 T1223 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> 734322 T1227 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 734323 T1223 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> 734366 T1220 oasc.ZkController.register We are
http://127.0.0.1:53885/bs/gv/collection1/ and leader is
http://127.0.0.1:53885/bs/gv/collection1/
[junit4] 2> 734367 T1220 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:53885/bs/gv
[junit4] 2> 734367 T1220 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 734367 T1220 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 734367 T1220 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 734369 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 734370 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53885/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53885_bs%2Fgv",
[junit4] 2> "numShards":"1"} current state version: 4
[junit4] 2> 734371 T1213 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53885/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53885_bs%2Fgv",
[junit4] 2> "numShards":"1"}
[junit4] 2> 734474 T1212 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> 734474 T1223 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> 735598 T1186 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 735599 T1186 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 735601 T1186 oejs.AbstractConnector.doStart Started
[email protected]:53890
[junit4] 2> 735602 T1186 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@60da5686
[junit4] 2> 735602 T1186 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 735602 T1186 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003
[junit4] 2> 735603 T1186 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/'
[junit4] 2> 735636 T1186 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/solr.xml
[junit4] 2> 735696 T1186 oasc.CoreContainer.<init> New CoreContainer
1863493584
[junit4] 2> 735697 T1186 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/]
[junit4] 2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 735698 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 735699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 735700 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 735700 T1186 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 735700 T1186 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 735701 T1186 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 735701 T1186 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 735701 T1186 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53879/solr
[junit4] 2> 735701 T1186 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 735702 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 735702 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 735704 T1239 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6d2fe35c
name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 735705 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 735705 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 735707 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 735708 T1242 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@384fa2e4
name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 735709 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 735713 T1186
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 736716 T1186 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53890_bs%2Fgv
[junit4] 2> 736717 T1186 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:53890_bs%2Fgv
[junit4] 2> 736720 T1186 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 736728 T1243 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 736728 T1243 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 736730 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 736730 T1243 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 736730 T1243 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 736730 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53890/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53890_bs%2Fgv",
[junit4] 2> "numShards":"1"} current state version: 5
[junit4] 2> 736731 T1243 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 736731 T1213 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53890/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53890_bs%2Fgv",
[junit4] 2> "numShards":"1"}
[junit4] 2> 736731 T1213 oasco.ReplicaMutator.updateState Collection
already exists with numShards=1
[junit4] 2> 736732 T1213 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 736732 T1243 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 736732 T1243 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 736733 T1213 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 736733 T1242 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 736734 T1242 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 737732 T1243 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 737733 T1243 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 737733 T1243 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 737734 T1243 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 737734 T1243 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 737735 T1243 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/'
[junit4] 2> 737736 T1243 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 737737 T1243 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 737738 T1243 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 737771 T1243 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 737774 T1243 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 737809 T1243 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 737873 T1243 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 737974 T1243 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 737987 T1243 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 738374 T1243 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 738377 T1243 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 738379 T1243 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 738384 T1243 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 738402 T1243 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 738402 T1243 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 738403 T1243 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-003/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/
[junit4] 2> 738403 T1243 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@21993c80
[junit4] 2> 738405 T1243 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1
[junit4] 2> 738406 T1243 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index/
[junit4] 2> 738407 T1243 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index'
doesn't exist. Creating new index...
[junit4] 2> 738408 T1243 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index
[junit4] 2> 738409 T1243 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2895872467303534]
[junit4] 2> 738440 T1243 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 738440 T1243 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 738446 T1243 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 738446 T1243 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 738447 T1243 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 738447 T1243 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 738447 T1243 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 738448 T1243 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 738448 T1243 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 738448 T1243 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 738449 T1243 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 738449 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 738450 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 738450 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 738451 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 738452 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 738452 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 738455 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 738455 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 738456 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 738457 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 738458 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 738459 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 738459 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 738460 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 738460 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 738461 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 738461 T1243 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 738462 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 738463 T1243 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 738481 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 738483 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 738485 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 738487 T1243 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 738491 T1243 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 738492 T1243 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 738494 T1243 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27820321960034355]
[junit4] 2> 738495 T1243 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 738495 T1243 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 738496 T1243 oass.SolrIndexSearcher.<init> Opening
Searcher@309ab835[collection1] main
[junit4] 2> 738496 T1243 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 738497 T1243 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 738497 T1243 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 738498 T1243
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 738498 T1243 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 738498 T1243 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 738499 T1243
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 738499 T1243 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 738499 T1243 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 738500 T1243 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 738501 T1244 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@309ab835[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 738501 T1243 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 738502 T1247 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:53890/bs/gv collection:collection1
shard:shard1
[junit4] 2> 738502 T1186 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1
[junit4] 2> 738503 T1186 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 738503 T1247 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 738507 T1247 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 738508 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 738509 T1247
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 738509 T1247 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C211 name=collection1
org.apache.solr.core.SolrCore@589e3679
url=http://127.0.0.1:53890/bs/gv/collection1 node=127.0.0.1:53890_bs%2Fgv
C211_STATE=coll:collection1 core:collection1 props:{core=collection1,
state=down, base_url=http://127.0.0.1:53890/bs/gv,
node_name=127.0.0.1:53890_bs%2Fgv}
[junit4] 2> 738509 T1247 C211 P53890 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:53890/bs/gv/collection1/
[junit4] 2> 738509 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 738509 T1247 C211 P53890 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 738510 T1247 C211 P53890 oasc.SyncStrategy.syncToMe
http://127.0.0.1:53890/bs/gv/collection1/ has no replicas
[junit4] 2> 738510 T1247 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:53890/bs/gv/collection1/ shard1
[junit4] 2> 738510 T1247 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 738511 T1213 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 738511 T1242 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 738512 T1242 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 738514 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:53890/bs/gv";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 738515 T1213 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 738516 T1242 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 738516 T1242 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 738517 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 738564 T1247 oasc.ZkController.register We are
http://127.0.0.1:53890/bs/gv/collection1/ and leader is
http://127.0.0.1:53890/bs/gv/collection1/
[junit4] 2> 738565 T1247 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:53890/bs/gv
[junit4] 2> 738565 T1247 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 738565 T1247 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 738565 T1247 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 738567 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 738567 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53890/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53890_bs%2Fgv",
[junit4] 2> "numShards":"1"} current state version: 5
[junit4] 2> 738568 T1213 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53890/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53890_bs%2Fgv",
[junit4] 2> "numShards":"1"}
[junit4] 2> 738569 T1213 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 738569 T1247 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 738570 T1242 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 738570 T1242 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 739607 T1186 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 739608 T1186 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 739610 T1186 oejs.AbstractConnector.doStart Started
[email protected]:53896
[junit4] 2> 739611 T1186 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@60da5686
[junit4] 2> 739611 T1186 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 739611 T1186 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004
[junit4] 2> 739612 T1186 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/'
[junit4] 2> 739641 T1186 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/solr.xml
[junit4] 2> 739697 T1186 oasc.CoreContainer.<init> New CoreContainer
985638198
[junit4] 2> 739697 T1186 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/]
[junit4] 2> 739698 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 739698 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 739699 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 739700 T1186 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 739701 T1186 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 739701 T1186 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 739701 T1186 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 739701 T1186 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 739702 T1186 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53879/solr
[junit4] 2> 739702 T1186 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 739702 T1186
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 739703 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 739705 T1259 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4b0de448
name:ZooKeeperConnection Watcher:127.0.0.1:53879 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 739706 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 739706 T1186 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 739709 T1186 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 739710 T1262 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@15856359
name:ZooKeeperConnection Watcher:127.0.0.1:53879/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 739710 T1186 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 739715 T1186
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 740718 T1186 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53896_bs%2Fgv
[junit4] 2> 740719 T1186 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:53896_bs%2Fgv
[junit4] 2> 740722 T1186 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 740730 T1263 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 740730 T1263 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 740732 T1212 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 740732 T1263 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 740732 T1263 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 740732 T1213 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53896/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53896_bs%2Fgv",
[junit4] 2> "numShards":"1"} current state version: 5
[junit4] 2> 740733 T1263 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 740733 T1213 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53896/bs/gv";,
[junit4] 2> "node_name":"127.0.0.1:53896_bs%2Fgv",
[junit4] 2> "numShards":"1"}
[junit4] 2> 740733 T1213 oasco.ReplicaMutator.updateState Collection
already exists with numShards=1
[junit4] 2> 740734 T1213 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 740734 T1263 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 740734 T1263 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 740735 T1213 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 740735 T1242 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 740735 T1262 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 740736 T1242 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 740736 T1262 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 741734 T1263 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 741735 T1263 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 741735 T1263 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 741735 T1263 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 741736 T1263 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 741736 T1263 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/'
[junit4] 2> 741738 T1263 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 741739 T1263 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 741739 T1263 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 741773 T1263 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 741775 T1263 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 741810 T1263 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 741857 T1263 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 741958 T1263 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 741971 T1263 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 742323 T1263 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 742326 T1263 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 742328 T1263 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 742332 T1263 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 742350 T1263 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 742351 T1263 oasc.SolrCore.initDirectoryFactory
solr.StandardDirectoryFactory
[junit4] 2> 742351 T1263 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-004/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/
[junit4] 2> 742351 T1263 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@21993c80
[junit4] 2> 742353 T1263 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2
[junit4] 2> 742354 T1263 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index/
[junit4] 2> 742354 T1263 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index'
doesn't exist. Creating new index...
[junit4] 2> 742356 T1263 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index
[junit4] 2> 742357 T1263 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2895872467303534]
[junit4] 2> 742372 T1263 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 742372 T1263 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 742378 T1263 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 742379 T1263 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 742379 T1263 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 742379 T1263 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 742379 T1263 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 742380 T1263 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 742380 T1263 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 742381 T1263 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 742381 T1263 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 742382 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 742382 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 742383 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 742383 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 742384 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 742384 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 742387 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 742387 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 742388 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 742389 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 742389 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 742390 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 742390 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 742391 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 742392 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 742392 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 742393 T1263 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 742393 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 742394 T1263 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 742413 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 742415 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 742416 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 742418 T1263 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 742422 T1263 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 742422 T1263 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 742423 T1263 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27820321960034355]
[junit4] 2> 742425 T1263 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 742425 T1263 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 742426 T1263 oass.SolrIndexSearcher.<init> Opening
Searcher@3394e61f[collection1] main
[junit4] 2> 742426 T1263 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 742427 T1263 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 742427 T1263 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 742428 T1263
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 742428 T1263 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 742428 T1263 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 742429 T1263
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 742429 T1263 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 742429 T1263 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 742429 T1263 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 742430 T1264 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@3394e61f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 742431 T1263 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 742432 T1267 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:53896/bs/gv collection:collection1
shard:shard1
[junit4] 2> 742432 T1186 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1
[junit4] 2> 742432 T1186 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 742434 T1267 oasc.ZkController.register We are
http://127.0.0.1:53896/bs/gv/collection1/ and leader is
http://127.0.0.1:53890/bs/gv/collection1/
[junit4] 2> 742434 T1267 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:53896/bs/gv
[junit4] 2> 742434 T1267 oasc.ZkController.checkRecovery Core needs to
recover:collection1
[junit4] 2> 742434 T1267 oasu.DefaultSolrCoreState.doRecovery Running
recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C212 name=collection1
org.apache.solr.core.SolrCore@718b939f
url=http://127.0.0.1:53896/bs/gv/collection1 node=127.0.0.1:53896_bs%2Fgv
C212_STATE=coll:collection1 core:collection1 props:{core=collection1,
state=down, base_url=http://127.0.0.1:53896/bs/gv,
node_name=127.0.0.1:53896_bs%2Fgv}
[junit4] 2> 742435 T1268 C212 P53896 oasc.RecoveryStrategy.run Starting
recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 742436 T1268 C212 P53896 oasc.RecoveryStrategy.doRecovery
###### startupVe
[...truncated too long message...]
dLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 825490 T1186 oasc.CachingDirectoryFactory.close Closing
StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 825490 T1186 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index;done=false>>]
[junit4] 2> 825491 T1186 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2/index
[junit4] 2> 825491 T1186 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2;done=false>>]
[junit4] 2> 825491 T1186 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001/tempDir-001/jetty2
[junit4] 2> 825492 T1186 oasc.Overseer.close Overseer
(id=93033784810799113-127.0.0.1:53896_bs%2Fgv-n_0000000002) closing
[junit4] 2> 825493 T1286 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:53896_bs%2Fgv
[junit4] 2> 826993 T1282 oascc.ZkStateReader$7.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 826994 T1284 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 826996 T1186 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/bs/gv,null}
[junit4] 2> 831997 T1186 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 831999 T1186 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:53879 53879
[junit4] 2> 832112 T1187 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:53879 53879
[junit4] 2> 832115 T1187 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN
Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 3 /solr/configs/conf1
[junit4] 2> 2 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2
/solr/overseer_elect/election/93033784810799107-127.0.0.1:53885_bs%2Fgv-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ChaosMonkeyNothingIsSafeTest -Dtests.method=testDistribSearch
-Dtests.seed=D36030A63CD25D63 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=vi -Dtests.timezone=Asia/Jakarta -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 101s J1 | ChaosMonkeyNothingIsSafeTest.testDistribSearch
<<<
[junit4] > Throwable #1: java.lang.AssertionError: There were too many
update fails - we expect it can happen, but shouldn't easily
[junit4] > at
__randomizedtesting.SeedInfo.seed([D36030A63CD25D63:5286BEBE4B8D3D5F]:0)
[junit4] > at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.doTest(ChaosMonkeyNothingIsSafeTest.java:223)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 832128 T1186 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest-D36030A63CD25D63-001
[junit4] 2> NOTE: test params are: codec=Lucene50, sim=DefaultSimilarity,
locale=vi, timezone=Asia/Jakarta
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=16,threads=1,free=95258688,total=296747008
[junit4] 2> NOTE: All tests run in this JVM:
[TestWordDelimiterFilterFactory, TestFieldTypeCollectionResource,
TestFileDictionaryLookup, DirectSolrSpellCheckerTest,
TestHighFrequencyDictionaryFactory, MultiThreadedOCPTest,
DistributedFacetPivotLargeTest, BasicFunctionalityTest, DistanceFunctionTest,
TestQueryUtils, TestSerializedLuceneMatchVersion, SoftAutoCommitTest,
PreAnalyzedUpdateProcessorTest, TestPerFieldSimilarity,
TestIndexingPerformance, TestDistributedSearch,
CoreMergeIndexesAdminHandlerTest, TimeZoneUtilsTest, ClusterStateUpdateTest,
TestManagedSchemaFieldTypeResource, SSLMigrationTest,
LeaderFailoverAfterPartitionTest, OutputWriterTest, TestShardHandlerFactory,
DistributedFacetPivotLongTailTest, ChaosMonkeyNothingIsSafeTest]
[junit4] Completed on J1 in 101.00s, 1 test, 1 failure <<< FAILURES!
[...truncated 870 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:529:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:477:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:190:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:508:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:970:
There were test failures: 453 suites, 1855 tests, 1 failure, 61 ignored (23
assumptions)
Total time: 61 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #2381
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 187968352 bytes
Compression is 0.0%
Took 36 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
