Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java7/4917/
1 tests failed.
REGRESSION:
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create
core [halfcollection_shard1_replica1] Caused by: Could not get shard id for
core: halfcollection_shard1_replica1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error
CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core:
halfcollection_shard1_replica1
at
__randomizedtesting.SeedInfo.seed([6B02810174568A1F:EAE40F190309EA23]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:569)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java: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:43)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11549 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/init-core-data-001
[junit4] 2> 844261 T2012 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (true)
[junit4] 2> 844262 T2012 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 844268 T2012 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 844269 T2012 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 844270 T2013 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 844370 T2012 oasc.ZkTestServer.run start zk server on
port:63886
[junit4] 2> 844371 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 844372 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 844375 T2019 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5abf4c18
name:ZooKeeperConnection Watcher:127.0.0.1:63886 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 844375 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 844376 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 844376 T2012 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 844379 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 844380 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 844382 T2021 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2bb0e20d
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 844382 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 844382 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 844382 T2012 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 844385 T2012 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 844386 T2012 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 844388 T2012 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 844389 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 844390 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 844393 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 844393 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 844396 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 844396 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 844398 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 844399 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 844400 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 844401 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 844403 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 844403 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 844405 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 844406 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 844408 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 844408 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 844410 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 844411 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 844412 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 844413 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 844415 T2012 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 844415 T2012 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> Using new style solr.xml
[junit4] 2> 845758 T2012 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 845763 T2012 oejs.AbstractConnector.doStart Started
[email protected]:63895
[junit4] 2> 845763 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 845763 T2012 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 845764 T2012 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002
[junit4] 2> 845764 T2012 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/'
[junit4] 2> 845793 T2012 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solr.xml
[junit4] 2> 845834 T2012 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002
[junit4] 2> 845835 T2012 oasc.CoreContainer.<init> New CoreContainer
1932881063
[junit4] 2> 845835 T2012 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/]
[junit4] 2> 845836 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 845836 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 845836 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 845836 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 845837 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 845837 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 845837 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 845837 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 845837 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 845838 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 845838 T2012 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 845838 T2012 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 845839 T2012 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 845839 T2012 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 845839 T2012 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:63886/solr
[junit4] 2> 845839 T2012 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 845840 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 845840 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 845842 T2032 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@38708308
name:ZooKeeperConnection Watcher:127.0.0.1:63886 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 845842 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 845843 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 845844 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 845845 T2034 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5f8c6ecf
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 845846 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 845847 T2012 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 845849 T2012 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 845851 T2012 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 845853 T2012 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 845854 T2012 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 845856 T2012 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 845857 T2012 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63895_
[junit4] 2> 845858 T2012 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:63895_
[junit4] 2> 845860 T2012 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 845861 T2012 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 845862 T2012 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 845864 T2012 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:63895_
[junit4] 2> 845864 T2012 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 845865 T2012 oasc.Overseer.start Overseer
(id=92600694910222339-127.0.0.1:63895_-n_0000000000) starting
[junit4] 2> 845867 T2012 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 845872 T2012 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 845873 T2036 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 845873 T2012 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 845875 T2012 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 845876 T2012
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 845878 T2035 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 845880 T2012 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002
[junit4] 2> 846496 T2012 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/conf/
[junit4] 2> 846585 T2012 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 846586 T2012 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0
[junit4] 2> 846586 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 846587 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 846588 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 846589 T2040 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@44256d3d
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 846589 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 846589 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 846591 T2012
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 846593 T2012 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 846593 T2012 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 846593 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 846594 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 846595 T2042 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2cf901f5
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 846595 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 846595 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 846597 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 846599 T2035 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: collection1
[junit4] 2> 846600 T2035
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 846600 T2035
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 846602 T2035 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 846603 T2043 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> 846603 T2044 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> 847531 T2012 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 847532 T2012 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 847534 T2012 oejs.AbstractConnector.doStart Started
[email protected]:63901
[junit4] 2> 847535 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 847535 T2012 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 847535 T2012 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003
[junit4] 2> 847536 T2012 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003/'
[junit4] 2> 847565 T2012 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003/solr.xml
[junit4] 2> 847643 T2012 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003
[junit4] 2> 847643 T2012 oasc.CoreContainer.<init> New CoreContainer
1231342161
[junit4] 2> 847643 T2012 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003/]
[junit4] 2> 847644 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 847644 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 847645 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 847645 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 847645 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 847645 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 847645 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 847646 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 847646 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 847646 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 847647 T2012 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 847647 T2012 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 847647 T2012 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 847647 T2012 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 847648 T2012 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:63886/solr
[junit4] 2> 847648 T2012 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 847648 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 847649 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 847650 T2055 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@781eb17b
name:ZooKeeperConnection Watcher:127.0.0.1:63886 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 847651 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 847652 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 847654 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 847655 T2057 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@53d5d73 name:ZooKeeperConnection
Watcher:127.0.0.1:63886/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 847655 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 847660 T2012
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 848664 T2012 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63901_
[junit4] 2> 848665 T2012 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:63901_
[junit4] 2> 848668 T2012 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 848672 T2012 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003
[junit4] 2> 848680 T2012 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-003/conf/
[junit4] 2> 849845 T2012 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 849846 T2012 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0
[junit4] 2> 849846 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 850769 T2012 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 850770 T2012 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 850772 T2012 oejs.AbstractConnector.doStart Started
[email protected]:63905
[junit4] 2> 850773 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 850773 T2012 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 850773 T2012 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004
[junit4] 2> 850773 T2012 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/'
[junit4] 2> 850802 T2012 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solr.xml
[junit4] 2> 850843 T2012 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004
[junit4] 2> 850844 T2012 oasc.CoreContainer.<init> New CoreContainer
1510164799
[junit4] 2> 850844 T2012 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/]
[junit4] 2> 850845 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 850845 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 850845 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 850845 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 850846 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 850846 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 850846 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 850846 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 850847 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 850847 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 850847 T2012 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 850847 T2012 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 850848 T2012 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 850848 T2012 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 850848 T2012 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:63886/solr
[junit4] 2> 850848 T2012 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 850849 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 850849 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 850851 T2070 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@dbdf544 name:ZooKeeperConnection
Watcher:127.0.0.1:63886 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 850851 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 850852 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 850854 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 850855 T2072 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2c40364e
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 850855 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 850860 T2012
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 851865 T2012 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63905_
[junit4] 2> 851866 T2012 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:63905_
[junit4] 2> 851869 T2012 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 851873 T2012 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004
[junit4] 2> 851876 T2012 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/conf/
[junit4] 2> 852873 T2012 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 852875 T2012 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0
[junit4] 2> 852875 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 853788 T2012 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 853788 T2012 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 853791 T2012 oejs.AbstractConnector.doStart Started
[email protected]:63909
[junit4] 2> 853791 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 853792 T2012 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 853792 T2012 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005
[junit4] 2> 853792 T2012 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/'
[junit4] 2> 853821 T2012 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solr.xml
[junit4] 2> 853861 T2012 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005
[junit4] 2> 853862 T2012 oasc.CoreContainer.<init> New CoreContainer
812579299
[junit4] 2> 853862 T2012 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/]
[junit4] 2> 853863 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 853863 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 853863 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 853863 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 853864 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 853864 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 853864 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 853864 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 853864 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 853865 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 853865 T2012 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 853865 T2012 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 853866 T2012 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 853866 T2012 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 853866 T2012 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:63886/solr
[junit4] 2> 853866 T2012 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 853867 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 853867 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 853869 T2085 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@45d7beec
name:ZooKeeperConnection Watcher:127.0.0.1:63886 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 853870 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 853870 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 853872 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 853873 T2087 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2b2e740b
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 853873 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 853878 T2012
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 854882 T2012 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63909_
[junit4] 2> 854883 T2012 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:63909_
[junit4] 2> 854886 T2012 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 854890 T2012 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005
[junit4] 2> 854946 T2012 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/conf/
[junit4] 2> 855877 T2012 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 855878 T2012 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0
[junit4] 2> 855878 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 857090 T2012 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 4
[junit4] 2> 857091 T2012 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 857093 T2012 oejs.AbstractConnector.doStart Started
[email protected]:63916
[junit4] 2> 857094 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 857094 T2012 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 857094 T2012 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006
[junit4] 2> 857095 T2012 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/'
[junit4] 2> 857154 T2012 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/solr.xml
[junit4] 2> 857240 T2012 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006
[junit4] 2> 857241 T2012 oasc.CoreContainer.<init> New CoreContainer
174367593
[junit4] 2> 857241 T2012 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/]
[junit4] 2> 857242 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 857242 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 857242 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 857243 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 857243 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 857243 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 857243 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 857243 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 857244 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 857244 T2012 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 857244 T2012 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 857245 T2012 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 857245 T2012 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 857245 T2012 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 857246 T2012 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:63886/solr
[junit4] 2> 857246 T2012 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 857246 T2012
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 857247 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 857248 T2100 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@552447d3
name:ZooKeeperConnection Watcher:127.0.0.1:63886 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 857249 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 857250 T2012 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 857251 T2012 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 857252 T2102 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@224ea55f
name:ZooKeeperConnection Watcher:127.0.0.1:63886/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 857253 T2012 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 857259 T2012
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 858264 T2012 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63916_
[junit4] 2> 858265 T2012 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:63916_
[junit4] 2> 858268 T2012 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 858273 T2012 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006
[junit4] 2> 859329 T2012 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/conf/
[junit4] 2> 859387 T2012 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 859388 T2012 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0
[junit4] 2> 859388 T2012 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 859399 T2047 oasha.CollectionsHandler.handleCreateAction
Creating Collection :
collection.configName=conf1&action=CREATE&replicationFactor=2&router.field=myOwnField&version=2&name=solrj_collection&wt=javabin&numShards=2
[junit4] 2> 859401 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 859401 T2036 oasc.OverseerCollectionProcessor.run Overseer
Collection Processor: Get the message
id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 859403 T2105 oasc.OverseerCollectionProcessor.processMessage
WARN OverseerCollectionProcessor.processMessage : create , {
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 859403 T2105 oasc.OverseerCollectionProcessor.createConfNode
creating collections conf node /collections/solrj_collection
[junit4] 2> 859404 T2105 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection
[junit4] 2> 859406 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 859409 T2035 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: solrj_collection
[junit4] 2> 859409 T2035
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
solrj_collection with shards [shard1, shard2]
[junit4] 2> 859409 T2035
oasc.Overseer$ClusterStateUpdater.createCollection state version
solrj_collection 1
[junit4] 2> 859411 T2103 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859411 T2044 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859411 T2073 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859411 T2088 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859411 T2058 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859411 T2043 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859507 T2105 oasc.OverseerCollectionProcessor.createCollection
Creating SolrCores for new collection solrj_collection, shardNames [shard1,
shard2] , replicationFactor : 2
[junit4] 2> 859508 T2105 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:63905_
[junit4] 2> 859508 T2105 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:63909_
[junit4] 2> 859510 T2105 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:63895_
[junit4] 2> 859511 T2105 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:63916_
[junit4] 2> 859512 T2063 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard1&collection=solrj_collection&collection.configName=conf1&action=CREATE&version=2&name=solrj_collection_shard1_replica1&wt=javabin&numShards=2
[junit4] 2> 859512 T2081 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard1&collection=solrj_collection&collection.configName=conf1&action=CREATE&version=2&name=solrj_collection_shard1_replica2&wt=javabin&numShards=2
[junit4] 2> 859512 T2027 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard2&collection=solrj_collection&collection.configName=conf1&action=CREATE&version=2&name=solrj_collection_shard2_replica1&wt=javabin&numShards=2
[junit4] 2> 859514 T2092 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard2&collection=solrj_collection&collection.configName=conf1&action=CREATE&version=2&name=solrj_collection_shard2_replica2&wt=javabin&numShards=2
[junit4] 2> 859515 T2063 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica1 state=down collection=solrj_collection
[junit4] 2> 859515 T2081 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica2 state=down collection=solrj_collection
[junit4] 2> 859516 T2063 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 859516 T2081 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 859516 T2027 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica1 state=down collection=solrj_collection
[junit4] 2> 859516 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 859516 T2092 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica2 state=down collection=solrj_collection
[junit4] 2> 859517 T2027 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 859518 T2092 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 859520 T2035 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:63905";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "node_name":"127.0.0.1:63905_",
[junit4] 2> "numShards":"2",
[junit4] 2> "roles":null}
[junit4] 2> 859522 T2035 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:63909";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "node_name":"127.0.0.1:63909_",
[junit4] 2> "numShards":"2",
[junit4] 2> "roles":null}
[junit4] 2> 859524 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 859524 T2035 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "base_url":"http://127.0.0.1:63895";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "node_name":"127.0.0.1:63895_",
[junit4] 2> "numShards":"2",
[junit4] 2> "roles":null}
[junit4] 2> 859527 T2035 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "base_url":"http://127.0.0.1:63916";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "node_name":"127.0.0.1:63916_",
[junit4] 2> "numShards":"2",
[junit4] 2> "roles":null}
[junit4] 2> 859528 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 859529 T2058 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859529 T2044 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859529 T2073 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859529 T2043 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859529 T2103 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 859529 T2088 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 860517 T2063 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 860517 T2063 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 860519 T2027 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 860517 T2081 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 860519 T2027 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 860520 T2081 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 860521 T2092 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 860521 T2092 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 860520 T2063 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 860523 T2063 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 860523 T2027 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 860524 T2027 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 860524 T2081 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 860524 T2092 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 860526 T2092 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 860526 T2081 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 860526 T2063 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 860529 T2063 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/'
[junit4] 2> 860531 T2027 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 860531 T2027 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/'
[junit4] 2> 860542 T2081 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 860542 T2081 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/'
[junit4] 2> 860549 T2092 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 860550 T2092 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/solrj_collection_shard2_replica2/'
[junit4] 2> 861316 T2027 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 861322 T2081 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 861351 T2063 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 861457 T2092 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 861484 T2027 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 861488 T2027 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 861510 T2081 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 861520 T2027 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 861520 T2081 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 861540 T2063 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 861543 T2063 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 861555 T2081 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 861560 T2063 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 861930 T2092 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 861934 T2092 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 862020 T2092 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 862570 T2027 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 862585 T2027 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 862588 T2027 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 862602 T2027 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 862608 T2027 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 862615 T2027 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 862616 T2027
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 862618 T2027
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 862619 T2027 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 862620 T2027
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 862620 T2027
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 862620 T2027 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica1' using configuration from collection
solrj_collection
[junit4] 2> 862621 T2027 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 862621 T2027 oasc.SolrCore.<init>
[solrj_collection_shard2_replica1] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/data/
[junit4] 2> 862622 T2027 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@302197f7
[junit4] 2> 862623 T2027 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/data
[junit4] 2> 862623 T2027 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 862623 T2027 oasc.SolrCore.initIndex WARN
[solrj_collection_shard2_replica1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 862624 T2027 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-002/solrj_collection_shard2_replica1/data/index
[junit4] 2> 862625 T2027 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=28, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=68.2724609375,
floorSegmentMB=0.693359375, forceMergeDeletesPctAllowed=26.907761812362477,
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 862626 T2027 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-6f0bc07b)),segFN=segments_1,generation=1}
[junit4] 2> 862626 T2027 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 862632 T2027 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 862632 T2027 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 862633 T2027 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 862633 T2027 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 862634 T2027 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 862634 T2027 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 862635 T2027 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 862635 T2027 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 862635 T2027 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 862636 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862637 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862637 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862638 T2027 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 862638 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 862639 T2027 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 862639 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 862640 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 862640 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 862641 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 862641 T2027 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 862655 T2063 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 862662 T2063 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 862665 T2063 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 862666 T2081 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 862674 T2027 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862677 T2027 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862678 T2081 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 862680 T2027 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862681 T2081 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 862683 T2027 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862685 T2063 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 862689 T2063 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 862689 T2027 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 862690 T2027 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 862691 T2027 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=41, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=86.5380859375,
floorSegmentMB=0.275390625, forceMergeDeletesPctAllowed=8.58910146999692,
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 862691 T2027 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-6f0bc07b)),segFN=segments_1,generation=1}
[junit4] 2> 862692 T2027 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 862692 T2027 oass.SolrIndexSearcher.<init> Opening
Searcher@37d9d3c4[solrj_collection_shard2_replica1] main
[junit4] 2> 862692 T2027 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 862693 T2027 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 862693 T2027 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 862694 T2063 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 862694 T2027
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 862695 T2027 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 862695 T2063
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 862694 T2081 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 862696 T2063
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 862695 T2027 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 862696 T2063 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 862697 T2027
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 862697 T2027 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 862698 T2063
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 862698 T2027 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 862698 T2063
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 862699 T2063 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica1' using configuration from collection
solrj_collection
[junit4] 2> 862699 T2063 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 862700 T2081 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 862700 T2063 oasc.SolrCore.<init>
[solrj_collection_shard1_replica1] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/data/
[junit4] 2> 862700 T2027
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 862700 T2063 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@302197f7
[junit4] 2> 862701 T2027 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 862702 T2027 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 862703 T2063 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/data
[junit4] 2> 862703 T2063 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/data/index/
[junit4] 2> 862703 T2063 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 862704 T2063 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-004/solrj_collection_shard1_replica1/data/index
[junit4] 2> 862705 T2063 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=28, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=68.2724609375,
floorSegmentMB=0.693359375, forceMergeDeletesPctAllowed=26.907761812362477,
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 862706 T2110 oasc.SolrCore.registerSearcher
[solrj_collection_shard2_replica1] Registered new searcher
Searcher@37d9d3c4[solrj_collection_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 862706 T2063 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-6545c4a9)),segFN=segments_1,generation=1}
[junit4] 2> 862706 T2063 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 862707 T2081 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 862708 T2081
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 862708 T2081
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 862709 T2081 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 862709 T2027 oasc.CoreContainer.registerCore registering core:
solrj_collection_shard2_replica1
[junit4] 2> 862709 T2027 oasc.ZkController.register Register replica -
core:solrj_collection_shard2_replica1 address:http://127.0.0.1:63895
collection:solrj_collection shard:shard2
[junit4] 2> 862709 T2081
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 862710 T2081
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 862710 T2081 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica2' using configuration from collection
solrj_collection
[junit4] 2> 862710 T2027 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection/leader_elect/shard2/election
[junit4] 2> 862711 T2081 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 862711 T2063 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 862711 T2081 oasc.SolrCore.<init>
[solrj_collection_shard1_replica2] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/data/
[junit4] 2> 862712 T2063 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 862712 T2081 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@302197f7
[junit4] 2> 862712 T2063 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 862713 T2063 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 862713 T2063 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 862713 T2081 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/data
[junit4] 2> 862713 T2063 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 862714 T2081 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/data/index/
[junit4] 2> 862714 T2063 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 862714 T2081 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica2] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/data/index'
doesn't exist. Creating new index...
[junit4] 2> 862715 T2063 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 862715 T2081 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-005/solrj_collection_shard1_replica2/data/index
[junit4] 2> 862715 T2027 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 862715 T2063 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 862716 T2081 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=28, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=68.2724609375,
floorSegmentMB=0.693359375, forceMergeDeletesPctAllowed=26.907761812362477,
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 862716 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862717 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862717 T2043 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 862717 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862717 T2081 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-65102789)),segFN=segments_1,generation=1}
[junit4] 2> 862717 T2027
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Waiting until we see
more replicas up for shard shard2: total=2 found=1 timeoutin=7.0873779E15ms
[junit4] 2> 862718 T2063 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 862718 T2081 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 862719 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 862719 T2063 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 862720 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 862720 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 862721 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 862722 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 862722 T2073 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 862722 T2063 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 862723 T2088 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 862722 T2043 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 862722 T2103 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 862722 T2044 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 862722 T2058 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 862725 T2081 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 862725 T2063 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862726 T2081 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 862726 T2081 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 862727 T2081 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 862727 T2081 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 862728 T2081 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 862728 T2063 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862728 T2081 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 862729 T2081 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 862729 T2081 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 862730 T2063 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862730 T2081 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862731 T2081 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862731 T2081 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 862732 T2063 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 862732 T2081 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 862733 T2081 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 862733 T2081 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 862733 T2081 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 862734
[...truncated too long message...]
yId=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 1574275 T2012 oasu.SolrCoreState.decrefSolrCoreState Closing
SolrCoreState
[junit4] 2> 1574275 T2012 oasu.DefaultSolrCoreState.closeIndexWriter
SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1574276 T2012 oasu.DefaultSolrCoreState.closeIndexWriter
closing IndexWriter with IndexWriterCloser
[junit4] 2> 1574276 T2012 oasc.SolrCore.closeSearcher
[nodes_used_collection_shard2_replica1] Closing main searcher on request.
[junit4] 2> 1574277 T2012 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1574277 T2012 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/nodes_used_collection_shard2_replica1/data/index
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/nodes_used_collection_shard2_replica1/data/index;done=false>>]
[junit4] 2> 1574278 T2012 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/nodes_used_collection_shard2_replica1/data/index
[junit4] 2> 1574278 T2012 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/nodes_used_collection_shard2_replica1/data
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/nodes_used_collection_shard2_replica1/data;done=false>>]
[junit4] 2> 1574278 T2012 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001/tempDir-006/nodes_used_collection_shard2_replica1/data
[junit4] 2> 1574279 T2459 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> 1574279 T2012 oasc.Overseer.close Overseer
(id=92600694910222349-127.0.0.1:63916_-n_0000000004) closing
[junit4] 2> 1574280 T2477 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:63916_
[junit4] 2> 1575780 T2459 oascc.ZkStateReader$2.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1575781 T2466 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1575782 T2012 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1579223 T2012 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 1579225 T2012 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:63886 63886
[junit4] 2> 1579445 T2224 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:63886 63886
[junit4] 2> 1579446 T2224 oasc.ZkTestServer$ZKServerMain.runFromConfig
WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5
/solr/collections/awholynewstresscollection_collection1_0/state.json
[junit4] 2> 5
/solr/collections/awholynewstresscollection_collection0_0/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 2
/solr/collections/awholynewcollection_2/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch
-Dtests.seed=6B02810174568A1F -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=is_IS -Dtests.timezone=Africa/Monrovia
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 735s J0 |
CollectionsAPIDistributedZkTest.testDistribSearch <<<
[junit4] > Throwable #1:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error
CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core:
halfcollection_shard1_replica1
[junit4] > at
__randomizedtesting.SeedInfo.seed([6B02810174568A1F:EAE40F190309EA23]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:569)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1579461 T2012 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest-6B02810174568A1F-001
[junit4] 2> 735204 T2011 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{range_facet_l=FSTOrd41, a_i1=FSTOrd41, _version_=Lucene41(blocksize=128),
multiDefault=FSTOrd41, intDefault=Lucene41(blocksize=128),
text=OrdsLucene41(blocksize=128), timestamp=FSTOrd41, a_t=FSTOrd41,
id=PostingsFormat(name=Lucene41VarGapFixedInterval),
other_tl1=Lucene41(blocksize=128)},
docValues:{timestamp=DocValuesFormat(name=Memory)},
sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=is_IS,
timezone=Africa/Monrovia
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=16,threads=1,free=114501608,total=354418688
[junit4] 2> NOTE: All tests run in this JVM:
[TestDefaultSearchFieldResource, TestBinaryResponseWriter, TestSchemaResource,
TestRestManager, BlockDirectoryTest, SolrCmdDistributorTest, TestFaceting,
TestJmxIntegration, TestCollectionAPI, TriLevelCompositeIdRoutingTest,
TestRangeQuery, HdfsDirectoryTest, TestReRankQParserPlugin, CacheHeaderTest,
TestXIncludeConfig, TestDistribDocBasedVersion, JsonLoaderTest, TestFiltering,
ChangedSchemaMergeTest, TestSolrXml, TestRawResponseWriter, TestQueryTypes,
TestStressReorder, ReturnFieldsTest, FileUtilsTest, TestFoldingMultitermQuery,
SuggestComponentTest, DistanceFunctionTest, TermsComponentTest,
MinimalSchemaTest, TestPostingsSolrHighlighter, AlternateDirectoryTest,
HdfsBasicDistributedZkTest, TestExpandComponent, SoftAutoCommitTest,
SpellingQueryConverterTest, TestBulkSchemaConcurrent,
ChaosMonkeyNothingIsSafeTest, TestDefaultSimilarityFactory,
BasicDistributedZkTest, TestRTGBase, BlockCacheTest, ConvertedLegacyTest,
PrimUtilsTest, ConnectionManagerTest, TestArbitraryIndexDir,
TestFileDictionaryLookup, SynonymTokenizerTest, TestSolrJ,
TestCursorMarkWithoutUniqueKey, TestManagedSchemaDynamicFieldResource,
TestCloudSchemaless, TestLeaderElectionZkExpiry,
CollectionsAPIDistributedZkTest]
[junit4] Completed on J0 in 735.49s, 1 test, 1 error <<< FAILURES!
[...truncated 834 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:524:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:472:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:61:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build.xml:189:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/common-build.xml:496:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:1359:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:961:
There were test failures: 437 suites, 1784 tests, 1 error, 57 ignored (22
assumptions)
Total time: 80 minutes 7 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-trunk-Java7 #4916
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 156906603 bytes
Compression is 0.0%
Took 28 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]
