Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/693/
No tests ran.
Build Log:
[...truncated 9836 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/init-core-data-001
[junit4] 2> 166078 T841 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 166078 T841 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 166102 T841 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 166103 T842 oasc.ZkTestServer$2$1.setClientPort client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 166103 T842 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 166203 T841 oasc.ZkTestServer.run start zk server on port:50627
[junit4] 2> 166203 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 166219 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 166222 T849 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@79188e64
name:ZooKeeperConnection Watcher:127.0.0.1:50627 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 166222 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 166223 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 166223 T841 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 166232 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 166233 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 166244 T852 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@67536413
name:ZooKeeperConnection Watcher:127.0.0.1:50627/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 166244 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 166245 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 166245 T841 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 166248 T841 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 166250 T841 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 166252 T841 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 166253 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 166259 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 166262 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 166262 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 166278 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 166278 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 166284 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 166285 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 166286 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 166286 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 166288 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 166288 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 166289 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 166290 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 166296 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 166297 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 166299 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 166299 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 166308 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 166308 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 166317 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 166317 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 166320 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 166321 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 166327 T855 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@70e4798f
name:ZooKeeperConnection Watcher:127.0.0.1:50627 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 166327 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 166328 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 166328 T841 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 166359 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 166380 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 166385 T858 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@671e13c5
name:ZooKeeperConnection Watcher:127.0.0.1:50627/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 166385 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 166386 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 166386 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf2/solrconfig.xml
[junit4] 2> 166387 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/solrconfig.xml
[junit4] 2> 166389 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf2/schema.xml
[junit4] 2> 166389 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/schema.xml
[junit4] 2> 166391 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf2/enumsConfig.xml
[junit4] 2> 166391 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/enumsConfig.xml
[junit4] 2> 166393 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 166393 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 166395 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf2/stopwords.txt
[junit4] 2> 166395 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/stopwords.txt
[junit4] 2> 166396 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf2/protwords.txt
[junit4] 2> 166397 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/protwords.txt
[junit4] 2> 166398 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf2/currency.xml
[junit4] 2> 166398 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/currency.xml
[junit4] 2> 166400 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf2/open-exchange-rates.json
[junit4] 2> 166400 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/open-exchange-rates.json
[junit4] 2> 166402 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 166402 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 166403 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf2/old_synonyms.txt
[junit4] 2> 166404 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/old_synonyms.txt
[junit4] 2> 166405 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf2/synonyms.txt
[junit4] 2> 166406 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/synonyms.txt
[junit4] 2> 166407 T841 oasc.AbstractZkTestCase.putConfig put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml
to /configs/conf2/elevate.xml
[junit4] 2> 166407 T841 oascc.SolrZkClient.makePath makePath:
/configs/conf2/elevate.xml
[junit4] 2> 166853 T841 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1
[junit4] 2> 166862 T841 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 166864 T841 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@7e7baff1{/,null,AVAILABLE}
[junit4] 2> 166864 T841 oejs.AbstractConnector.doStart Started
ServerConnector@470dceea{HTTP/1.1}{127.0.0.1:57722}
[junit4] 2> 166865 T841 oejs.Server.doStart Started @168823ms
[junit4] 2> 166865 T841 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties: {hostContext=/, hostPort=57722,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores}
[junit4] 2> 166865 T841 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 166866 T841 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/'
[junit4] 2> 166888 T841 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/solr.xml
[junit4] 2> 166907 T841 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores
[junit4] 2> 166908 T841 oasc.CoreContainer.<init> New CoreContainer
313342638
[junit4] 2> 166908 T841 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/]
[junit4] 2> 166908 T841 oasc.CoreContainer.load loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/lib
[junit4] 2> 166908 T841 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/lib).
[junit4] 2> 166920 T841 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 166922 T841 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 166923 T841 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 166923 T841 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 166923 T841 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 166923 T841 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:50627/solr
[junit4] 2> 166923 T841 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 166924 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 166925 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 166936 T872 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@759a7633
name:ZooKeeperConnection Watcher:127.0.0.1:50627 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 166942 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 166942 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 166952 T841 n:127.0.0.1:57722_
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 166956 T875 n:127.0.0.1:57722_ oascc.ConnectionManager.process
Watcher org.apache.solr.common.cloud.ConnectionManager@7c6ddc30
name:ZooKeeperConnection Watcher:127.0.0.1:50627/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 166956 T841 n:127.0.0.1:57722_
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 166957 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer/queue
[junit4] 2> 166959 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-queue-work
[junit4] 2> 166961 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-map-running
[junit4] 2> 166965 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-map-completed
[junit4] 2> 166969 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-map-failure
[junit4] 2> 166971 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /live_nodes
[junit4] 2> 166972 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /aliases.json
[junit4] 2> 166973 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /clusterstate.json
[junit4] 2> 166974 T841 n:127.0.0.1:57722_
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:57722_
[junit4] 2> 166974 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /live_nodes/127.0.0.1:57722_
[junit4] 2> 166976 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer_elect
[junit4] 2> 166977 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer_elect/election
[junit4] 2> 166979 T841 n:127.0.0.1:57722_ oasc.Overseer.close Overseer
(id=null) closing
[junit4] 2> 166981 T841 n:127.0.0.1:57722_
oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader
127.0.0.1:57722_
[junit4] 2> 166981 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer_elect/leader
[junit4] 2> 166982 T841 n:127.0.0.1:57722_ oasc.Overseer.start Overseer
(id=93888555935989765-127.0.0.1:57722_-n_0000000000) starting
[junit4] 2> 166983 T841 n:127.0.0.1:57722_ oascc.SolrZkClient.makePath
makePath: /overseer/queue-work
[junit4] 2> 166988 T841 n:127.0.0.1:57722_
oasc.OverseerAutoReplicaFailoverThread.<init> Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 166993 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 167018 T841 n:127.0.0.1:57722_
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 167018 T877 n:127.0.0.1:57722_
oasc.OverseerCollectionProcessor.run Process current queue of collection
creations
[junit4] 2> 167027 T841 n:127.0.0.1:57722_
oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
[junit4] 2> 167027 T841 n:127.0.0.1:57722_
oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 167028 T841 n:127.0.0.1:57722_
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores
[junit4] 2> 167029 T841 n:127.0.0.1:57722_ oasc.CoreDescriptor.<init> CORE
DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1,
collection=control_collection,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/, coreNodeName=,
dataDir=data/, shard=}
[junit4] 2> 167030 T841 n:127.0.0.1:57722_
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/
[junit4] 2> 167030 T841 n:127.0.0.1:57722_
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 167039 T879 n:127.0.0.1:57722_ c:control_collection
x:collection1 oasc.ZkController.publish publishing core=collection1 state=down
collection=control_collection
[junit4] 2> 167040 T879 n:127.0.0.1:57722_ c:control_collection
x:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 167041 T879 n:127.0.0.1:57722_
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 167062 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 167063 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:57722";,
[junit4] 2> "node_name":"127.0.0.1:57722_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 167063 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:57722";,
[junit4] 2> "node_name":"127.0.0.1:57722_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 167063 T876 n:127.0.0.1:57722_
oasco.ClusterStateMutator.createCollection building a new cName:
control_collection
[junit4] 2> 167064 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 167078 T875 n:127.0.0.1:57722_ 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> 168041 T879 n:127.0.0.1:57722_
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for
collection1
[junit4] 2> 168041 T879 n:127.0.0.1:57722_
oasc.ZkController.createCollectionZkNode Check for collection
zkNode:control_collection
[junit4] 2> 168044 T879 n:127.0.0.1:57722_
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 168044 T879 n:127.0.0.1:57722_
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/control_collection
[junit4] 2> 168045 T879 n:127.0.0.1:57722_
oascc.ZkStateReader.readConfigName path=/collections/control_collection
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 168045 T879 n:127.0.0.1:57722_ oasc.SolrResourceLoader.<init>
new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/'
[junit4] 2> 168063 T879 n:127.0.0.1:57722_ oasc.Config.<init> loaded
config solrconfig.xml with version 0
[junit4] 2> 168069 T879 n:127.0.0.1:57722_
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 168073 T879 n:127.0.0.1:57722_ oasc.SolrConfig.<init> Using
Lucene MatchVersion: 6.0.0
[junit4] 2> 168081 T879 n:127.0.0.1:57722_ oasc.SolrConfig.<init> Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 168083 T879 n:127.0.0.1:57722_ oass.IndexSchema.readSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 168090 T879 n:127.0.0.1:57722_ oass.IndexSchema.readSchema
[collection1] Schema name=test
[junit4] 2> 168203 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider.init Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 168224 T879 n:127.0.0.1:57722_ oass.IndexSchema.readSchema
default search field in schema is text
[junit4] 2> 168226 T879 n:127.0.0.1:57722_ oass.IndexSchema.readSchema
unique key field: id
[junit4] 2> 168244 T879 n:127.0.0.1:57722_
oass.FileExchangeRateProvider.reload Reloading exchange rates from file
currency.xml
[junit4] 2> 168248 T879 n:127.0.0.1:57722_
oass.FileExchangeRateProvider.reload Reloading exchange rates from file
currency.xml
[junit4] 2> 168250 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 168251 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 168251 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 168251 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 168252 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 168252 T879 n:127.0.0.1:57722_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 168252 T879 n:127.0.0.1:57722_ oasc.CoreContainer.create
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 168252 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 168252 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/], dataDir=[null]
[junit4] 2> 168253 T879 n:127.0.0.1:57722_ x:collection1
oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to
JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@69bc2553
[junit4] 2> 168253 T879 n:127.0.0.1:57722_ x:collection1
oasc.CachingDirectoryFactory.get return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/data
[junit4] 2> 168253 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.getNewIndexDir New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/data/index/
[junit4] 2> 168254 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.initIndex WARN [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/data/index' doesn't exist.
Creating new index...
[junit4] 2> 168254 T879 n:127.0.0.1:57722_ x:collection1
oasc.CachingDirectoryFactory.get return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/control-001/cores/collection1/data/index
[junit4] 2> 168254 T879 n:127.0.0.1:57722_ x:collection1
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 168255 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1515798f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@242a0f5b),segFN=segments_1,generation=1}
[junit4] 2> 168255 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 168259 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 168259 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 168259 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 168260 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 168260 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 168260 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 168260 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 168260 T879 n:127.0.0.1:57722_ x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 168261 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 168262 T879 n:127.0.0.1:57722_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 168264 T879 n:127.0.0.1:57722_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 168265 T879 n:127.0.0.1:57722_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 168266 T879 n:127.0.0.1:57722_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 168276 T879 n:127.0.0.1:57722_ x:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 168277 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.initStatsCache Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 168280 T879 n:127.0.0.1:57722_ x:collection1
oasu.UpdateHandler.<init> Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 168281 T879 n:127.0.0.1:57722_ x:collection1
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 168282 T879 n:127.0.0.1:57722_ x:collection1
oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 168282 T879 n:127.0.0.1:57722_ x:collection1
oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 168287 T879 n:127.0.0.1:57722_ x:collection1
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@30530339
[junit4] 2> 168306 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1515798f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@242a0f5b),segFN=segments_1,generation=1}
[junit4] 2> 168307 T879 n:127.0.0.1:57722_ x:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 168307 T879 n:127.0.0.1:57722_ x:collection1
oass.SolrIndexSearcher.<init> Opening Searcher@111af7d0[collection1] main
[junit4] 2> 168308 T879 n:127.0.0.1:57722_ x:collection1
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/control_collection
[junit4] 2> 168309 T879 n:127.0.0.1:57722_ x:collection1
oascc.ZkStateReader.readConfigName path=/collections/control_collection
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 168309 T879 n:127.0.0.1:57722_ x:collection1
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for
the RestManager with znodeBase: /configs/conf1
[junit4] 2> 168309 T879 n:127.0.0.1:57722_ x:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 168309 T879 n:127.0.0.1:57722_ x:collection1
oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 168309 T879 n:127.0.0.1:57722_ x:collection1
oasr.ManagedResourceStorage.load Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 168310 T879 n:127.0.0.1:57722_ x:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 168310 T879 n:127.0.0.1:57722_ x:collection1
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 168310 T879 n:127.0.0.1:57722_ x:collection1
oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 168310 T879 n:127.0.0.1:57722_ x:collection1
oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 168311 T879 n:127.0.0.1:57722_ x:collection1
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 168311 T880 n:127.0.0.1:57722_ x:collection1
oasc.SolrCore.registerSearcher [collection1] Registered new searcher
Searcher@111af7d0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 168313 T879 n:127.0.0.1:57722_ x:collection1
oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value
of version field
[junit4] 2> 168313 T879 n:127.0.0.1:57722_ x:collection1
oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_
for 65536 version buckets from index
[junit4] 2> 168313 T879 n:127.0.0.1:57722_ x:collection1
oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot
seed version bucket highest value from index
[junit4] 2> 168313 T879 n:127.0.0.1:57722_ x:collection1
oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in
index or recent updates, using new clock 1502216897287946240
[junit4] 2> 168314 T879 n:127.0.0.1:57722_ x:collection1
oasu.UpdateLog.seedBucketsWithHighestVersion Took 1 ms to seed version buckets
with highest version 1502216897287946240
[junit4] 2> 168315 T879 n:127.0.0.1:57722_ x:collection1
oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 168319 T841 n:127.0.0.1:57722_ oass.SolrDispatchFilter.init
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 168319 T841 n:127.0.0.1:57722_ oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 168321 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ZkController.register Register replica - core:collection1
address:http://127.0.0.1:57722 collection:control_collection shard:shard1
[junit4] 2> 168321 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 168329 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 168331 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 168335 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the
leader process for shard shard1
[junit4] 2> 168336 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 168337 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough
replicas found to continue.
[junit4] 2> 168337 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new
leader - try and sync
[junit4] 2> 168337 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> ASYNC NEW_CORE C55 name=collection1
org.apache.solr.core.SolrCore@3aecd8e url=http://127.0.0.1:57722/collection1
node=127.0.0.1:57722_ C55_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:57722,
node_name=127.0.0.1:57722_, state=down}
[junit4] 2> 168337 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 C55 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:57722/collection1/
[junit4] 2> 168338 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 C55 oasc.SyncStrategy.syncReplicas Sync Success - now sync
replicas to me
[junit4] 2> 168338 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 C55 oasc.SyncStrategy.syncToMe
http://127.0.0.1:57722/collection1/ has no replicas
[junit4] 2> 168338 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new
leader: http://127.0.0.1:57722/collection1/ shard1
[junit4] 2> 168338 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 168340 T886 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@564b0a8f
name:ZooKeeperConnection Watcher:127.0.0.1:50627/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 168341 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 168341 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 168341 T841
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 168343 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 168343 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:57722";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 168347 T841 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 168446 T886 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> 168446 T875 n:127.0.0.1:57722_ 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> 168495 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ZkController.register We are
http://127.0.0.1:57722/collection1/ and leader is
http://127.0.0.1:57722/collection1/
[junit4] 2> 168495 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:57722
[junit4] 2> 168495 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery
necessary
[junit4] 2> 168495 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ZkController.publish publishing core=collection1
state=active collection=control_collection
[junit4] 2> 168495 T883 n:127.0.0.1:57722_ c:control_collection s:shard1
x:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 168497 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 168498 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:57722";,
[junit4] 2> "node_name":"127.0.0.1:57722_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 168499 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:57722";,
[junit4] 2> "node_name":"127.0.0.1:57722_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 168601 T886 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> 168604 T875 n:127.0.0.1:57722_ 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> 168677 T841 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1
[junit4] 2> 168679 T841 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001
[junit4] 2> 168679 T841 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 168694 T841 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@1ba675de{/,null,AVAILABLE}
[junit4] 2> 168695 T841 oejs.AbstractConnector.doStart Started
ServerConnector@3a69e080{HTTP/1.1}{127.0.0.1:46305}
[junit4] 2> 168695 T841 oejs.Server.doStart Started @170653ms
[junit4] 2> 168695 T841 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties: {solrconfig=solrconfig.xml, hostContext=/, hostPort=46305,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores}
[junit4] 2> 168696 T841 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 168696 T841 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/'
[junit4] 2> 168712 T841 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/solr.xml
[junit4] 2> 168717 T841 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores
[junit4] 2> 168718 T841 oasc.CoreContainer.<init> New CoreContainer
683490839
[junit4] 2> 168718 T841 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/]
[junit4] 2> 168718 T841 oasc.CoreContainer.load loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/lib
[junit4] 2> 168719 T841 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/lib).
[junit4] 2> 168748 T841 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 168749 T841 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 168750 T841 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 168750 T841 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 168751 T841 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 168751 T841 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:50627/solr
[junit4] 2> 168751 T841 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 168751 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 168752 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 168789 T900 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7230eac4
name:ZooKeeperConnection Watcher:127.0.0.1:50627 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 168793 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 168793 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 168803 T841 n:127.0.0.1:46305_
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 168813 T903 n:127.0.0.1:46305_ oascc.ConnectionManager.process
Watcher org.apache.solr.common.cloud.ConnectionManager@6ead9fd2
name:ZooKeeperConnection Watcher:127.0.0.1:50627/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 168813 T841 n:127.0.0.1:46305_
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 168819 T841 n:127.0.0.1:46305_
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 169829 T841 n:127.0.0.1:46305_
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46305_
[junit4] 2> 169829 T841 n:127.0.0.1:46305_ oascc.SolrZkClient.makePath
makePath: /live_nodes/127.0.0.1:46305_
[junit4] 2> 169832 T841 n:127.0.0.1:46305_ oasc.Overseer.close Overseer
(id=null) closing
[junit4] 2> 169834 T841 n:127.0.0.1:46305_
oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
[junit4] 2> 169834 T841 n:127.0.0.1:46305_
oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 169835 T841 n:127.0.0.1:46305_
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores
[junit4] 2> 169837 T841 n:127.0.0.1:46305_ oasc.CoreDescriptor.<init> CORE
DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1, collection=collection1,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/, coreNodeName=,
dataDir=data/, shard=}
[junit4] 2> 169838 T841 n:127.0.0.1:46305_
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/
[junit4] 2> 169838 T841 n:127.0.0.1:46305_
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 169846 T904 n:127.0.0.1:46305_ c:collection1 x:collection1
oasc.ZkController.publish publishing core=collection1 state=down
collection=collection1
[junit4] 2> 169846 T904 n:127.0.0.1:46305_ c:collection1 x:collection1
oasc.ZkController.publish numShards not found on descriptor - reading it from
system property
[junit4] 2> 169847 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 169847 T904 n:127.0.0.1:46305_
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 169850 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46305";,
[junit4] 2> "node_name":"127.0.0.1:46305_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 169851 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46305";,
[junit4] 2> "node_name":"127.0.0.1:46305_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 169851 T876 n:127.0.0.1:57722_
oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 169852 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 169954 T886 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 169955 T875 n:127.0.0.1:57722_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 169955 T903 n:127.0.0.1:46305_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 170848 T904 n:127.0.0.1:46305_
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for
collection1
[junit4] 2> 170849 T904 n:127.0.0.1:46305_
oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 170849 T904 n:127.0.0.1:46305_
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 170849 T904 n:127.0.0.1:46305_
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 170850 T904 n:127.0.0.1:46305_
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 170850 T904 n:127.0.0.1:46305_ oasc.SolrResourceLoader.<init>
new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/'
[junit4] 2> 170864 T904 n:127.0.0.1:46305_ oasc.Config.<init> loaded
config solrconfig.xml with version 0
[junit4] 2> 170871 T904 n:127.0.0.1:46305_
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 170875 T904 n:127.0.0.1:46305_ oasc.SolrConfig.<init> Using
Lucene MatchVersion: 6.0.0
[junit4] 2> 170907 T904 n:127.0.0.1:46305_ oasc.SolrConfig.<init> Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 170909 T904 n:127.0.0.1:46305_ oass.IndexSchema.readSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 170914 T904 n:127.0.0.1:46305_ oass.IndexSchema.readSchema
[collection1] Schema name=test
[junit4] 2> 171015 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider.init Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 171024 T904 n:127.0.0.1:46305_ oass.IndexSchema.readSchema
default search field in schema is text
[junit4] 2> 171025 T904 n:127.0.0.1:46305_ oass.IndexSchema.readSchema
unique key field: id
[junit4] 2> 171039 T904 n:127.0.0.1:46305_
oass.FileExchangeRateProvider.reload Reloading exchange rates from file
currency.xml
[junit4] 2> 171042 T904 n:127.0.0.1:46305_
oass.FileExchangeRateProvider.reload Reloading exchange rates from file
currency.xml
[junit4] 2> 171045 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 171064 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 171064 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 171064 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 171065 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 171065 T904 n:127.0.0.1:46305_
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 171065 T904 n:127.0.0.1:46305_ oasc.CoreContainer.create
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 171066 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 171066 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/], dataDir=[null]
[junit4] 2> 171066 T904 n:127.0.0.1:46305_ x:collection1
oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to
JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@69bc2553
[junit4] 2> 171067 T904 n:127.0.0.1:46305_ x:collection1
oasc.CachingDirectoryFactory.get return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/data
[junit4] 2> 171074 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.getNewIndexDir New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 171074 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.initIndex WARN [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/data/index' doesn't exist.
Creating new index...
[junit4] 2> 171075 T904 n:127.0.0.1:46305_ x:collection1
oasc.CachingDirectoryFactory.get return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 171075 T904 n:127.0.0.1:46305_ x:collection1
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 171076 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@45b35c23
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13c54d3b),segFN=segments_1,generation=1}
[junit4] 2> 171076 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 171080 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 171081 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 171081 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 171081 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 171081 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 171081 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 171082 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 171082 T904 n:127.0.0.1:46305_ x:collection1
oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 171082 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 171084 T904 n:127.0.0.1:46305_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 171085 T904 n:127.0.0.1:46305_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 171087 T904 n:127.0.0.1:46305_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 171088 T904 n:127.0.0.1:46305_ x:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 171119 T904 n:127.0.0.1:46305_ x:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 171120 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.initStatsCache Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 171136 T904 n:127.0.0.1:46305_ x:collection1
oasu.UpdateHandler.<init> Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 171136 T904 n:127.0.0.1:46305_ x:collection1
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 171139 T904 n:127.0.0.1:46305_ x:collection1
oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 171139 T904 n:127.0.0.1:46305_ x:collection1
oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 171142 T904 n:127.0.0.1:46305_ x:collection1
oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@4238a3fc
[junit4] 2> 171149 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@45b35c23
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13c54d3b),segFN=segments_1,generation=1}
[junit4] 2> 171150 T904 n:127.0.0.1:46305_ x:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 171150 T904 n:127.0.0.1:46305_ x:collection1
oass.SolrIndexSearcher.<init> Opening Searcher@3bd98ef4[collection1] main
[junit4] 2> 171150 T904 n:127.0.0.1:46305_ x:collection1
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 171151 T904 n:127.0.0.1:46305_ x:collection1
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 171152 T904 n:127.0.0.1:46305_ x:collection1
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for
the RestManager with znodeBase: /configs/conf1
[junit4] 2> 171153 T904 n:127.0.0.1:46305_ x:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 171153 T904 n:127.0.0.1:46305_ x:collection1
oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 171154 T904 n:127.0.0.1:46305_ x:collection1
oasr.ManagedResourceStorage.load Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 171155 T904 n:127.0.0.1:46305_ x:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 171156 T904 n:127.0.0.1:46305_ x:collection1
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 171156 T904 n:127.0.0.1:46305_ x:collection1
oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 171156 T904 n:127.0.0.1:46305_ x:collection1
oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 171157 T905 n:127.0.0.1:46305_ x:collection1
oasc.SolrCore.registerSearcher [collection1] Registered new searcher
Searcher@3bd98ef4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 171165 T904 n:127.0.0.1:46305_ x:collection1
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 171166 T904 n:127.0.0.1:46305_ x:collection1
oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value
of version field
[junit4] 2> 171166 T904 n:127.0.0.1:46305_ x:collection1
oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_
for 65536 version buckets from index
[junit4] 2> 171166 T904 n:127.0.0.1:46305_ x:collection1
oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot
seed version bucket highest value from index
[junit4] 2> 171166 T904 n:127.0.0.1:46305_ x:collection1
oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in
index or recent updates, using new clock 1502216900279533568
[junit4] 2> 171168 T904 n:127.0.0.1:46305_ x:collection1
oasu.UpdateLog.seedBucketsWithHighestVersion Took 2 ms to seed version buckets
with highest version 1502216900279533568
[junit4] 2> 171168 T904 n:127.0.0.1:46305_ x:collection1
oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 171170 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ZkController.register Register replica - core:collection1
address:http://127.0.0.1:46305 collection:collection1 shard:shard2
[junit4] 2> 171170 T841 n:127.0.0.1:46305_ oass.SolrDispatchFilter.init
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 171171 T841 n:127.0.0.1:46305_ oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 171171 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 171174 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the
leader process for shard shard2
[junit4] 2> 171175 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 171180 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 171183 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough
replicas found to continue.
[junit4] 2> 171183 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new
leader - try and sync
[junit4] 2> ASYNC NEW_CORE C56 name=collection1
org.apache.solr.core.SolrCore@3aea39ef url=http://127.0.0.1:46305/collection1
node=127.0.0.1:46305_ C56_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:46305,
node_name=127.0.0.1:46305_, state=down}
[junit4] 2> 171183 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 C56 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:46305/collection1/
[junit4] 2> 171183 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 C56 oasc.SyncStrategy.syncReplicas Sync Success - now sync
replicas to me
[junit4] 2> 171184 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 C56 oasc.SyncStrategy.syncToMe
http://127.0.0.1:46305/collection1/ has no replicas
[junit4] 2> 171184 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new
leader: http://127.0.0.1:46305/collection1/ shard2
[junit4] 2> 171184 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 171188 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 171189 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:46305";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 171291 T886 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 171291 T875 n:127.0.0.1:57722_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 171292 T903 n:127.0.0.1:46305_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 171339 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ZkController.register We are
http://127.0.0.1:46305/collection1/ and leader is
http://127.0.0.1:46305/collection1/
[junit4] 2> 171340 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:46305
[junit4] 2> 171340 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery
necessary
[junit4] 2> 171340 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ZkController.publish publishing core=collection1
state=active collection=collection1
[junit4] 2> 171340 T908 n:127.0.0.1:46305_ c:collection1 s:shard2
x:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 171341 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 171342 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46305";,
[junit4] 2> "node_name":"127.0.0.1:46305_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 171343 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46305";,
[junit4] 2> "node_name":"127.0.0.1:46305_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 171445 T886 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 171446 T903 n:127.0.0.1:46305_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 171445 T875 n:127.0.0.1:57722_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 171578 T841 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores/collection1
[junit4] 2> 171579 T841 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001
[junit4] 2> 171580 T841 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 171599 T841 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@11ceabaf{/,null,AVAILABLE}
[junit4] 2> 171599 T841 oejs.AbstractConnector.doStart Started
ServerConnector@325dfc83{HTTP/1.1}{127.0.0.1:48947}
[junit4] 2> 171600 T841 oejs.Server.doStart Started @173558ms
[junit4] 2> 171600 T841 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties: {solrconfig=solrconfig.xml, hostContext=/, hostPort=48947,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores}
[junit4] 2> 171601 T841 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 171601 T841 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/'
[junit4] 2> 171618 T841 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/solr.xml
[junit4] 2> 171624 T841 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores
[junit4] 2> 171624 T841 oasc.CoreContainer.<init> New CoreContainer
1704186590
[junit4] 2> 171625 T841 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/]
[junit4] 2> 171625 T841 oasc.CoreContainer.load loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/lib
[junit4] 2> 171625 T841 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/lib).
[junit4] 2> 171655 T841 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 171657 T841 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 171657 T841 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 171668 T841 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 171668 T841 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 171668 T841 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:50627/solr
[junit4] 2> 171668 T841 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 171670 T841
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 171689 T841 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 171693 T922 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4f4273fa
name:ZooKeeperConnection Watcher:127.0.0.1:50627 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 171694 T841 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 171696 T841 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 171705 T841 n:127.0.0.1:48947_
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 171719 T925 n:127.0.0.1:48947_ oascc.ConnectionManager.process
Watcher org.apache.solr.common.cloud.ConnectionManager@b0f583c
name:ZooKeeperConnection Watcher:127.0.0.1:50627/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 171719 T841 n:127.0.0.1:48947_
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 171723 T841 n:127.0.0.1:48947_
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 172733 T841 n:127.0.0.1:48947_
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:48947_
[junit4] 2> 172733 T841 n:127.0.0.1:48947_ oascc.SolrZkClient.makePath
makePath: /live_nodes/127.0.0.1:48947_
[junit4] 2> 172736 T841 n:127.0.0.1:48947_ oasc.Overseer.close Overseer
(id=null) closing
[junit4] 2> 172738 T841 n:127.0.0.1:48947_
oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
[junit4] 2> 172738 T841 n:127.0.0.1:48947_
oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 172739 T841 n:127.0.0.1:48947_
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores
[junit4] 2> 172740 T841 n:127.0.0.1:48947_ oasc.CoreDescriptor.<init> CORE
DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores/collection1, collection=collection1,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores/collection1/, coreNodeName=,
dataDir=data/, shard=}
[junit4] 2> 172740 T841 n:127.0.0.1:48947_
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest
3A6821C4380CD3A8-001/shard-2-001/cores/collection1/
[junit4] 2> 172740 T841 n:127.0.0.1:48947_
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 172749 T926 n:127.0.0.1:48947_ c:collection1 x:collection1
oasc.ZkController.publish publishing core=collection1 state=down
collection=collection1
[junit4] 2> 172750 T926 n:127.0.0.1:48947_ c:collection1 x:collection1
oasc.ZkController.publish numShards not found on descriptor - reading it from
system property
[junit4] 2> 172750 T875 n:127.0.0.1:57722_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 172750 T926 n:127.0.0.1:48947_
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 172751 T876 n:127.0.0.1:57722_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:48947";,
[junit4] 2> "node_name":"127.0.0.1:48947_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 172752 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:48947";,
[junit4] 2> "node_name":"127.0.0.1:48947_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 172752 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 172752 T876 n:127.0.0.1:57722_
oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 172856 T886 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 172857 T903 n:127.0.0.1:46305_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 172856 T925 n:127.0.0.1:48947_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 172856 T875 n:127.0.0.1:57722_ oascc.ZkStateReader$2.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 173751 T926 n:127.0.0.1:48947_
oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for
collection1
[junit4] 2> 173751 T926 n:127.0.0.1:48947_
oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 173752 T926 n:127.0.0.1:48947_
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 173752 T926 n:127.0.0.1:48947_
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 173753 T926 n:127.0.0.1:48947_
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 173753 T926 n:127.0.0.1:48947_ oasc.SolrResourceLoader.<in
[...truncated too long message...]
at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] > at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] > at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] > at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] > at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] > at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] > at
org.eclipse.jetty.server.Server.handle(Server.java:497)
[junit4] > at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] > at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] > at
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] > at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at
__randomizedtesting.SeedInfo.seed([3A6821C4380CD3A8:B23C1E1E96F0BE50]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
[junit4] > at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
[junit4] > at
org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
[junit4] > at
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
[junit4] > at
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1855914 T9419 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch
3A6821C4380CD3A8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{other_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval),
range_facet_l_dv=PostingsFormat(name=Asserting),
SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapDocFreqInterval),
n_l1=PostingsFormat(name=LuceneVarGapDocFreqInterval),
a_n_tdt=PostingsFormat(name=Asserting), multiDefault=FST50,
intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_td1=FST50,
n_d1=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_f1=FST50,
range_facet_l=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_tl1=FST50,
oddField_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
n_tf1=PostingsFormat(name=LuceneVarGapDocFreqInterval), lowerfilt1and2=FST50,
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), lowerfilt=FST50,
id=PostingsFormat(name=Asserting),
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
timestamp=PostingsFormat(name=LuceneVarGapDocFreqInterval),
severity=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
foo_sev_enum=PostingsFormat(name=Asserting), n_dt1=FST50,
a_i1=PostingsFormat(name=LuceneVarGapDocFreqInterval),
b_n_tdt=PostingsFormat(name=LuceneVarGapDocFreqInterval),
n_ti1=PostingsFormat(name=Asserting), rnd_b=PostingsFormat(name=Asserting),
_version_=PostingsFormat(name=LuceneVarGapDocFreqInterval),
foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
n_tdt1=PostingsFormat(name=LuceneVarGapDocFreqInterval),
foo_d=PostingsFormat(name=Asserting),
range_facet_i_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval),
foo_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting),
foo_sev_enum=DocValuesFormat(name=Asserting),
range_facet_i_dv=DocValuesFormat(name=Lucene50),
timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=en_CA,
timezone=Africa/Porto-Novo
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_25 (64-bit)/cpus=4,threads=2,free=31354712,total=535298048
[junit4] 2> NOTE: All tests run in this JVM: [ShardSplitTest,
VMParamsZkACLAndCredentialsProvidersTest, TestQueryTypes, TestRestManager,
DistanceUnitsTest, TestLuceneMatchVersion, TestImplicitCoreProperties,
TestShortCircuitedRequests, VersionInfoTest, TestManagedSchemaFieldResource,
CircularListTest, TestClusterStateMutator, CachingDirectoryFactoryTest,
TestPerFieldSimilarity, TestStressReorder, TestNoOpRegenerator, TestInitParams,
SuggesterTest, TestAddFieldRealTimeGet, AutoCommitTest,
TermVectorComponentDistributedTest, SolrCoreCheckLockOnStartupTest,
MoreLikeThisHandlerTest, TestFieldCollectionResource, ActionThrottleTest,
OutputWriterTest, TestObjectReleaseTracker, DirectSolrSpellCheckerTest,
TestQueryUtils, PathHierarchyTokenizerFactoryTest, TestCSVResponseWriter,
TestSystemIdResolver, SOLR749Test, DeleteShardTest, DirectUpdateHandlerTest,
XmlUpdateRequestHandlerTest, SampleTest, TestReqParamsAPI, TestStressRecovery,
TestBM25SimilarityFactory, XsltUpdateRequestHandlerTest,
OverseerCollectionProcessorTest, TestSolr4Spatial2, HdfsSyncSliceTest,
TestFastOutputStream, RecoveryZkTest, TestPartialUpdateDeduplication,
TestIntervalFaceting, HardAutoCommitTest, TestSolrConfigHandler,
HdfsRecoverLeaseTest, TestCollationField, TestSolrJ,
TestDynamicFieldCollectionResource, TestBinaryResponseWriter,
TestMiniSolrCloudCluster, TestSurroundQueryParser, TestRestoreCore,
TestPseudoReturnFields, SolrInfoMBeanTest, TestFieldTypeResource,
AtomicUpdatesTest, LeaderElectionTest,
OverriddenZkACLAndCredentialsProvidersTest, BadIndexSchemaTest,
TestHighlightDedupGrouping, StatsComponentTest, TestCloudInspectUtil,
TestSolrXml, DirectUpdateHandlerOptimizeTest, TestDistributedMissingSort,
CdcrUpdateLogTest, FileBasedSpellCheckerTest, QueryEqualityTest,
TestRequestStatusCollectionAPI, TestZkChroot, CdcrVersionReplicationTest,
ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OverseerTest,
ShardRoutingTest, FullSolrCloudDistribCmdsTest, TestRandomDVFaceting,
TestDistributedSearch]
[junit4] Completed [239/502] on J2 in 343.39s, 1 test, 1 error <<< FAILURES!
[...truncated 508 lines...]
FATAL: hudson.remoting.RequestAbortedException: java.io.IOException: Unexpected
termination of the channel
hudson.remoting.RequestAbortedException:
hudson.remoting.RequestAbortedException: java.io.IOException: Unexpected
termination of the channel
at
hudson.remoting.RequestAbortedException.wrapForRethrow(RequestAbortedException.java:41)
at
hudson.remoting.RequestAbortedException.wrapForRethrow(RequestAbortedException.java:34)
at hudson.remoting.Request.call(Request.java:174)
at hudson.remoting.Channel.call(Channel.java:742)
at
hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:168)
at com.sun.proxy.$Proxy120.join(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:956)
at hudson.Launcher$ProcStarter.join(Launcher.java:367)
at hudson.tasks.Ant.perform(Ant.java:217)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:756)
at hudson.model.Build$BuildExecution.build(Build.java:198)
at hudson.model.Build$BuildExecution.doRun(Build.java:159)
at
hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:529)
at hudson.model.Run.execute(Run.java:1706)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:88)
at hudson.model.Executor.run(Executor.java:232)
Caused by: hudson.remoting.RequestAbortedException: java.io.IOException:
Unexpected termination of the channel
at hudson.remoting.Request.abort(Request.java:299)
at hudson.remoting.Channel.terminate(Channel.java:805)
at
hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:69)
Caused by: java.io.IOException: Unexpected termination of the channel
at
hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:50)
Caused by: java.io.EOFException
at
java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2325)
at
java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:2794)
at
java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:801)
at java.io.ObjectInputStream.<init>(ObjectInputStream.java:299)
at
hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:40)
at
hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:34)
at
hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:48)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
