Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/1945/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=16408,
name=SocketProxy-Response-59874:60083, state=RUNNABLE,
group=TGRP-HttpPartitionTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=16408, name=SocketProxy-Response-59874:60083,
state=RUNNABLE, group=TGRP-HttpPartitionTest]
at
__randomizedtesting.SeedInfo.seed([84AFCF1E3A0E92F1:CFBF0C494F2FF09]:0)
Caused by: java.lang.RuntimeException: java.net.SocketException: Socket is
closed
at __randomizedtesting.SeedInfo.seed([84AFCF1E3A0E92F1]:0)
at
org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:344)
Caused by: java.net.SocketException: Socket is closed
at java.net.Socket.setSoTimeout(Socket.java:1101)
at
org.apache.solr.cloud.SocketProxy$Bridge$Pump.run(SocketProxy.java:341)
Build Log:
[...truncated 10736 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/init-core-data-001
[junit4] 2> 4987940 T16014
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 4987946 T16014 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4987947 T16015 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 4988048 T16014 oasc.ZkTestServer.run start zk server on
port:59870
[junit4] 2> 4988049 T16014
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4988056 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4988068 T16022 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@48348e4b
name:ZooKeeperConnection Watcher:127.0.0.1:59870 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4988069 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4988069 T16014 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4988070 T16014 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 4988086 T16014
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4988089 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4988094 T16025 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@a73d9a3 name:ZooKeeperConnection
Watcher:127.0.0.1:59870/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4988095 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4988095 T16014 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4988096 T16014 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 4988106 T16014 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 4988115 T16014 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 4988120 T16014 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 4988129 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4988129 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 4988139 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 4988139 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 4988148 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4988149 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4988158 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4988159 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 4988171 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4988171 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 4988179 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4988179 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 4988189 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4988190 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 4988199 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4988199 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 4988209 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4988210 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4988217 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4988218 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 4988227 T16014 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4988227 T16014 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 4990128 T16014 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/cores/collection1
[junit4] 2> 4990132 T16014 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4990143 T16014 oejs.AbstractConnector.doStart Started
[email protected]:59874
[junit4] 2> 4990143 T16014 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5bdf59bd
[junit4] 2> 4990144 T16014 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 4990145 T16014 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001
[junit4] 2> 4990145 T16014 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/'
[junit4] 2> 4990192 T16014 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/solr.xml
[junit4] 2> 4990230 T16014 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/cores
[junit4] 2> 4990232 T16014 oasc.CoreContainer.<init> New CoreContainer
1314314310
[junit4] 2> 4990232 T16014 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/]
[junit4] 2> 4990233 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4990233 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4990234 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4990234 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4990234 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4990234 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4990235 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4990235 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4990235 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4990235 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4990236 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4990236 T16014 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4990237 T16014 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4990237 T16014 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4990237 T16014 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4990238 T16014 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59870/solr
[junit4] 2> 4990238 T16014 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4990239 T16014
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4990241 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4990245 T16037 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2e090cac
name:ZooKeeperConnection Watcher:127.0.0.1:59870 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4990246 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4990247 T16014 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4990252 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4990258 T16040 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6bfbc2f4
name:ZooKeeperConnection Watcher:127.0.0.1:59870/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4990258 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4990263 T16014 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 4990274 T16014 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 4990285 T16014 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 4990296 T16014 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 4990306 T16014 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 4990318 T16014 oascc.SolrZkClient.makePath makePath:
/live_nodes
[junit4] 2> 4990324 T16014 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59873_
[junit4] 2> 4990326 T16014 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:59873_
[junit4] 2> 4990337 T16014 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 4990344 T16014 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 4990350 T16014 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4990356 T16014 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:59873_
[junit4] 2> 4990356 T16014 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 4990364 T16014 oasc.Overseer.start Overseer
(id=93293987511795715-127.0.0.1:59873_-n_0000000000) starting
[junit4] 2> 4990371 T16014 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 4990402 T16014 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 4990404 T16042 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 4990408 T16014 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 4990417 T16014 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 4990422 T16014
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4990434 T16041 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 4990440 T16014 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/cores
[junit4] 2> 4990441 T16014 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/cores/collection1/
[junit4] 2> 4990441 T16014 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 4990444 T16044 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 4990445 T16044 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4990447 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4990447 T16044 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4990456 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:59873";,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59873_",
[junit4] 2> "roles":null} current state version: 0
[junit4] 2> 4990457 T16041 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:59873";,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59873_",
[junit4] 2> "roles":null}
[junit4] 2> 4990458 T16041 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 4990458 T16041 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 4990463 T16040 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> 4991449 T16044 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4991449 T16044 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 4991454 T16044 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4991455 T16044 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 4991459 T16044 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 4991464 T16044 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/cores/collection1/'
[junit4] 2> 4991503 T16044 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4991510 T16044 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4991524 T16044 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4991548 T16044 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 4991637 T16044 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4991641 T16044 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4991653 T16044 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 4992060 T16044 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4992067 T16044 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 4992071 T16044 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4992105 T16044 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4992114 T16044 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4992123 T16044 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4992127 T16044
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4992129 T16044
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4992130 T16044 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4992132 T16044
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4992132 T16044
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4992133 T16044 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 4992133 T16044 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4992133 T16044 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/control-001/cores/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/control/data/
[junit4] 2> 4992134 T16044 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@489a912e
[junit4] 2> 4992136 T16044 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/control/data
[junit4] 2> 4992136 T16044 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/control/data/index/
[junit4] 2> 4992137 T16044 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 4992138 T16044 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/control/data/index
[junit4] 2> 4992139 T16044 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=74.5439453125,
floorSegmentMB=1.4541015625, forceMergeDeletesPctAllowed=23.29438900827957,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 4992142 T16044 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@19fd6cc2
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cef41e2),segFN=segments_1,generation=1}
[junit4] 2> 4992142 T16044 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4992150 T16044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 4992151 T16044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 4992151 T16044 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 4992151 T16044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 4992152 T16044 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 4992152 T16044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4992153 T16044 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4992154 T16044 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4992154 T16044 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4992155 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4992157 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4992158 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4992158 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4992160 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 4992161 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 4992162 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 4992162 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 4992164 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 4992165 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 4992166 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 4992167 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 4992167 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 4992168 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 4992169 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 4992169 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 4992170 T16044 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 4992171 T16044 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 4992185 T16044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4992188 T16044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4992190 T16044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4992191 T16044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4992193 T16044 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4992196 T16044 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4992196 T16044 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4992197 T16044 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=24.583984375,
floorSegmentMB=0.33203125, forceMergeDeletesPctAllowed=17.437861729769395,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2152098305947342
[junit4] 2> 4992197 T16044 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@19fd6cc2
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cef41e2),segFN=segments_1,generation=1}
[junit4] 2> 4992197 T16044 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4992198 T16044 oass.SolrIndexSearcher.<init> Opening
Searcher@360a9c40[collection1] main
[junit4] 2> 4992198 T16044 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 4992200 T16044 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 4992201 T16044 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4992202 T16044
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4992202 T16044 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4992202 T16044 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4992204 T16044
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4992204 T16044 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4992204 T16044 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4992205 T16044 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4992205 T16044 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4992206 T16045 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@360a9c40[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4992208 T16048 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:59873 collection:control_collection
shard:shard1
[junit4] 2> 4992209 T16014 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4992209 T16014 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4992210 T16048 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 4992210 T16014
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4992212 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4992217 T16052 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@69540732
name:ZooKeeperConnection Watcher:127.0.0.1:59870/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4992218 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4992218 T16014 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4992221 T16014
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4992228 T16048
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 4992231 T16014 oasc.ChaosMonkey.monkeyLog monkey: init -
expire sessions:false cause connection loss:false
[junit4] 2> 4992234 T16048
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4992234 T16048
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C4788 name=collection1
org.apache.solr.core.SolrCore@651bfd0d url=http://127.0.0.1:59873/collection1
node=127.0.0.1:59873_ C4788_STATE=coll:control_collection core:collection1
props:{base_url=http://127.0.0.1:59873, state=down, core=collection1,
node_name=127.0.0.1:59873_}
[junit4] 2> 4992235 T16048 C4788 P59873 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:59873/collection1/
[junit4] 2> 4992235 T16048 C4788 P59873 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4992235 T16048 C4788 P59873 oasc.SyncStrategy.syncToMe
http://127.0.0.1:59873/collection1/ has no replicas
[junit4] 2> 4992235 T16048
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:59873/collection1/ shard1
[junit4] 2> 4992236 T16048 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 4992242 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4992246 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 4992250 T16040 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> 4992251 T16052 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> 4992261 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:59873";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 4992266 T16053 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4992270 T16052 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> 4992301 T16048 oasc.ZkController.register We are
http://127.0.0.1:59873/collection1/ and leader is
http://127.0.0.1:59873/collection1/
[junit4] 2> 4992301 T16048 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:59873
[junit4] 2> 4992301 T16048 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4992302 T16048 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 4992303 T16048 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4992306 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4992314 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:59873";,
[junit4] 2> "state":"active",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59873_",
[junit4] 2> "roles":null} current state version: 3
[junit4] 2> 4992316 T16041 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:59873";,
[junit4] 2> "state":"active",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59873_",
[junit4] 2> "roles":null}
[junit4] 2> 4992424 T16040 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> 4992425 T16052 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> 4994156 T16014 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/cores/collection1
[junit4] 2> 4994160 T16014 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 4994162 T16014 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4994171 T16014 oejs.AbstractConnector.doStart Started
[email protected]:59879
[junit4] 2> 4994173 T16014 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5bdf59bd
[junit4] 2> 4994173 T16014 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 4994174 T16014 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001
[junit4] 2> 4994174 T16014 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/'
[junit4] 2> 4994221 T16014 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/solr.xml
[junit4] 2> 4994258 T16014 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/cores
[junit4] 2> 4994259 T16014 oasc.CoreContainer.<init> New CoreContainer
1075431107
[junit4] 2> 4994259 T16014 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/]
[junit4] 2> 4994262 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4994263 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4994263 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4994264 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4994264 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4994265 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4994265 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4994265 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4994266 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4994266 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4994267 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4994268 T16014 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4994268 T16014 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4994268 T16014 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4994269 T16014 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4994269 T16014 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59870/solr
[junit4] 2> 4994270 T16014 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4994271 T16014
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4994274 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4994278 T16065 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5da8fab7
name:ZooKeeperConnection Watcher:127.0.0.1:59870 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4994279 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4994280 T16014 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4994284 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4994289 T16068 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6218d1db
name:ZooKeeperConnection Watcher:127.0.0.1:59870/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4994289 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4994317 T16014
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4995336 T16014 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59878_
[junit4] 2> 4995340 T16014 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:59878_
[junit4] 2> 4995359 T16014 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4995369 T16014 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/cores
[junit4] 2> 4995371 T16014 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/cores/collection1/
[junit4] 2> 4995372 T16014 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 4995376 T16069 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 4995376 T16069 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4995379 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4995379 T16069 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4995382 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:59878";,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59878_",
[junit4] 2> "roles":null} current state version: 4
[junit4] 2> 4995384 T16041 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:59878";,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59878_",
[junit4] 2> "roles":null}
[junit4] 2> 4995384 T16041 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 4995384 T16041 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 4995389 T16040 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4995389 T16068 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4995389 T16052 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4996382 T16069 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4996383 T16069 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 4996390 T16069 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4996390 T16069 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4996397 T16069 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4996398 T16069 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/cores/collection1/'
[junit4] 2> 4996440 T16069 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4996449 T16069 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4996470 T16069 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4996498 T16069 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 4996588 T16069 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4996592 T16069 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4996598 T16069 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 4997034 T16069 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4997040 T16069 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 4997044 T16069 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4997071 T16069 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4997078 T16069 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 4997084 T16069 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4997087 T16069
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4997088 T16069
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4997088 T16069 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4997091 T16069
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 4997091 T16069
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 4997092 T16069 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 4997092 T16069 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4997092 T16069 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-1-001/cores/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty1/
[junit4] 2> 4997092 T16069 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@489a912e
[junit4] 2> 4997093 T16069 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty1
[junit4] 2> 4997093 T16069 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty1/index/
[junit4] 2> 4997093 T16069 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 4997094 T16069 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty1/index
[junit4] 2> 4997094 T16069 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=74.5439453125,
floorSegmentMB=1.4541015625, forceMergeDeletesPctAllowed=23.29438900827957,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 4997096 T16069 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3085d0e5
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21558346),segFN=segments_1,generation=1}
[junit4] 2> 4997096 T16069 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4997101 T16069 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 4997101 T16069 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 4997102 T16069 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 4997102 T16069 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 4997102 T16069 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 4997102 T16069 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4997103 T16069 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4997104 T16069 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 4997104 T16069 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4997105 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4997105 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4997105 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4997107 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4997107 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 4997108 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 4997108 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 4997109 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 4997109 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 4997114 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 4997114 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 4997115 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 4997116 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 4997116 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 4997117 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 4997118 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 4997119 T16069 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 4997119 T16069 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 4997131 T16069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4997132 T16069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4997135 T16069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4997137 T16069 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4997138 T16069 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4997140 T16069 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4997140 T16069 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4997141 T16069 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=24.583984375,
floorSegmentMB=0.33203125, forceMergeDeletesPctAllowed=17.437861729769395,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2152098305947342
[junit4] 2> 4997142 T16069 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3085d0e5
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21558346),segFN=segments_1,generation=1}
[junit4] 2> 4997142 T16069 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4997142 T16069 oass.SolrIndexSearcher.<init> Opening
Searcher@2e97048c[collection1] main
[junit4] 2> 4997142 T16069 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4997145 T16069 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4997146 T16069 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4997148 T16069
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4997148 T16069 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4997149 T16069 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4997150 T16069
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4997151 T16069 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4997151 T16069 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4997151 T16069 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4997152 T16070 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@2e97048c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4997153 T16069 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4997156 T16073 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:59878 collection:collection1
shard:shard1
[junit4] 2> 4997156 T16014 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4997157 T16014 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4997159 T16073 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 4997170 T16073
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 4997174 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4997176 T16073
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4997176 T16073
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C4789 name=collection1
org.apache.solr.core.SolrCore@45e17e32 url=http://127.0.0.1:59878/collection1
node=127.0.0.1:59878_ C4789_STATE=coll:collection1 core:collection1
props:{base_url=http://127.0.0.1:59878, state=down, core=collection1,
node_name=127.0.0.1:59878_}
[junit4] 2> 4997176 T16073 C4789 P59878 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:59878/collection1/
[junit4] 2> 4997177 T16073 C4789 P59878 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4997177 T16073 C4789 P59878 oasc.SyncStrategy.syncToMe
http://127.0.0.1:59878/collection1/ has no replicas
[junit4] 2> 4997177 T16073
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:59878/collection1/ shard1
[junit4] 2> 4997177 T16073 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 4997182 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 4997187 T16040 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997199 T16068 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997207 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:59878";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 6
[junit4] 2> 4997209 T16052 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997212 T16053 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4997225 T16075 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997232 T16076 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997258 T16073 oasc.ZkController.register We are
http://127.0.0.1:59878/collection1/ and leader is
http://127.0.0.1:59878/collection1/
[junit4] 2> 4997258 T16073 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:59878
[junit4] 2> 4997258 T16073 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4997259 T16073 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 4997260 T16073 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4997263 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4997265 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:59878";,
[junit4] 2> "state":"active",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59878_",
[junit4] 2> "roles":null} current state version: 7
[junit4] 2> 4997267 T16041 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:59878";,
[junit4] 2> "state":"active",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59878_",
[junit4] 2> "roles":null}
[junit4] 2> 4997378 T16076 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997378 T16040 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4997380 T16075 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 4998926 T16014 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/cores/collection1
[junit4] 2> 4998930 T16014 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 4998931 T16014 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4998936 T16014 oejs.AbstractConnector.doStart Started
[email protected]:59883
[junit4] 2> 4998938 T16014 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@5bdf59bd
[junit4] 2> 4998938 T16014 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 4998939 T16014 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001
[junit4] 2> 4998939 T16014 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/'
[junit4] 2> 4998994 T16014 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/solr.xml
[junit4] 2> 4999034 T16014 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/cores
[junit4] 2> 4999036 T16014 oasc.CoreContainer.<init> New CoreContainer
1173815761
[junit4] 2> 4999036 T16014 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/]
[junit4] 2> 4999037 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4999038 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4999038 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4999038 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4999039 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4999039 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4999040 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4999040 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4999041 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4999041 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4999041 T16014 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4999042 T16014 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4999042 T16014 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4999042 T16014 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4999043 T16014 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4999043 T16014 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59870/solr
[junit4] 2> 4999043 T16014 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4999044 T16014
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4999045 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4999052 T16088 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@778814a6
name:ZooKeeperConnection Watcher:127.0.0.1:59870 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4999053 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4999053 T16014 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4999058 T16014 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4999061 T16091 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@41f62558
name:ZooKeeperConnection Watcher:127.0.0.1:59870/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4999062 T16014 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4999081 T16014
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 5000094 T16014 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59882_
[junit4] 2> 5000100 T16014 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:59882_
[junit4] 2> 5000116 T16014 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 5000124 T16014 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/cores
[junit4] 2> 5000127 T16014 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/cores/collection1/
[junit4] 2> 5000127 T16014 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 5000130 T16092 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 5000130 T16092 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 5000133 T16092 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 5000133 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 5000137 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:59882";,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59882_",
[junit4] 2> "roles":null} current state version: 8
[junit4] 2> 5000139 T16041 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:59882";,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "node_name":"127.0.0.1:59882_",
[junit4] 2> "roles":null}
[junit4] 2> 5000139 T16041 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 5000140 T16041 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 5000145 T16091 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5000145 T16076 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5000146 T16040 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5000145 T16075 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001135 T16092 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 5001136 T16092 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 5001140 T16092 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 5001140 T16092 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 5001149 T16092 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 5001150 T16092 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/cores/collection1/'
[junit4] 2> 5001182 T16092 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 5001189 T16092 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 5001206 T16092 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 5001257 T16092 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 5001302 T16092 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 5001308 T16092 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 5001319 T16092 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 5001722 T16092 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 5001737 T16092 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 5001740 T16092 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 5001770 T16092 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 5001778 T16092 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 5001785 T16092 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 5001788 T16092
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 5001790 T16092
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 5001790 T16092 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 5001794 T16092
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 5001794 T16092
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 5001794 T16092 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 5001796 T16092 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 5001797 T16092 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/shard-2-001/cores/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty2/
[junit4] 2> 5001797 T16092 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@489a912e
[junit4] 2> 5001798 T16092 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty2
[junit4] 2> 5001800 T16092 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty2/index/
[junit4] 2> 5001800 T16092 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 5001801 T16092 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
84AFCF1E3A0E92F1-001/tempDir-001/jetty2/index
[junit4] 2> 5001802 T16092 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=74.5439453125,
floorSegmentMB=1.4541015625, forceMergeDeletesPctAllowed=23.29438900827957,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 5001805 T16092 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@23da0784
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f237d7f),segFN=segments_1,generation=1}
[junit4] 2> 5001805 T16092 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 5001813 T16092 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 5001813 T16092 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 5001814 T16092 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 5001814 T16092 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 5001815 T16092 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 5001815 T16092 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 5001817 T16092 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 5001817 T16092 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 5001817 T16092 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 5001819 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5001819 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5001820 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5001820 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 5001821 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 5001821 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 5001823 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 5001823 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 5001824 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 5001826 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 5001826 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 5001827 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 5001827 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 5001828 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 5001828 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 5001829 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 5001829 T16092 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 5001830 T16092 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 5001842 T16092 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5001843 T16092 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5001846 T16092 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5001849 T16092 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 5001850 T16092 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 5001852 T16092 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 5001853 T16092 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 5001854 T16092 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=24.583984375,
floorSegmentMB=0.33203125, forceMergeDeletesPctAllowed=17.437861729769395,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2152098305947342
[junit4] 2> 5001854 T16092 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@23da0784
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3f237d7f),segFN=segments_1,generation=1}
[junit4] 2> 5001854 T16092 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 5001855 T16092 oass.SolrIndexSearcher.<init> Opening
Searcher@5de97942[collection1] main
[junit4] 2> 5001855 T16092 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 5001859 T16092 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 5001860 T16092 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 5001862 T16092
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 5001862 T16092 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 5001862 T16092 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 5001864 T16092
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 5001864 T16092 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 5001864 T16092 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 5001864 T16092 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 5001865 T16093 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5de97942[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5001866 T16092 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 5001869 T16096 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:59882 collection:collection1
shard:shard2
[junit4] 2> 5001869 T16014 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 5001870 T16014 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 5001872 T16096 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 5001886 T16096
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard2
[junit4] 2> 5001891 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 5001892 T16096
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 5001893 T16096
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C4790 name=collection1
org.apache.solr.core.SolrCore@5953e91c url=http://127.0.0.1:59882/collection1
node=127.0.0.1:59882_ C4790_STATE=coll:collection1 core:collection1
props:{base_url=http://127.0.0.1:59882, state=down, core=collection1,
node_name=127.0.0.1:59882_}
[junit4] 2> 5001893 T16096 C4790 P59882 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:59882/collection1/
[junit4] 2> 5001893 T16096 C4790 P59882 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 5001893 T16096 C4790 P59882 oasc.SyncStrategy.syncToMe
http://127.0.0.1:59882/collection1/ has no replicas
[junit4] 2> 5001894 T16096
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:59882/collection1/ shard2
[junit4] 2> 5001894 T16096 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 5001896 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 9
[junit4] 2> 5001903 T16040 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001905 T16091 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001916 T16075 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001916 T16076 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001919 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:59882";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 10
[junit4] 2> 5001924 T16053 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 5001927 T16098 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001929 T16052 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 5001966 T16096 oasc.ZkController.register We are
http://127.0.0.1:59882/collection1/ and leader is
http://127.0.0.1:59882/collection1/
[junit4] 2> 5001966 T16096 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:59882
[junit4] 2> 5001966 T16096 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 5001967 T16096 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 5001967 T16096 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 5001969 T16040 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 5001974 T16041 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit
[...truncated too long message...]
e=Direct)}, sim=DefaultSimilarity, locale=pt_BR, timezone=America/Goose_Bay
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_76
(64-bit)/cpus=2,threads=1,free=147620312,total=532152320
[junit4] 2> NOTE: All tests run in this JVM:
[WordBreakSolrSpellCheckerTest, RegexBoostProcessorTest,
TestBlendedInfixSuggestions, TestCollationFieldDocValues,
HdfsDirectoryFactoryTest, VMParamsZkACLAndCredentialsProvidersTest,
TestQuerySenderListener, TestReplicaProperties, TestShortCircuitedRequests,
CachingDirectoryFactoryTest, NoCacheHeaderTest, DateRangeFieldTest,
FullSolrCloudDistribCmdsTest, TestSchemaNameResource, TestBlobHandler,
TestManagedSchemaFieldResource, PrimitiveFieldTypeTest, TestRandomDVFaceting,
FastVectorHighlighterTest, TestCollationField, LeaderElectionTest,
ClusterStateTest, ReplicationFactorTest, BadCopyFieldTest,
FieldAnalysisRequestHandlerTest,
IgnoreCommitOptimizeUpdateProcessorFactoryTest,
OpenExchangeRatesOrgProviderTest, TestDocBasedVersionConstraints,
TestSolrConfigHandler, DistributedQueryComponentCustomSortTest,
DirectUpdateHandlerTest, TestArbitraryIndexDir,
TestReversedWildcardFilterFactory, TestCloudSchemaless,
TestSolrConfigHandlerCloud, FileUtilsTest, SignatureUpdateProcessorFactoryTest,
BlockCacheTest, TimeZoneUtilsTest, TestRealTimeGet, SolrRequestParserTest,
DistanceUnitsTest, TestPhraseSuggestions, TestStandardQParsers,
ActionThrottleTest, DistributedFacetPivotSmallAdvancedTest, TestJmxIntegration,
TestLeaderElectionZkExpiry, AddBlockUpdateTest, MigrateRouteKeyTest,
TestDistributedSearch, OutOfBoxZkACLAndCredentialsProvidersTest,
ZkSolrClientTest, BufferStoreTest, TestLMDirichletSimilarityFactory,
OverriddenZkACLAndCredentialsProvidersTest, ClusterStateUpdateTest,
TestObjectReleaseTracker, RequestLoggingTest, DebugComponentTest,
DocumentBuilderTest, CoreAdminCreateDiscoverTest, TestBinaryField,
MergeStrategyTest, SolrCoreCheckLockOnStartupTest, TestSimpleQParserPlugin,
TestDefaultSearchFieldResource, IndexSchemaRuntimeFieldTest,
TestAddFieldRealTimeGet, ConvertedLegacyTest, HighlighterConfigTest,
TestSchemaVersionResource, SoftAutoCommitTest, TestBulkSchemaConcurrent,
FileBasedSpellCheckerTest, HdfsBasicDistributedZk2Test,
HdfsBasicDistributedZkTest, OpenCloseCoreStressTest,
TestOverriddenPrefixQueryForCustomFieldType, AnalysisErrorHandlingTest,
AlternateDirectoryTest, CoreMergeIndexesAdminHandlerTest, OutputWriterTest,
TestBadConfig, DistributedFacetPivotSmallTest, TestTolerantSearch,
TestQuerySenderNoQuery, TestLFUCache, HardAutoCommitTest, TestCodecSupport,
ShardSplitTest, TestDynamicFieldCollectionResource,
TestSolrConfigHandlerConcurrent, DistributedQueueTest, PluginInfoTest,
TestHighFrequencyDictionaryFactory, AliasIntegrationTest, HighlighterTest,
QueryElevationComponentTest, ScriptEngineTest, TestMaxScoreQueryParser,
ShowFileRequestHandlerTest, SolrPluginUtilsTest, TestStressReorder,
TestLuceneMatchVersion, ShardRoutingTest, TestRandomMergePolicy,
RAMDirectoryFactoryTest, TestExactSharedStatsCache, TestComponentsName,
TestSolr4Spatial2, TestSchemaManager, URLClassifyProcessorTest,
TestCharFilters, UUIDUpdateProcessorFallbackTest, LukeRequestHandlerTest,
TestSchemaSimilarityResource, TestSolrXml, QueryEqualityTest, UpdateParamsTest,
HdfsUnloadDistributedZkTest, LeaderInitiatedRecoveryOnCommitTest,
IndexBasedSpellCheckerTest, ChaosMonkeyNothingIsSafeTest, QueryParsingTest,
PreAnalyzedFieldTest, TestStressRecovery, SuggesterWFSTTest,
ResponseHeaderTest, DeleteInactiveReplicaTest, TestSolrDeletionPolicy1,
TestSolrDynamicMBean, BinaryUpdateRequestHandlerTest,
ParsingFieldUpdateProcessorsTest, TestFunctionQuery, SolrCmdDistributorTest,
AssignTest, TestPostingsSolrHighlighter,
DistribDocExpirationUpdateProcessorTest, MoreLikeThisHandlerTest,
UnloadDistributedZkTest, TestManagedResource, CoreAdminRequestStatusTest,
TestSort, CurrencyFieldXmlFileTest, BasicDistributedZkTest, ZkControllerTest,
TestUpdate, TestBM25SimilarityFactory, TestAnalyzedSuggestions,
PathHierarchyTokenizerFactoryTest, TestWriterPerf, HdfsLockFactoryTest,
MinimalSchemaTest, PreAnalyzedUpdateProcessorTest, SuggestComponentTest,
TestFastWriter, RequiredFieldsTest, DeleteShardTest, TestQueryUtils,
NumericFieldsTest, AnalysisAfterCoreReloadTest, ResponseLogComponentTest,
MultiThreadedOCPTest, TestReqParamsAPI, TestCSVLoader, CursorMarkTest,
TestCloudInspectUtil, OverseerStatusTest, BasicDistributedZk2Test,
TestReplicationHandler, SpellCheckComponentTest, DocValuesTest,
TestDefaultSimilarityFactory, TestImplicitCoreProperties,
TestFileDictionaryLookup, DeleteLastCustomShardedReplicaTest,
ZkStateWriterTest, TestRebalanceLeaders, SuggesterFSTTest, SolrCoreTest,
PeerSyncTest, TermsComponentTest, ShardRoutingCustomTest,
TestClassNameShortening, TestGroupingSearch, TestDocumentBuilder,
TestDefaultStatsCache, StandardRequestHandlerTest, SolrIndexConfigTest,
SliceStateTest, TestDistribDocBasedVersion, TestCursorMarkWithoutUniqueKey,
DistributedSpellCheckComponentTest, TestStressLucene,
TestCollapseQParserPlugin, DateMathParserTest, SuggesterTest,
DistributedIntervalFacetingTest, TestReplicationHandlerBackup,
TestClusterStateMutator, XmlUpdateRequestHandlerTest, TestJmxMonitoredMap,
BasicZkTest, PolyFieldTest, SimpleMLTQParserTest, JSONWriterTest,
TestSolrQueryParser, StressHdfsTest, ConnectionManagerTest,
TestSolrCoreProperties, TestIntervalFaceting,
DocExpirationUpdateProcessorFactoryTest, DocValuesMultiTest,
StatsComponentTest, TestInitParams, SpellCheckCollatorTest, BadIndexSchemaTest,
RequestHandlersTest, TestFieldTypeResource, TestJoin, TestSystemIdResolver,
TestFoldingMultitermQuery, TestDynamicLoading, ChaosMonkeySafeLeaderTest,
CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest,
LeaderElectionIntegrationTest, RecoveryZkTest, TestRandomFaceting,
TestZkChroot, ZkCLITest, AutoCommitTest, TestDistributedGrouping, TestFaceting,
TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest,
TestReload, TestStressVersions, DistributedTermsComponentTest, TestRangeQuery,
TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, TestFiltering,
BasicFunctionalityTest, TestLazyCores, OverseerCollectionProcessorTest,
TestIndexSearcher, CurrencyFieldOpenExchangeTest,
DistributedQueryElevationComponentTest, SolrIndexSplitterTest,
SimplePostToolTest, TestCoreDiscovery, TestExtendedDismaxParser,
CoreAdminHandlerTest, SuggesterTSTTest, TestTrie, SpatialFilterTest,
SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields,
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases,
TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest,
DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest,
SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest,
SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, CacheHeaderTest,
TestSurroundQueryParser, DisMaxRequestHandlerTest, DirectSolrSpellCheckerTest,
DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions,
TestValueSourceCache, TermVectorComponentTest, TestIndexingPerformance,
LoggingHandlerTest, IndexSchemaTest, ReturnFieldsTest,
UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, MBeansHandlerTest,
UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest,
TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter,
PingRequestHandlerTest, CSVRequestHandlerTest, SearchHandlerTest,
TestBinaryResponseWriter, SOLR749Test, TestSolrIndexConfig, CopyFieldTest,
BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2,
MultiTermTest, SampleTest, TestDocSet, TestSearchPerf,
TestElisionMultitermQuery, TestConfig, TestFuzzyAnalyzedSuggestions,
ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest,
SpellPossibilityIteratorTest, SynonymTokenizerTest, TestXIncludeConfig,
EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory,
TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory,
TestIBSimilarityFactory, ResourceLoaderTest, TestFastOutputStream,
TestFastLRUCache, PrimUtilsTest, TestSuggestSpellingConverter, DateFieldTest,
SpellingQueryConverterTest, DOMUtilTest, TestSolrJ, TestLRUCache, TestUtils,
ZkNodePropsTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest,
DistributedMLTComponentTest, TestRTGBase, CursorPagingTest, SolrTestCaseJ4Test,
TestDistributedMissingSort, TestHighlightDedupGrouping,
AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest,
CollectionsAPIAsyncDistributedZkTest, CustomCollectionTest, DeleteReplicaTest,
DistribCursorPagingTest, DistribJoinFromCollectionTest,
ExternalCollectionsTest, HttpPartitionTest]
[junit4] Completed in 186.45s, 1 test, 1 error <<< FAILURES!
[...truncated 334 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:529: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:477: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:191: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:508: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1368:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:975:
There were test failures: 466 suites, 1861 tests, 1 error, 51 ignored (20
assumptions)
Total time: 164 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0
-XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
