Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11703/
Java: 32bit/jdk1.9.0-ea-b47 -client -XX:+UseParallelGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ReplicationFactorTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([E4A3A7A0817AC89C]:0)
FAILED: org.apache.solr.cloud.ReplicationFactorTest.test
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([E4A3A7A0817AC89C]:0)
Build Log:
[...truncated 10019 lines...]
[junit4] Suite: org.apache.solr.cloud.ReplicationFactorTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/init-core-data-001
[junit4] 2> 115063 T606 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /djnw/bz
[junit4] 2> 115065 T606 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 115066 T607 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 115166 T606 oasc.ZkTestServer.run start zk server on port:35230
[junit4] 2> 115167 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 115168 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 115170 T614 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1d7cb50 name:ZooKeeperConnection
Watcher:127.0.0.1:35230 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 115171 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 115171 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 115171 T606 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 115175 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 115176 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 115177 T617 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7b44b6 name:ZooKeeperConnection
Watcher:127.0.0.1:35230/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 115177 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 115178 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 115178 T606 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 115181 T606 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 115184 T606 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 115186 T606 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 115188 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 115189 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 115192 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 115193 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 115195 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 115195 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 115197 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 115197 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 115200 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 115200 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 115203 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 115203 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 115205 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 115205 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 115207 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 115208 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 115210 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 115211 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 115213 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 115213 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 115216 T606 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 115216 T606 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 115505 T606 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/cores/collection1
[junit4] 2> 115508 T606 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 115510 T606 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@f8aaf1{/djnw/bz,null,AVAILABLE}
[junit4] 2> 115546 T606 oejs.AbstractConnector.doStart Started
ServerConnector@1be017b{HTTP/1.1}{127.0.0.1:39985}
[junit4] 2> 115547 T606 oejs.Server.doStart Started @116576ms
[junit4] 2> 115547 T606 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@6f3b58
[junit4] 2> 115548 T606 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 115548 T606 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001
[junit4] 2> 115548 T606 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/'
[junit4] 2> 115565 T606 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/solr.xml
[junit4] 2> 115571 T606 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/cores
[junit4] 2> 115571 T606 oasc.CoreContainer.<init> New CoreContainer
13354563
[junit4] 2> 115572 T606 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/]
[junit4] 2> 115572 T606 oashc.HttpShardHandlerFactory.getParameter Setting
socketTimeout to: 90000
[junit4] 2> 115573 T606 oashc.HttpShardHandlerFactory.getParameter Setting
urlScheme to:
[junit4] 2> 115573 T606 oashc.HttpShardHandlerFactory.getParameter Setting
connTimeout to: 15000
[junit4] 2> 115573 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnectionsPerHost to: 20
[junit4] 2> 115573 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnections to: 10000
[junit4] 2> 115574 T606 oashc.HttpShardHandlerFactory.getParameter Setting
corePoolSize to: 0
[junit4] 2> 115574 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maximumPoolSize to: 2147483647
[junit4] 2> 115574 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxThreadIdleTime to: 5
[junit4] 2> 115575 T606 oashc.HttpShardHandlerFactory.getParameter Setting
sizeOfQueue to: -1
[junit4] 2> 115575 T606 oashc.HttpShardHandlerFactory.getParameter Setting
fairnessPolicy to: false
[junit4] 2> 115575 T606 oashc.HttpShardHandlerFactory.getParameter Setting
useRetries to: false
[junit4] 2> 115576 T606 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 115577 T606 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 115577 T606 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 115578 T606 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 115578 T606 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:35230/solr
[junit4] 2> 115579 T606 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 115579 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 115580 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 115582 T631 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@ba84b7 name:ZooKeeperConnection
Watcher:127.0.0.1:35230 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 115583 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 115584 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 115588 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 115590 T634 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@de25b name:ZooKeeperConnection
Watcher:127.0.0.1:35230/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 115591 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 115594 T606 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 115600 T606 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 115604 T606 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 115608 T606 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 115613 T606 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 115618 T606 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 115620 T606 oasc.ZkController.createEphemeralLiveNode Register
node as live in ZooKeeper:/live_nodes/127.0.0.1:48629_djnw%2Fbz
[junit4] 2> 115622 T606 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:48629_djnw%2Fbz
[junit4] 2> 115626 T606 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 115629 T606 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 115634 T606 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 115636 T606 oasc.OverseerElectionContext.runLeaderProcess I am
going to be the leader 127.0.0.1:48629_djnw%2Fbz
[junit4] 2> 115637 T606 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 115640 T606 oasc.Overseer.start Overseer
(id=93222974641799171-127.0.0.1:48629_djnw%2Fbz-n_0000000000) starting
[junit4] 2> 115645 T606 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 115661 T606 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 115662 T636 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 115664 T606 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 115667 T606 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 115670 T606
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 115678 T635 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 115679 T606 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/cores
[junit4] 2> 115681 T606 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/cores/collection1/
[junit4] 2> 115682 T606 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 115685 T638 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 115686 T638 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 115687 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 115688 T638 oasc.ZkController.waitForCoreNodeName look for our
core node name
[junit4] 2> 115694 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:48629/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:48629_djnw%2Fbz",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 115695 T635 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:48629/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:48629_djnw%2Fbz",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 115696 T635 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 115696 T635 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 115698 T634 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> 116689 T638 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 116689 T638 oasc.ZkController.createCollectionZkNode Check for
collection zkNode:control_collection
[junit4] 2> 116691 T638 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 116691 T638 oascc.ZkStateReader.readConfigName Load collection
config from:/collections/control_collection
[junit4] 2> 116693 T638 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 116693 T638 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/cores/collection1/'
[junit4] 2> 116718 T638 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 116722 T638 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 116730 T638 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 116735 T638 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 116747 T638 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 116749 T638 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 116757 T638 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 116881 T638 oass.OpenExchangeRatesOrgProvider.init Initialized
with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 116886 T638 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 116887 T638 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 116906 T638 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 116911 T638 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 116915 T638 oass.OpenExchangeRatesOrgProvider.reload Reloading
exchange rates from open-exchange-rates.json
[junit4] 2> 116917 T638
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 116917 T638
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 116918 T638 oass.OpenExchangeRatesOrgProvider.reload Reloading
exchange rates from open-exchange-rates.json
[junit4] 2> 116919 T638
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 116920 T638
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 116920 T638 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 116921 T638 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 116921 T638 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/control-001/cores/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/control/data/
[junit4] 2> 116921 T638 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1e82641
[junit4] 2> 116922 T638 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/control/data
[junit4] 2> 116923 T638 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/control/data/index/
[junit4] 2> 116923 T638 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 116924 T638 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/control/data/index
[junit4] 2> 116924 T638 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 116925 T638 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@10c0669
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16c58fd),segFN=segments_1,generation=1}
[junit4] 2> 116925 T638 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 116929 T638 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 116929 T638 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 116930 T638 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 116930 T638 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 116931 T638 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 116931 T638 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 116931 T638 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 116932 T638 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 116932 T638 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 116933 T638 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 116933 T638 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 116934 T638 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 116935 T638 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 116935 T638 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 116936 T638 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 116937 T638 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 116937 T638 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 116938 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 116939 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 116940 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 116940 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 116941 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 116942 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 116942 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 116943 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 116943 T638 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 116944 T638 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 116946 T638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 116947 T638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 116947 T638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 116948 T638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 116949 T638 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 116950 T638 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 116950 T638 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 116951 T638 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 116951 T638 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@10c0669
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16c58fd),segFN=segments_1,generation=1}
[junit4] 2> 116952 T638 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 116952 T638 oass.SolrIndexSearcher.<init> Opening
Searcher@17daa80[collection1] main
[junit4] 2> 116952 T638 oascc.ZkStateReader.readConfigName Load collection
config from:/collections/control_collection
[junit4] 2> 116953 T638 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 116954 T638 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 116954 T638
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 116955 T638 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 116955 T638 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 116956 T638
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 116957 T638 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 116957 T638 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 116957 T638 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 116959 T639 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@17daa80[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 116960 T638 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 116960 T642 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:48629/djnw/bz
collection:control_collection shard:shard1
[junit4] 2> 116961 T606 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 116962 T606 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 116962 T642 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 116964 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 116965 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 116970 T646 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1b7cced name:ZooKeeperConnection
Watcher:127.0.0.1:35230/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 116972 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 116972 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 116974 T606
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 116976 T642 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 116978 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 116979 T606 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 116979 T642
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 116980 T642 oasc.ShardLeaderElectionContext.runLeaderProcess I
may be the new leader - try and sync
[junit4] 2> 116979 T606 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> ASYNC NEW_CORE C86 name=collection1
org.apache.solr.core.SolrCore@fe63f1
url=http://127.0.0.1:48629/djnw/bz/collection1 node=127.0.0.1:48629_djnw%2Fbz
C86_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:48629/djnw/bz, node_name=127.0.0.1:48629_djnw%2Fbz,
state=down}
[junit4] 2> 116980 T642 C86 P48629 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:48629/djnw/bz/collection1/
[junit4] 2> 116980 T635 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> 116982 T642 C86 P48629 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 116980 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 116982 T642 C86 P48629 oasc.SyncStrategy.syncToMe
http://127.0.0.1:48629/djnw/bz/collection1/ has no replicas
[junit4] 2> 116983 T642 oasc.ShardLeaderElectionContext.runLeaderProcess I
am the new leader: http://127.0.0.1:48629/djnw/bz/collection1/ shard1
[junit4] 2> 116983 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 116984 T642 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 116985 T649 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1b871b6 name:ZooKeeperConnection
Watcher:127.0.0.1:35230/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 116985 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 116986 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 116989 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 116990 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "stateFormat":"2"} current state version: 1
[junit4] 2> 116991 T635 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 116992 T646 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> 116993 T634 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> 116999 T635 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:48629/djnw/bz";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 117000 T635 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 117001 T646 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> 117006 T650 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 117007 T646 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> 117044 T642 oasc.ZkController.register We are
http://127.0.0.1:48629/djnw/bz/collection1/ and leader is
http://127.0.0.1:48629/djnw/bz/collection1/
[junit4] 2> 117044 T642 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:48629/djnw/bz
[junit4] 2> 117044 T642 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 117044 T642 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 117045 T642 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 117046 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 117048 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:48629/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:48629_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 117049 T635 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:48629/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:48629_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 117152 T646 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> 117152 T634 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> 117299 T606 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/cores/collection1
[junit4] 2> 117300 T606 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 117301 T606 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 117303 T606 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@2c5528{/djnw/bz,null,AVAILABLE}
[junit4] 2> 117303 T606 oejs.AbstractConnector.doStart Started
ServerConnector@1823c6a{HTTP/1.1}{127.0.0.1:40488}
[junit4] 2> 117304 T606 oejs.Server.doStart Started @118333ms
[junit4] 2> 117304 T606 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@6f3b58
[junit4] 2> 117305 T606 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 117305 T606 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001
[junit4] 2> 117305 T606 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/'
[junit4] 2> 117322 T606 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/solr.xml
[junit4] 2> 117327 T606 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/cores
[junit4] 2> 117328 T606 oasc.CoreContainer.<init> New CoreContainer
10914717
[junit4] 2> 117328 T606 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/]
[junit4] 2> 117328 T606 oashc.HttpShardHandlerFactory.getParameter Setting
socketTimeout to: 90000
[junit4] 2> 117329 T606 oashc.HttpShardHandlerFactory.getParameter Setting
urlScheme to:
[junit4] 2> 117329 T606 oashc.HttpShardHandlerFactory.getParameter Setting
connTimeout to: 15000
[junit4] 2> 117329 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnectionsPerHost to: 20
[junit4] 2> 117329 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnections to: 10000
[junit4] 2> 117330 T606 oashc.HttpShardHandlerFactory.getParameter Setting
corePoolSize to: 0
[junit4] 2> 117330 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maximumPoolSize to: 2147483647
[junit4] 2> 117330 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxThreadIdleTime to: 5
[junit4] 2> 117330 T606 oashc.HttpShardHandlerFactory.getParameter Setting
sizeOfQueue to: -1
[junit4] 2> 117331 T606 oashc.HttpShardHandlerFactory.getParameter Setting
fairnessPolicy to: false
[junit4] 2> 117331 T606 oashc.HttpShardHandlerFactory.getParameter Setting
useRetries to: false
[junit4] 2> 117332 T606 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 117332 T606 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 117332 T606 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 117332 T606 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 117333 T606 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:35230/solr
[junit4] 2> 117333 T606 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 117333 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 117334 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 117336 T664 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@584a58 name:ZooKeeperConnection
Watcher:127.0.0.1:35230 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 117337 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 117337 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 117340 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 117341 T667 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@19439b1 name:ZooKeeperConnection
Watcher:127.0.0.1:35230/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 117341 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 117350 T606
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 118358 T606 oasc.ZkController.createEphemeralLiveNode Register
node as live in ZooKeeper:/live_nodes/127.0.0.1:59191_djnw%2Fbz
[junit4] 2> 118359 T606 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:59191_djnw%2Fbz
[junit4] 2> 118363 T606 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 118366 T606 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/cores
[junit4] 2> 118366 T606 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/cores/collection1/
[junit4] 2> 118367 T606 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 118370 T668 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 118371 T668 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 118373 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 118373 T668 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 118374 T668 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 118374 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59191/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:59191_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 118374 T668 oascc.ZkStateReader.addZkWatch Updating collection
state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 118375 T635 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59191/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:59191_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 118375 T635 oasco.ReplicaMutator.updateState Collection
already exists with numShards=3
[junit4] 2> 118375 T635 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 118376 T668 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 118376 T668 oasc.ZkController.waitForCoreNodeName look for our
core node name
[junit4] 2> 118479 T635 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 118480 T667 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 118481 T667 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 119376 T668 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 119377 T668 oasc.ZkController.createCollectionZkNode Check for
collection zkNode:collection1
[junit4] 2> 119378 T668 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 119378 T668 oascc.ZkStateReader.readConfigName Load collection
config from:/collections/collection1
[junit4] 2> 119379 T668 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 119380 T668 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/cores/collection1/'
[junit4] 2> 119394 T668 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 119396 T668 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 119403 T668 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 119409 T668 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 119422 T668 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 119424 T668 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 119431 T668 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 119550 T668 oass.OpenExchangeRatesOrgProvider.init Initialized
with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 119554 T668 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 119556 T668 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 119572 T668 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 119575 T668 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 119577 T668 oass.OpenExchangeRatesOrgProvider.reload Reloading
exchange rates from open-exchange-rates.json
[junit4] 2> 119579 T668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 119580 T668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 119580 T668 oass.OpenExchangeRatesOrgProvider.reload Reloading
exchange rates from open-exchange-rates.json
[junit4] 2> 119581 T668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 119581 T668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 119582 T668 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 119582 T668 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 119582 T668 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-1-001/cores/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty1/
[junit4] 2> 119582 T668 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1e82641
[junit4] 2> 119584 T668 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty1
[junit4] 2> 119584 T668 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty1/index/
[junit4] 2> 119584 T668 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 119585 T668 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty1/index
[junit4] 2> 119585 T668 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 119586 T668 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@10d38ce
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8bd62),segFN=segments_1,generation=1}
[junit4] 2> 119586 T668 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 119591 T668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 119591 T668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 119592 T668 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 119592 T668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 119592 T668 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 119592 T668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 119593 T668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 119594 T668 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 119594 T668 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 119597 T668 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 119599 T668 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 119600 T668 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 119602 T668 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 119603 T668 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 119604 T668 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 119605 T668 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 119606 T668 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 119607 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 119608 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 119608 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 119609 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 119610 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 119611 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 119612 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 119613 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 119614 T668 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 119615 T668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 119618 T668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 119619 T668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 119620 T668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 119621 T668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 119622 T668 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 119623 T668 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 119623 T668 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 119625 T668 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 119625 T668 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@10d38ce
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8bd62),segFN=segments_1,generation=1}
[junit4] 2> 119625 T668 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 119626 T668 oass.SolrIndexSearcher.<init> Opening
Searcher@179117[collection1] main
[junit4] 2> 119626 T668 oascc.ZkStateReader.readConfigName Load collection
config from:/collections/collection1
[junit4] 2> 119627 T668 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 119627 T668 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 119628 T668
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 119628 T668 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 119628 T668 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 119629 T668
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 119629 T668 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 119630 T668 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 119630 T668 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 119631 T669 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@179117[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 119632 T668 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 119633 T672 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:59191/djnw/bz collection:collection1
shard:shard2
[junit4] 2> 119634 T606 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 119634 T606 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 119634 T672 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 119640 T672 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 119641 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 119641 T672
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 119642 T672 oasc.ShardLeaderElectionContext.runLeaderProcess I
may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C87 name=collection1
org.apache.solr.core.SolrCore@1da4820
url=http://127.0.0.1:59191/djnw/bz/collection1 node=127.0.0.1:59191_djnw%2Fbz
C87_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:59191/djnw/bz, node_name=127.0.0.1:59191_djnw%2Fbz,
state=down}
[junit4] 2> 119642 T672 C87 P59191 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:59191/djnw/bz/collection1/
[junit4] 2> 119642 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 119642 T672 C87 P59191 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 119643 T672 C87 P59191 oasc.SyncStrategy.syncToMe
http://127.0.0.1:59191/djnw/bz/collection1/ has no replicas
[junit4] 2> 119643 T672 oasc.ShardLeaderElectionContext.runLeaderProcess I
am the new leader: http://127.0.0.1:59191/djnw/bz/collection1/ shard2
[junit4] 2> 119643 T672 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 119644 T635 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 119645 T667 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 119645 T667 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 119653 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 119654 T635 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:59191/djnw/bz";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 119655 T635 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 119656 T667 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 119657 T667 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 119704 T672 oasc.ZkController.register We are
http://127.0.0.1:59191/djnw/bz/collection1/ and leader is
http://127.0.0.1:59191/djnw/bz/collection1/
[junit4] 2> 119704 T672 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:59191/djnw/bz
[junit4] 2> 119705 T672 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 119705 T672 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 119705 T672 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 119706 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 119709 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59191/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:59191_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 119710 T635 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59191/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:59191_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 119711 T635 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 119712 T667 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 119712 T672 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 119713 T667 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 119987 T606 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/cores/collection1
[junit4] 2> 119988 T606 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 119989 T606 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 119991 T606 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@887fdc{/djnw/bz,null,AVAILABLE}
[junit4] 2> 119992 T606 oejs.AbstractConnector.doStart Started
ServerConnector@4fd88b{HTTP/1.1}{127.0.0.1:36262}
[junit4] 2> 119992 T606 oejs.Server.doStart Started @121022ms
[junit4] 2> 119993 T606 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@6f3b58
[junit4] 2> 119993 T606 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 119994 T606 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001
[junit4] 2> 119994 T606 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/'
[junit4] 2> 120012 T606 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/solr.xml
[junit4] 2> 120017 T606 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/cores
[junit4] 2> 120018 T606 oasc.CoreContainer.<init> New CoreContainer
33278896
[junit4] 2> 120018 T606 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/]
[junit4] 2> 120019 T606 oashc.HttpShardHandlerFactory.getParameter Setting
socketTimeout to: 90000
[junit4] 2> 120019 T606 oashc.HttpShardHandlerFactory.getParameter Setting
urlScheme to:
[junit4] 2> 120019 T606 oashc.HttpShardHandlerFactory.getParameter Setting
connTimeout to: 15000
[junit4] 2> 120020 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnectionsPerHost to: 20
[junit4] 2> 120020 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnections to: 10000
[junit4] 2> 120020 T606 oashc.HttpShardHandlerFactory.getParameter Setting
corePoolSize to: 0
[junit4] 2> 120020 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maximumPoolSize to: 2147483647
[junit4] 2> 120021 T606 oashc.HttpShardHandlerFactory.getParameter Setting
maxThreadIdleTime to: 5
[junit4] 2> 120021 T606 oashc.HttpShardHandlerFactory.getParameter Setting
sizeOfQueue to: -1
[junit4] 2> 120021 T606 oashc.HttpShardHandlerFactory.getParameter Setting
fairnessPolicy to: false
[junit4] 2> 120021 T606 oashc.HttpShardHandlerFactory.getParameter Setting
useRetries to: false
[junit4] 2> 120022 T606 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 120022 T606 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 120023 T606 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 120023 T606 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 120023 T606 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:35230/solr
[junit4] 2> 120024 T606 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 120024 T606
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 120025 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 120028 T687 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1e0796d name:ZooKeeperConnection
Watcher:127.0.0.1:35230 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 120029 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 120029 T606 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 120032 T606 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 120033 T690 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1f76e40 name:ZooKeeperConnection
Watcher:127.0.0.1:35230/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 120033 T606 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 120038 T606
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 121043 T606 oasc.ZkController.createEphemeralLiveNode Register
node as live in ZooKeeper:/live_nodes/127.0.0.1:54950_djnw%2Fbz
[junit4] 2> 121055 T606 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:54950_djnw%2Fbz
[junit4] 2> 121063 T606 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 121068 T606 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/cores
[junit4] 2> 121069 T606 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/cores/collection1/
[junit4] 2> 121070 T606 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 121074 T691 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 121074 T691 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 121076 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 121077 T691 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 121077 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:54950/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:54950_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 121077 T691 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 121078 T635 oasco.ReplicaMutator.updateState Update state
numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:54950/djnw/bz";,
[junit4] 2> "node_name":"127.0.0.1:54950_djnw%2Fbz",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 121078 T691 oascc.ZkStateReader.addZkWatch Updating collection
state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 121079 T635 oasco.ReplicaMutator.updateState Collection
already exists with numShards=3
[junit4] 2> 121079 T635 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard3
[junit4] 2> 121080 T691 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 121081 T635 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 121081 T691 oasc.ZkController.waitForCoreNodeName look for our
core node name
[junit4] 2> 121082 T667 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 121082 T690 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 121082 T667 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 121083 T690 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 122081 T691 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 122082 T691 oasc.ZkController.createCollectionZkNode Check for
collection zkNode:collection1
[junit4] 2> 122083 T691 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 122083 T691 oascc.ZkStateReader.readConfigName Load collection
config from:/collections/collection1
[junit4] 2> 122084 T691 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 122084 T691 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/cores/collection1/'
[junit4] 2> 122101 T691 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 122103 T691 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 122110 T691 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 122114 T691 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 122125 T691 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 122127 T691 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 122134 T691 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 122247 T691 oass.OpenExchangeRatesOrgProvider.init Initialized
with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 122251 T691 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 122253 T691 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 122264 T691 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 122267 T691 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 122269 T691 oass.OpenExchangeRatesOrgProvider.reload Reloading
exchange rates from open-exchange-rates.json
[junit4] 2> 122270 T691
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 122270 T691
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 122271 T691 oass.OpenExchangeRatesOrgProvider.reload Reloading
exchange rates from open-exchange-rates.json
[junit4] 2> 122272 T691
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 122272 T691
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 122272 T691 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 122272 T691 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 122272 T691 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/shard-2-001/cores/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty2/
[junit4] 2> 122273 T691 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1e82641
[junit4] 2> 122274 T691 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty2
[junit4] 2> 122275 T691 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty2/index/
[junit4] 2> 122275 T691 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 122275 T691 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest
E4A3A7A0817AC89C-001/tempDir-001/jetty2/index
[junit4] 2> 122276 T691 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 122276 T691 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1f59d1c
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b60c7),segFN=segments_1,generation=1}
[junit4] 2> 122277 T691 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 122281 T691 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 122282 T691 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 122282 T691 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 122282 T691 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 122282 T691 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 122283 T691 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 122283 T691 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 122283 T691 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 122283 T691 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 122284 T691 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122285 T691 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122285 T691 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122286 T691 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122287 T691 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 122287 T691 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 122289 T691 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 122290 T691 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 122290 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 122291 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 122292 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 122292 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 122293 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 122294 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 122294 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 122295 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 122295 T691 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 122296 T691 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 122298 T691 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122299 T691 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122300 T691 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122302 T691 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122303 T691 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 122303 T691 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 122304 T691 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 122305 T691 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 122305 T691 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1f59d1c
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b60c7),segFN=segments_1,generation=1}
[junit4] 2> 122306 T691 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 122306 T691 oass.SolrIndexSearcher.<init> Opening
Searcher@1548c8a[collection1] main
[junit4] 2> 122306 T691 oascc.ZkStateReader.readConfigName Load collection
config from:/collections/collection1
[junit4] 2> 122307 T691 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 122307 T691 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 122308 T691
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 122308 T691 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 122309 T691 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 122309 T691
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 122310 T691 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 122310 T691 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 122310 T691 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 122312 T692 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1548c8a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122313 T691 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 122314 T695 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:54950/djnw/bz collection:collection1
shard:shard3
[junit4] 2> 122314 T606 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4] 2> 122315 T606 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 122315 T695 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard3/election
[junit4] 2> 122322 T695 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard3
[junit4] 2> 122323 T634 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 122324 T695
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 122324 T695 oasc.ShardLeaderElectionContext.runLeaderProcess I
may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C88 name=collection1
org.apache.solr.core.SolrCore@d488a4
url=http://127.0.0.1:54950/djnw/bz/collection1 node=127.0.0.1:54950_djnw%2Fbz
C88_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:54950/djnw/bz, node_name=127.0.0.1:54950_djnw%2Fbz,
state=down}
[junit4] 2> 122324 T695 C88 P54950 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:54950/djnw/bz/collection1/
[junit4] 2> 122324 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 122324 T695 C88 P54950 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 122325 T695 C88 P54950 oasc.SyncStrategy.syncToMe
http://127.0.0.1:54950/djnw/bz/collection1/ has no replicas
[junit4] 2> 122325 T695 oasc.ShardLeaderElectionContext.runLeaderProcess I
am the new leader: http://127.0.0.1:54950/djnw/bz/collection1/ shard3
[junit4] 2> 122325 T695 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard3
[junit4] 2> 122325 T635 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 5
[junit4] 2> 122326 T690 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 122326 T667 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 122327 T690 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 122328 T667 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 122330 T635 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:54950/djnw/bz";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 1
[...truncated too long message...]
2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 49) Thread[id=731, name=Scheduler-31014098,
state=WAITING, group=TGRP-ReplicationFactorTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 50) Thread[id=695, name=coreZkRegister-275-thread-1,
state=WAITING, group=TGRP-ReplicationFactorTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 51) Thread[id=652,
name=qtp32208252-652-selector-ServerConnectorManager@ad5865/0, state=RUNNABLE,
group=TGRP-ReplicationFactorTest]
[junit4] 2> at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] 2> at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:90)
[junit4] 2> at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.select(SelectorManager.java:596)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.run(SelectorManager.java:545)
[junit4] 2> at
org.eclipse.jetty.util.thread.NonBlockingThread.run(NonBlockingThread.java:52)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:626)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:546)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 52) Thread[id=861,
name=searcherExecutor-305-thread-1, state=WAITING,
group=TGRP-ReplicationFactorTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 53) Thread[id=693, name=Thread-194, state=WAITING,
group=TGRP-ReplicationFactorTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at
org.apache.solr.core.CloserThread.run(CoreContainer.java:902)
[junit4] 2> 54) Thread[id=676,
name=qtp10042300-676-selector-ServerConnectorManager@15bbb01/1, state=RUNNABLE,
group=TGRP-ReplicationFactorTest]
[junit4] 2> at
sun.nio.ch.EPollArrayWrapper.interrupt(Native Method)
[junit4] 2> at
sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317)
[junit4] 2> at
sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:193)
[junit4] 2> at
java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213)
[junit4] 2> at
java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219)
[junit4] 2> at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:78)
[junit4] 2> at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.select(SelectorManager.java:596)
[junit4] 2> at
org.eclipse.jetty.io.SelectorManager$ManagedSelector.run(SelectorManager.java:545)
[junit4] 2> at
org.eclipse.jetty.util.thread.NonBlockingThread.run(NonBlockingThread.java:52)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:626)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:546)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{_version_=PostingsFormat(name=Memory doPackFST= true),
multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval),
a_t=PostingsFormat(name=LuceneVarGapFixedInterval),
intDefault=PostingsFormat(name=Memory doPackFST= true),
id=PostingsFormat(name=Asserting), text=PostingsFormat(name=MockRandom),
range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval),
timestamp=PostingsFormat(name=LuceneVarGapFixedInterval)},
docValues:{timestamp=DocValuesFormat(name=Lucene50)},
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=sv_SE,
timezone=Hongkong
[junit4] 2> NOTE: Linux 3.13.0-44-generic i386/Oracle Corporation 1.9.0-ea
(32-bit)/cpus=8,threads=73,free=69488840,total=239075328
[junit4] 2> NOTE: All tests run in this JVM: [SSLMigrationTest,
UUIDFieldTest, TestAnalyzeInfixSuggestions,
CollectionsAPIAsyncDistributedZkTest, TestCustomSort,
TriLevelCompositeIdRoutingTest, PrimUtilsTest, DOMUtilTest,
AliasIntegrationTest, SolrCoreTest, TestStressUserVersions, InfoHandlerTest,
SuggesterFSTTest, TestCollationField, CircularListTest, TestGroupingSearch,
ReplicationFactorTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ReplicationFactorTest -Dtests.seed=E4A3A7A0817AC89C
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv_SE
-Dtests.timezone=Hongkong -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | ReplicationFactorTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([E4A3A7A0817AC89C]:0)
[junit4] Completed on J0 in 7224.09s, 1 test, 2 errors <<< FAILURES!
[...truncated 311 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:191: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1348:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:956:
There were test failures: 461 suites, 1845 tests, 1 suite-level error, 1 error,
309 ignored (19 assumptions)
Total time: 149 minutes 51 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.9.0-ea-b47 -client
-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]
