Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/670/
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
Captured an uncaught exception in thread: Thread[id=3850, name=Thread-1223,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=3850, name=Thread-1223, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)
Build Log:
[...truncated 11726 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/init-core-data-001
[junit4] 2> 671916 T3019 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (false)
[junit4] 2> 671916 T3019 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 671922 T3019 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 671923 T3019 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 671924 T3020 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 672024 T3019 oasc.ZkTestServer.run start zk server on
port:57521
[junit4] 2> 672025 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 672026 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 672031 T3026 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@654fdfb9
name:ZooKeeperConnection Watcher:127.0.0.1:57521 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 672031 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 672031 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 672032 T3019 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 672034 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 672036 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 672037 T3028 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4c192e0b
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 672037 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 672037 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 672038 T3019 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 672040 T3019 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 672042 T3019 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 672044 T3019 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 672046 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 672047 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 672050 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 672051 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 672053 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 672054 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 672056 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 672057 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 672059 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 672060 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 672062 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 672062 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 672065 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 672065 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 672067 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 672068 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 672070 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 672071 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 672073 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 672073 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 672076 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 672076 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 672079 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 672079 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 672081 T3030 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@242d90b7
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 672081 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 672081 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> Using old style solr.xml
[junit4] 2> 672083 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 672084 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 672085 T3032 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@58e5e25 name:ZooKeeperConnection
Watcher:127.0.0.1:57521 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 672085 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 672085 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 672086 T3019 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 672087 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 672088 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 672089 T3034 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2d6f4e1a
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 672090 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 672090 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 672092 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf2/solrconfig.xml
[junit4] 2> 672092 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/solrconfig.xml
[junit4] 2> 672095 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf2/schema.xml
[junit4] 2> 672095 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/schema.xml
[junit4] 2> 672098 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 672098 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 672100 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf2/stopwords.txt
[junit4] 2> 672101 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/stopwords.txt
[junit4] 2> 672103 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf2/protwords.txt
[junit4] 2> 672103 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/protwords.txt
[junit4] 2> 672105 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf2/currency.xml
[junit4] 2> 672106 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/currency.xml
[junit4] 2> 672108 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf2/open-exchange-rates.json
[junit4] 2> 672109 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/open-exchange-rates.json
[junit4] 2> 672111 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 672111 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 672113 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf2/old_synonyms.txt
[junit4] 2> 672114 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/old_synonyms.txt
[junit4] 2> 672116 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf2/synonyms.txt
[junit4] 2> 672116 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/synonyms.txt
[junit4] 2> 672119 T3019 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/core/src/test-files/solr/collection1/conf/elevate.xml
to /configs/conf2/elevate.xml
[junit4] 2> 672119 T3019 oascc.SolrZkClient.makePath makePath:
/configs/conf2/elevate.xml
[junit4] 2> 673273 T3019 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 673287 T3019 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 673291 T3019 oejs.AbstractConnector.doStart Started
[email protected]:33847
[junit4] 2> 673293 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 673294 T3019 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 673294 T3019 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002
[junit4] 2> 673295 T3019 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/'
[junit4] 2> 673338 T3019 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solr.xml
[junit4] 2> 673417 T3019 oasc.CoreContainer.<init> New CoreContainer
999010979
[junit4] 2> 673417 T3019 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/]
[junit4] 2> 673418 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 673419 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 673419 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 673420 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 673420 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 673420 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 673421 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 673421 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 673421 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 673422 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 673425 T3019 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 673427 T3019 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 673427 T3019 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 673428 T3019 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 673428 T3019 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57521/solr
[junit4] 2> 673429 T3019 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 673429 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 673430 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 673432 T3045 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@368f9385
name:ZooKeeperConnection Watcher:127.0.0.1:57521 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 673432 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 673433 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 673434 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 673435 T3047 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@77a72333
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 673435 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 673437 T3019 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 673439 T3019 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 673441 T3019 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 673443 T3019 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 673445 T3019 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 673448 T3019 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 673449 T3019 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33847_
[junit4] 2> 673450 T3019 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:33847_
[junit4] 2> 673452 T3019 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 673453 T3019 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 673455 T3019 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 673457 T3019 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:33847_
[junit4] 2> 673457 T3019 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 673459 T3019 oasc.Overseer.start Overseer
(id=92705533017784326-127.0.0.1:33847_-n_0000000000) starting
[junit4] 2> 673461 T3019 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 673468 T3019 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 673468 T3049 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 673469 T3019 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 673471 T3019 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 673472 T3019
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 673475 T3048 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 673478 T3019 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3
[junit4] 2> 673478 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 673483 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 673484 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 673485 T3053 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7a34fbbb
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 673485 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 673486 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 673487 T3019
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 673489 T3019 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 673490 T3019 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 673490 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 673491 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 673492 T3055 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@20dc5a31
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 673492 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 673492 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 673494 T3056 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 673496 T3048 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: collection1
[junit4] 2> 673496 T3048
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 673496 T3048
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 673498 T3048 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 673499 T3056 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> 673500 T3057 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> 674688 T3019 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 674689 T3019 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 674692 T3019 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 674694 T3019 oejs.AbstractConnector.doStart Started
[email protected]:10701
[junit4] 2> 674697 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 674697 T3019 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 674697 T3019 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003
[junit4] 2> 674697 T3019 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/'
[junit4] 2> 674726 T3019 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solr.xml
[junit4] 2> 674779 T3019 oasc.CoreContainer.<init> New CoreContainer
563729374
[junit4] 2> 674779 T3019 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/]
[junit4] 2> 674780 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 674780 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 674780 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 674780 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 674781 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 674781 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 674781 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 674781 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 674782 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 674782 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 674784 T3019 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 674786 T3019 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 674786 T3019 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 674786 T3019 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 674786 T3019 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57521/solr
[junit4] 2> 674787 T3019 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 674787 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 674788 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 674789 T3068 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1df60e59
name:ZooKeeperConnection Watcher:127.0.0.1:57521 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 674789 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 674789 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 674791 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 674792 T3070 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@30974b7e
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 674792 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 674797 T3019
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 675802 T3019 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:10701_
[junit4] 2> 675803 T3019 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:10701_
[junit4] 2> 675806 T3019 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 675812 T3019 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3
[junit4] 2> 675812 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 677393 T3019 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 677394 T3019 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 677398 T3019 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 677400 T3019 oejs.AbstractConnector.doStart Started
[email protected]:13927
[junit4] 2> 677402 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 677402 T3019 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 677402 T3019 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004
[junit4] 2> 677402 T3019 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/'
[junit4] 2> 677448 T3019 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solr.xml
[junit4] 2> 677527 T3019 oasc.CoreContainer.<init> New CoreContainer
1291249394
[junit4] 2> 677527 T3019 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/]
[junit4] 2> 677528 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 677528 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 677529 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 677529 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 677529 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 677529 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 677529 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 677530 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 677530 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 677530 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 677532 T3019 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 677534 T3019 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 677534 T3019 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 677534 T3019 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 677534 T3019 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57521/solr
[junit4] 2> 677535 T3019 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 677535 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 677536 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 677537 T3083 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@39cb5470
name:ZooKeeperConnection Watcher:127.0.0.1:57521 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 677537 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 677538 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 677539 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 677540 T3085 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4d21be23
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 677540 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 677544 T3019
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 678549 T3019 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:13927_
[junit4] 2> 678550 T3019 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:13927_
[junit4] 2> 678553 T3019 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 678556 T3019 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3
[junit4] 2> 678557 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 679966 T3019 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 679966 T3019 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 679970 T3019 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 679972 T3019 oejs.AbstractConnector.doStart Started
[email protected]:38126
[junit4] 2> 679974 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 679974 T3019 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 679974 T3019 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005
[junit4] 2> 679975 T3019 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/'
[junit4] 2> 680003 T3019 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solr.xml
[junit4] 2> 680055 T3019 oasc.CoreContainer.<init> New CoreContainer
483982703
[junit4] 2> 680055 T3019 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/]
[junit4] 2> 680056 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 680056 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 680057 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 680057 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 680057 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 680057 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 680057 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 680058 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 680058 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 680058 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 680060 T3019 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 680062 T3019 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 680062 T3019 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 680062 T3019 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 680062 T3019 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57521/solr
[junit4] 2> 680063 T3019 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 680063 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 680064 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 680078 T3098 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@50f8866d
name:ZooKeeperConnection Watcher:127.0.0.1:57521 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 680079 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 680079 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 680080 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 680081 T3100 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6cbeb7b8
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 680081 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 680086 T3019
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 681090 T3019 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38126_
[junit4] 2> 681091 T3019 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:38126_
[junit4] 2> 681095 T3019 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 681101 T3019 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3
[junit4] 2> 681101 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 682228 T3019 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 4
[junit4] 2> 682229 T3019 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 682233 T3019 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 682235 T3019 oejs.AbstractConnector.doStart Started
[email protected]:19331
[junit4] 2> 682237 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 682237 T3019 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 682237 T3019 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-006
[junit4] 2> 682237 T3019 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-006/'
[junit4] 2> 682270 T3019 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-006/solr.xml
[junit4] 2> 682327 T3019 oasc.CoreContainer.<init> New CoreContainer
462815002
[junit4] 2> 682327 T3019 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-006/]
[junit4] 2> 682328 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 682328 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 682329 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 682329 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 682329 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 682329 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 682329 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 682330 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 682330 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 682330 T3019 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 682332 T3019 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 682334 T3019 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 682334 T3019 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 682334 T3019 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 682334 T3019 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57521/solr
[junit4] 2> 682335 T3019 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 682335 T3019
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 682336 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 682337 T3113 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@63387b2 name:ZooKeeperConnection
Watcher:127.0.0.1:57521 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 682337 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 682338 T3019 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 682339 T3019 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 682340 T3115 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@29559c74
name:ZooKeeperConnection Watcher:127.0.0.1:57521/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 682340 T3019 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 682345 T3019
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 683350 T3019 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:19331_
[junit4] 2> 683351 T3019 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:19331_
[junit4] 2> 683355 T3019 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 683359 T3019 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3
[junit4] 2> 683360 T3019 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 683514 T3061 oasha.CollectionsHandler.handleCreateAction
Creating Collection :
name=solrj_collection&replicationFactor=2&action=CREATE&collection.configName=conf1&numShards=2&wt=javabin&router.field=myOwnField&version=2
[junit4] 2> 683516 T3056 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 683517 T3049 oasc.OverseerCollectionProcessor.run Overseer
Collection Processor: Get the message
id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 683524 T3119 oasc.OverseerCollectionProcessor.processMessage
WARN OverseerCollectionProcessor.processMessage : create , {
[junit4] 2> "operation":"create",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 683529 T3119 oasc.OverseerCollectionProcessor.createConfNode
creating collections conf node /collections/solrj_collection
[junit4] 2> 683530 T3119 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection
[junit4] 2> 683533 T3056 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 683536 T3048 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: solrj_collection
[junit4] 2> 683536 T3048
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
solrj_collection with shards [shard1, shard2]
[junit4] 2> 683536 T3048
oasc.Overseer$ClusterStateUpdater.createCollection state version
solrj_collection 1
[junit4] 2> 683538 T3086 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683538 T3116 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683538 T3101 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683538 T3056 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683538 T3057 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683538 T3071 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683633 T3119 oasc.OverseerCollectionProcessor.createCollection
Creating SolrCores for new collection solrj_collection, shardNames [shard1,
shard2] , replicationFactor : 2
[junit4] 2> 683634 T3119 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:13927_
[junit4] 2> 683635 T3119 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:38126_
[junit4] 2> 683637 T3119 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:10701_
[junit4] 2> 683639 T3119 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:33847_
[junit4] 2> 683762 T3040 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard2&name=solrj_collection_shard2_replica2&action=CREATE&collection.configName=conf1&numShards=2&wt=javabin&collection=solrj_collection&version=2
[junit4] 2> 683765 T3075 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard1&name=solrj_collection_shard1_replica1&action=CREATE&collection.configName=conf1&numShards=2&wt=javabin&collection=solrj_collection&version=2
[junit4] 2> 683766 T3065 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard2&name=solrj_collection_shard2_replica1&action=CREATE&collection.configName=conf1&numShards=2&wt=javabin&collection=solrj_collection&version=2
[junit4] 2> 683767 T3090 oasha.CoreAdminHandler.handleCreateAction core
create command
qt=/admin/cores&shard=shard1&name=solrj_collection_shard1_replica2&action=CREATE&collection.configName=conf1&numShards=2&wt=javabin&collection=solrj_collection&version=2
[junit4] 2> 683773 T3065 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica1 state=down collection=solrj_collection
[junit4] 2> 683773 T3090 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica2 state=down collection=solrj_collection
[junit4] 2> 683773 T3040 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica2 state=down collection=solrj_collection
[junit4] 2> 683773 T3075 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica1 state=down collection=solrj_collection
[junit4] 2> 683774 T3056 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 683774 T3090 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 683774 T3065 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 683774 T3075 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 683774 T3040 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 683777 T3048 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "shard":"shard2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:10701_",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:10701"}
[junit4] 2> 683779 T3048 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38126_",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:38126"}
[junit4] 2> 683781 T3056 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 683782 T3048 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "shard":"shard2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33847_",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:33847"}
[junit4] 2> 683784 T3048 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13927_",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "base_url":"https://127.0.0.1:13927"}
[junit4] 2> 683786 T3056 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 683786 T3056 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683787 T3071 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683787 T3057 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683787 T3086 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683787 T3116 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 683787 T3101 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 684774 T3090 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 684775 T3090 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 684775 T3065 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 684776 T3090 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 684775 T3040 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 684776 T3090 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 684776 T3075 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 684776 T3065 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 684777 T3075 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 684776 T3040 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 684778 T3065 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 684778 T3090 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 684778 T3040 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 684778 T3065 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 684778 T3075 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 684779 T3040 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 684780 T3065 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 684779 T3090 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/'
[junit4] 2> 684780 T3065 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/'
[junit4] 2> 684780 T3075 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 684781 T3040 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 684782 T3040 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/'
[junit4] 2> 684782 T3075 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 684782 T3075 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/'
[junit4] 2> 684838 T3090 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 684845 T3075 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 684847 T3065 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 684865 T3040 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 684910 T3090 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 684912 T3090 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 684916 T3065 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 684916 T3075 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 684917 T3065 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 684917 T3075 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 684925 T3090 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 684926 T3040 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 684927 T3040 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 684928 T3075 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 684929 T3065 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 684940 T3040 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 685397 T3065 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 685402 T3075 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 685402 T3090 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 685408 T3040 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 685408 T3065 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 685409 T3075 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 685411 T3090 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 685412 T3065 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 685413 T3075 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 685414 T3090 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 685415 T3040 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 685418 T3040 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 685427 T3065 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685428 T3075 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685429 T3090 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685432 T3040 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685433 T3065 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685433 T3075 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685434 T3090 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685437 T3040 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 685438 T3065 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685440 T3065
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685440 T3090 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685440 T3075 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685440 T3065
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685441 T3090
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685442 T3075
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685441 T3065 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685442 T3075
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685442 T3040 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685443 T3065
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685442 T3090
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685444 T3040
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685444 T3090 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685443 T3065
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685443 T3075 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685445 T3090
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685445 T3065 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica1' using configuration from collection
solrj_collection
[junit4] 2> 685444 T3040
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685446 T3065 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 685446 T3075
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685446 T3090
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685447 T3075
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685447 T3065 oasc.SolrCore.<init>
[solrj_collection_shard2_replica1] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/data/
[junit4] 2> 685447 T3040 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 685449 T3065 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@244e4f54
[junit4] 2> 685448 T3075 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica1' using configuration from collection
solrj_collection
[junit4] 2> 685448 T3090 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica2' using configuration from collection
solrj_collection
[junit4] 2> 685451 T3075 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 685450 T3040
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 685452 T3075 oasc.SolrCore.<init>
[solrj_collection_shard1_replica1] Opening new SolrCore at
../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/data/
[junit4] 2> 685451 T3065 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/data
[junit4] 2> 685451 T3090 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 685453 T3065 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 685452 T3075 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@244e4f54
[junit4] 2> 685452 T3040
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 685453 T3065 oasc.SolrCore.initIndex WARN
[solrj_collection_shard2_replica1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 685453 T3090 oasc.SolrCore.<init>
[solrj_collection_shard1_replica2] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/data/
[junit4] 2> 685455 T3065 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-003/solrj_collection_shard2_replica1/data/index
[junit4] 2> 685455 T3090 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@244e4f54
[junit4] 2> 685454 T3075 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/data
[junit4] 2> 685454 T3040 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica2' using configuration from collection
solrj_collection
[junit4] 2> 685456 T3075 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/data/index/
[junit4] 2> 685455 T3065 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=24.2255859375,
floorSegmentMB=2.037109375, forceMergeDeletesPctAllowed=7.414876246236076,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=2.01171875,
noCFSRatio=0.6164819115401692
[junit4] 2> 685457 T3075 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 685456 T3090 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/data
[junit4] 2> 685458 T3075 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-004/solrj_collection_shard1_replica1/data/index
[junit4] 2> 685456 T3040 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 685458 T3075 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=24.2255859375,
floorSegmentMB=2.037109375, forceMergeDeletesPctAllowed=7.414876246236076,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=2.01171875,
noCFSRatio=0.6164819115401692
[junit4] 2> 685458 T3090 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/data/index/
[junit4] 2> 685458 T3065 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory(id=lucene-29aba046);
maxCacheMB=0.8798828125
maxMergeSizeMB=0.60546875)),segFN=segments_1,generation=1}
[junit4] 2> 685460 T3075 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory(id=lucene-2d184736);
maxCacheMB=0.8798828125
maxMergeSizeMB=0.60546875)),segFN=segments_1,generation=1}
[junit4] 2> 685460 T3065 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 685459 T3090 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica2] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/data/index'
doesn't exist. Creating new index...
[junit4] 2> 685459 T3040 oasc.SolrCore.<init>
[solrj_collection_shard2_replica2] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/data/
[junit4] 2> 685461 T3090 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-005/solrj_collection_shard1_replica2/data/index
[junit4] 2> 685460 T3075 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 685462 T3090 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=24.2255859375,
floorSegmentMB=2.037109375, forceMergeDeletesPctAllowed=7.414876246236076,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=2.01171875,
noCFSRatio=0.6164819115401692
[junit4] 2> 685462 T3040 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@244e4f54
[junit4] 2> 685464 T3090 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory(id=lucene-4bf57fb);
maxCacheMB=0.8798828125
maxMergeSizeMB=0.60546875)),segFN=segments_1,generation=1}
[junit4] 2> 685464 T3090 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 685464 T3040 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/data
[junit4] 2> 685465 T3040 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/data/index/
[junit4] 2> 685466 T3065 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 685466 T3040 oasc.SolrCore.initIndex WARN
[solrj_collection_shard2_replica2] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/data/index'
doesn't exist. Creating new index...
[junit4] 2> 685466 T3065 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 685467 T3040 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001/tempDir-002/solrj_collection_shard2_replica2/data/index
[junit4] 2> 685467 T3065 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 685468 T3040 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=24.2255859375,
floorSegmentMB=2.037109375, forceMergeDeletesPctAllowed=7.414876246236076,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=2.01171875,
noCFSRatio=0.6164819115401692
[junit4] 2> 685468 T3065 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 685469 T3075 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 685469 T3065 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 685469 T3075 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 685469 T3040 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory(id=lucene-79295b9b);
maxCacheMB=0.8798828125
maxMergeSizeMB=0.60546875)),segFN=segments_1,generation=1}
[junit4] 2> 685470 T3075 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 685470 T3090 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 685470 T3065 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 685471 T3090 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 685472 T3065 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 685471 T3075 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 685470 T3040 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 685472 T3075 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 685472 T3065 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 685472 T3090 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 685473 T3065 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 685473 T3075 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 685474 T3090 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 685475 T3075 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 685475 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685476 T3075 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 685475 T3090 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 685476 T3075 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 685476 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685477 T3090 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 685478 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685478 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685478 T3065 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 685478 T3090 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 685479 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 685478 T3040 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 685479 T3065 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 685479 T3090 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 685479 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685480 T3090 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 685480 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 685480 T3040 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 685481 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 685481 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685481 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685482 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 685482 T3040 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 685483 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 685483 T3075 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 685484 T3065 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 685483 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685484 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 685483 T3040 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 685485 T3075 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 685485 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685486 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 685485 T3040 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 685486 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 685486 T3090 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 685487 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 685486 T3040 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 685487 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 685488 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 685488 T3040 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 685488 T3090 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 685489 T3040 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 685489 T3075 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 685489 T3040 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 685489 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 685490 T3040 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685491 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 685491 T3040 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685491 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 685492 T3040 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 685492 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 685492 T3040 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 685493 T3090 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 685493 T3040 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 685494 T3040 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 685494 T3040 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 685495 T3040 oasc.RequestHand
[...truncated too long message...]
ard5/election/92705533017784336-core_node15-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection1_2/leader_elect/shard6/election/92705533017784330-core_node16-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection2_2/leader_elect/shard2/election/92705533017784341-core_node5-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection4_2/leader_elect/shard3/election/92705533017784334-core_node9-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection3_2/leader_elect/shard4/election/92705533017784334-core_node10-n_0000000000
[junit4] 2> 2
/solr/collections/halfdeletedcollection2/leader_elect/shard3/election/92705533017784334-core_node8-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection2_2/leader_elect/shard3/election/92705533017784341-core_node10-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection0_2/leader_elect/shard5/election/92705533017784336-core_node9-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection2_2/leader_elect/shard1/election/92705533017784336-core_node1-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 16 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch
-Dtests.seed=BAC342B198BF0FEB -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=ar_JO -Dtests.timezone=America/Danmarkshavn -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 588s J3 |
CollectionsAPIDistributedZkTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 330 seconds
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:178)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:137)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:132)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:834)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionWithDownNodes(CollectionsAPIDistributedZkTest.java:430)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:212)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=3850, name=Thread-1223, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)Throwable
#3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=3852, name=Thread-1225, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)Throwable
#4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=3851, name=Thread-1224, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)Throwable
#5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=3848, name=Thread-1221, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)Throwable
#6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=3847, name=Thread-1220, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)Throwable
#7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=3849, name=Thread-1222, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([BAC342B198BF0FEB]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1044)
[junit4] 2> 1259691 T3019 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-BAC342B198BF0FEB-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{timestamp=PostingsFormat(name=Memory doPackFST= false),
intDefault=PostingsFormat(name=Memory doPackFST= false),
text=PostingsFormat(name=LuceneVarGapDocFreqInterval),
range_facet_l=PostingsFormat(name=Memory doPackFST= false),
_version_=PostingsFormat(name=Memory doPackFST= false),
a_t=PostingsFormat(name=Memory doPackFST= false),
a_i1=PostingsFormat(name=Memory doPackFST= false),
other_tl1=PostingsFormat(name=Memory doPackFST= false),
id=PostingsFormat(name=Memory doPackFST= true),
multiDefault=PostingsFormat(name=LuceneFixedGap)},
docValues:{timestamp=DocValuesFormat(name=SimpleText)}, sim=DefaultSimilarity,
locale=ar_JO, timezone=America/Danmarkshavn
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=16,threads=1,free=149281680,total=508559360
[junit4] 2> NOTE: All tests run in this JVM:
[UniqFieldsUpdateProcessorFactoryTest,
StatelessScriptUpdateProcessorFactoryTest, LeaderElectionTest,
StandardRequestHandlerTest, TestRawResponseWriter,
SpellPossibilityIteratorTest, BadCopyFieldTest, DocValuesMultiTest,
DocumentAnalysisRequestHandlerTest, SearchHandlerTest, ZkCLITest,
EchoParamsTest, TestSchemaManager, TestMissingGroups,
TestLMJelinekMercerSimilarityFactory, TestIndexSearcher, TestJoin,
DocValuesMissingTest, FieldMutatingUpdateProcessorTest, TestNRTOpen,
TestReRankQParserPlugin, TestManagedSchemaFieldResource, TestSearchPerf,
TriLevelCompositeIdRoutingTest, CoreContainerCoreInitFailuresTest,
SliceStateTest, SuggesterTSTTest, TestCloudSchemaless, LoggingHandlerTest,
TestManagedSchema, FieldAnalysisRequestHandlerTest, TestCSVLoader,
TestOrdValues, SuggesterTest, SimpleFacetsTest, UUIDFieldTest,
TestMergePolicyConfig, TestPivotHelperCode, CollectionsAPIDistributedZkTest]
[junit4] Completed on J3 in 587.82s, 1 test, 1 error <<< FAILURES!
[...truncated 929 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/build.xml:532:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/build.xml:473:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/build.xml:61:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/build.xml:189:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/solr/common-build.xml:496:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/lucene/common-build.xml:1358:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk@2/lucene/common-build.xml:965:
There were test failures: 440 suites, 1800 tests, 1 error, 54 ignored (9
assumptions)
Total time: 215 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-trunk #664
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 433414723 bytes
Compression is 0.0%
Took 1 min 15 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
