Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.10-Linux/58/
Java: 32bit/ibm-j9-jdk7
-Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.MultiThreadedOCPTest
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.cloud.MultiThreadedOCPTest: 1) Thread[id=1839,
name=OverseerThreadFactory-812-thread-5, state=TIMED_WAITING, group=Overseer
collection creation process.] at java.lang.Thread.sleep(Native Method)
at java.lang.Thread.sleep(Thread.java:977) at
org.apache.solr.cloud.OverseerCollectionProcessor.waitForCoreNodeName(OverseerCollectionProcessor.java:1662)
at
org.apache.solr.cloud.OverseerCollectionProcessor.splitShard(OverseerCollectionProcessor.java:1544)
at
org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:564)
at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2677)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.lang.Thread.run(Thread.java:853) 2) Thread[id=1975,
name=OverseerThreadFactory-998-thread-1, state=TIMED_WAITING, group=Overseer
collection creation process.] at java.lang.Thread.sleep(Native Method)
at java.lang.Thread.sleep(Thread.java:977) at
org.apache.solr.cloud.OverseerCollectionProcessor.waitForCoreNodeName(OverseerCollectionProcessor.java:1662)
at
org.apache.solr.cloud.OverseerCollectionProcessor.splitShard(OverseerCollectionProcessor.java:1418)
at
org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:564)
at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2677)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.lang.Thread.run(Thread.java:853)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.MultiThreadedOCPTest:
1) Thread[id=1839, name=OverseerThreadFactory-812-thread-5,
state=TIMED_WAITING, group=Overseer collection creation process.]
at java.lang.Thread.sleep(Native Method)
at java.lang.Thread.sleep(Thread.java:977)
at
org.apache.solr.cloud.OverseerCollectionProcessor.waitForCoreNodeName(OverseerCollectionProcessor.java:1662)
at
org.apache.solr.cloud.OverseerCollectionProcessor.splitShard(OverseerCollectionProcessor.java:1544)
at
org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:564)
at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2677)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.lang.Thread.run(Thread.java:853)
2) Thread[id=1975, name=OverseerThreadFactory-998-thread-1,
state=TIMED_WAITING, group=Overseer collection creation process.]
at java.lang.Thread.sleep(Native Method)
at java.lang.Thread.sleep(Thread.java:977)
at
org.apache.solr.cloud.OverseerCollectionProcessor.waitForCoreNodeName(OverseerCollectionProcessor.java:1662)
at
org.apache.solr.cloud.OverseerCollectionProcessor.splitShard(OverseerCollectionProcessor.java:1418)
at
org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:564)
at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2677)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.lang.Thread.run(Thread.java:853)
at __randomizedtesting.SeedInfo.seed([2A8AFE9F121C5450]:0)
Build Log:
[...truncated 11126 lines...]
[junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/init-core-data-001
[junit4] 2> 543340 T1652 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (false)
[junit4] 2> 543341 T1652 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 543349 T1652 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 543350 T1652 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 543363 T1653 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 543451 T1652 oasc.ZkTestServer.run start zk server on
port:48537
[junit4] 2> 543452 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 543463 T1659 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6d38c7b name:ZooKeeperConnection
Watcher:127.0.0.1:48537 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 543463 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 543464 T1652 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 543474 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 543491 T1661 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@9d452ea8
name:ZooKeeperConnection Watcher:127.0.0.1:48537/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 543491 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 543492 T1652 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 543494 T1652 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 543496 T1652 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 543498 T1652 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 543501 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 543501 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 543504 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 543505 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 543508 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 543509 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 543513 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 543513 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 543517 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 543517 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 543521 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 543521 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 543524 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 543525 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 543530 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 543531 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 543535 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 543536 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 543541 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 543541 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 543546 T1652 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 543547 T1652 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 543558 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 543565 T1663 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@cf10fed3
name:ZooKeeperConnection Watcher:127.0.0.1:48537/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 543566 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 544014 T1652 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 544018 T1652 oejus.SslContextFactory.doStart Enabled Protocols
[TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 544020 T1652 oejs.AbstractConnector.doStart Started
[email protected]:59790
[junit4] 2> 544023 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 544023 T1652 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 544024 T1652 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002
[junit4] 2> 544024 T1652 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/'
[junit4] 2> 544032 T1652 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/solr.xml
[junit4] 2> 544070 T1652 oasc.CoreContainer.<init> New CoreContainer
358909034
[junit4] 2> 544071 T1652 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/]
[junit4] 2> 544073 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 544074 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 544074 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 544075 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 544076 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 544077 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 544078 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 544078 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 544079 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 544080 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 544083 T1652 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 544085 T1652 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 544086 T1652 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 544087 T1652 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 544088 T1652 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:48537/solr
[junit4] 2> 544089 T1652 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 544090 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 544111 T1674 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1ad53e8f
name:ZooKeeperConnection Watcher:127.0.0.1:48537 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 544116 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 544121 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 544123 T1676 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@d24c20c6
name:ZooKeeperConnection Watcher:127.0.0.1:48537/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 544126 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 544131 T1652 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 544135 T1652 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 544138 T1652 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 544142 T1652 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 544145 T1652 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 544148 T1652 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 544150 T1652 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59790_
[junit4] 2> 544152 T1652 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:59790_
[junit4] 2> 544155 T1652 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 544158 T1652 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 544160 T1652 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 544163 T1652 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:59790_
[junit4] 2> 544164 T1652 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 544169 T1652 oasc.Overseer.start Overseer
(id=93421548137742340-127.0.0.1:59790_-n_0000000000) starting
[junit4] 2> 544172 T1652 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 544181 T1652 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 544183 T1652 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 544186 T1652 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 544188 T1652
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 544195 T1677 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 544201 T1678 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 544210 T1680 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 544210 T1680 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 544213 T1680 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 544220 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 544222 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "state":"down",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"https://127.0.0.1:59790";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:59790_",
[junit4] 2> "core":"collection1"}
[junit4] 2> 544223 T1677
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
control_collection with shards [shard1]
[junit4] 2> 544223 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 544225 T1681 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> 545213 T1680 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 545214 T1680 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 545215 T1680 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 545215 T1680 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 545216 T1680 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 545216 T1680 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/'
[junit4] 2> 545217 T1680 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 545217 T1680 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 545218 T1680 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 545283 T1680 oasc.SolrConfig.<init> Using Lucene MatchVersion:
4.10.5
[junit4] 2> 545352 T1680 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 545357 T1680 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 545377 T1680 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 545983 T1680 oass.ByteField.init WARN ByteField is deprecated
and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 545983 T1680 oass.ShortField.init WARN ShortField is
deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 545984 T1680 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 545994 T1680 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 545998 T1680 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 546042 T1680 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 546053 T1680 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 546068 T1680 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 546069 T1680
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 546070 T1680
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 546072 T1680 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 546073 T1680
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 546074 T1680
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 546075 T1680 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 546075 T1680 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 546076 T1680 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/,
dataDir=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/data/
[junit4] 2> 546076 T1680 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5b434ba
[junit4] 2> 546078 T1680 oasc.CachingDirectoryFactory.get return new
directory for
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/data
[junit4] 2> 546078 T1680 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/data/index/
[junit4] 2> 546079 T1680 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 546081 T1680 oasc.CachingDirectoryFactory.get return new
directory for
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-002/collection1/data/index
[junit4] 2> 546084 T1680 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=73.939453125,
floorSegmentMB=1.447265625, forceMergeDeletesPctAllowed=5.959537686378934,
segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.28425457461540415
[junit4] 2> 546087 T1680 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@38fd3969
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@82648176),segFN=segments_1,generation=1}
[junit4] 2> 546087 T1680 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 546092 T1680 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 546092 T1680 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 546093 T1680 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 546093 T1680 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 546094 T1680 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 546094 T1680 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 546095 T1680 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 546095 T1680 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 546096 T1680 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 546097 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 546097 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 546098 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 546098 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 546099 T1680 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 546099 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 546100 T1680 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 546100 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 546101 T1680 oasc.RequestHandlers.initHandlersFromConfig WARN
Multiple requestHandler registered to the same name: /update ignoring:
org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 546101 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 546101 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 546102 T1680 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 546104 T1680 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 546106 T1680 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 546107 T1680 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 546109 T1680 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 546115 T1680 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 546119 T1680 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 546120 T1680 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 546121 T1680 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 546121 T1680 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@38fd3969
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@82648176),segFN=segments_1,generation=1}
[junit4] 2> 546122 T1680 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 546123 T1680 oass.SolrIndexSearcher.<init> Opening
Searcher@e048d0c9[collection1] main
[junit4] 2> 546123 T1680 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 546124 T1680 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 546125 T1680 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 546125 T1680
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 546126 T1680 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 546126 T1680 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 546127 T1680
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 546127 T1680 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 546128 T1680 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 546130 T1680
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 546130 T1680 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 546131 T1680 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 546135 T1680 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 546135 T1682 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@e048d0c9[collection1]
main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 546136 T1685 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:59790 collection:control_collection
shard:shard1
[junit4] 2> 546136 T1652 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1
[junit4] 2> 546138 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 546143 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 546145 T1685 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 546153 T1685 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 546159 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 546159 T1687 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@a48e028 name:ZooKeeperConnection
Watcher:127.0.0.1:48537/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 546160 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 546161 T1685
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 546161 T1685 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C624 name=collection1
org.apache.solr.core.SolrCore@88c9f219 url=https://127.0.0.1:59790/collection1
node=127.0.0.1:59790_ C624_STATE=coll:control_collection core:collection1
props:{state=down, base_url=https://127.0.0.1:59790,
node_name=127.0.0.1:59790_, core=collection1}
[junit4] 2> 546162 T1685 C624 P59790 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:59790/collection1/
[junit4] 2> 546162 T1685 C624 P59790 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 546163 T1685 C624 P59790 oasc.SyncStrategy.syncToMe
https://127.0.0.1:59790/collection1/ has no replicas
[junit4] 2> 546163 T1685 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:59790/collection1/ shard1
[junit4] 2> 546164 T1685 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 546165 T1652
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 546173 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 546174 T1652 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 546174 T1681 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> 546209 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 546314 T1688 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> 546315 T1681 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> 546361 T1685 oasc.ZkController.register We are
https://127.0.0.1:59790/collection1/ and leader is
https://127.0.0.1:59790/collection1/
[junit4] 2> 546361 T1685 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:59790
[junit4] 2> 546362 T1685 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 546362 T1685 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 546363 T1685 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 546365 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 546368 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"https://127.0.0.1:59790";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:59790_",
[junit4] 2> "core":"collection1"}
[junit4] 2> 546472 T1688 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> 546472 T1681 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> 546649 T1652 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 546651 T1652 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 546656 T1652 oejus.SslContextFactory.doStart Enabled Protocols
[TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 546658 T1652 oejs.AbstractConnector.doStart Started
[email protected]:60631
[junit4] 2> 546662 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 546663 T1652 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 546664 T1652 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003
[junit4] 2> 546665 T1652 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/'
[junit4] 2> 546681 T1652 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/solr.xml
[junit4] 2> 546744 T1652 oasc.CoreContainer.<init> New CoreContainer
-1906723487
[junit4] 2> 546745 T1652 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/]
[junit4] 2> 546747 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 546748 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 546749 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 546750 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 546751 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 546752 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 546753 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 546754 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 546755 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 546756 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 546759 T1652 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 546762 T1652 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 546764 T1652 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 546765 T1652 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 546766 T1652 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:48537/solr
[junit4] 2> 546767 T1652 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 546768 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 546783 T1699 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@889b93aa
name:ZooKeeperConnection Watcher:127.0.0.1:48537 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 546783 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 546787 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 546790 T1701 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@69ae93cb
name:ZooKeeperConnection Watcher:127.0.0.1:48537/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 546790 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 546802 T1652
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 547806 T1652 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60631_
[junit4] 2> 547807 T1652 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:60631_
[junit4] 2> 547810 T1652 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 547817 T1703 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 547817 T1703 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 547818 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 547818 T1703 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 547820 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"https://127.0.0.1:60631";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:60631_",
[junit4] 2> "core":"collection1"}
[junit4] 2> 547821 T1677
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 547821 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 547923 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 547924 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 547923 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 548820 T1703 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 548820 T1703 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 548821 T1703 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 548821 T1703 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 548822 T1703 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 548822 T1703 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/'
[junit4] 2> 548823 T1703 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 548824 T1703 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 548824 T1703 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 548867 T1703 oasc.SolrConfig.<init> Using Lucene MatchVersion:
4.10.5
[junit4] 2> 548903 T1703 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 548904 T1703 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 548909 T1703 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 549357 T1703 oass.ByteField.init WARN ByteField is deprecated
and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 549357 T1703 oass.ShortField.init WARN ShortField is
deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 549358 T1703 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 549370 T1703 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 549373 T1703 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 549384 T1703 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 549388 T1703 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 549392 T1703 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 549393 T1703
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 549393 T1703
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 549394 T1703 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 549394 T1703
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 549395 T1703
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 549395 T1703 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 549395 T1703 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 549395 T1703 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/,
dataDir=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/data/
[junit4] 2> 549396 T1703 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5b434ba
[junit4] 2> 549397 T1703 oasc.CachingDirectoryFactory.get return new
directory for
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/data
[junit4] 2> 549397 T1703 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/data/index/
[junit4] 2> 549397 T1703 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 549398 T1703 oasc.CachingDirectoryFactory.get return new
directory for
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-003/collection1/data/index
[junit4] 2> 549398 T1703 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=73.939453125,
floorSegmentMB=1.447265625, forceMergeDeletesPctAllowed=5.959537686378934,
segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.28425457461540415
[junit4] 2> 549399 T1703 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@78b6bc8d
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d8cfb2d6),segFN=segments_1,generation=1}
[junit4] 2> 549400 T1703 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 549410 T1703 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 549411 T1703 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 549413 T1703 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 549414 T1703 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 549415 T1703 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 549416 T1703 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 549416 T1703 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 549417 T1703 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 549418 T1703 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 549421 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 549421 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 549422 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 549423 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 549424 T1703 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 549424 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 549425 T1703 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 549426 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 549426 T1703 oasc.RequestHandlers.initHandlersFromConfig WARN
Multiple requestHandler registered to the same name: /update ignoring:
org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 549427 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 549428 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 549428 T1703 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 549430 T1703 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 549431 T1703 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 549432 T1703 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 549433 T1703 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 549434 T1703 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 549436 T1703 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 549436 T1703 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 549437 T1703 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 549437 T1703 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@78b6bc8d
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d8cfb2d6),segFN=segments_1,generation=1}
[junit4] 2> 549438 T1703 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 549438 T1703 oass.SolrIndexSearcher.<init> Opening
Searcher@2f20a5bc[collection1] main
[junit4] 2> 549438 T1703 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 549439 T1703 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 549439 T1703 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 549440 T1703
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 549440 T1703 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 549440 T1703 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 549441 T1703
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 549441 T1703 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 549441 T1703 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 549442 T1703 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 549444 T1704 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@2f20a5bc[collection1]
main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 549445 T1703 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 549446 T1707 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:60631 collection:collection1
shard:shard1
[junit4] 2> 549446 T1652 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1
[junit4] 2> 549447 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 549447 T1707 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 549452 T1707 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 549453 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 549453 T1707
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 549454 T1707 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C625 name=collection1
org.apache.solr.core.SolrCore@dc46706b url=https://127.0.0.1:60631/collection1
node=127.0.0.1:60631_ C625_STATE=coll:collection1 core:collection1
props:{state=down, base_url=https://127.0.0.1:60631,
node_name=127.0.0.1:60631_, core=collection1}
[junit4] 2> 549454 T1707 C625 P60631 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:60631/collection1/
[junit4] 2> 549455 T1707 C625 P60631 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 549455 T1707 C625 P60631 oasc.SyncStrategy.syncToMe
https://127.0.0.1:60631/collection1/ has no replicas
[junit4] 2> 549456 T1707 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:60631/collection1/ shard1
[junit4] 2> 549456 T1707 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 549462 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549462 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549463 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549471 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 549471 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549472 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549473 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549511 T1707 oasc.ZkController.register We are
https://127.0.0.1:60631/collection1/ and leader is
https://127.0.0.1:60631/collection1/
[junit4] 2> 549511 T1707 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:60631
[junit4] 2> 549512 T1707 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 549512 T1707 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 549512 T1707 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 549514 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 549520 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"https://127.0.0.1:60631";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:60631_",
[junit4] 2> "core":"collection1"}
[junit4] 2> 549627 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549627 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549628 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 549815 T1652 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 549816 T1652 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 549818 T1652 oejus.SslContextFactory.doStart Enabled Protocols
[TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 549819 T1652 oejs.AbstractConnector.doStart Started
[email protected]:57398
[junit4] 2> 549821 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 549822 T1652 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 549822 T1652 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004
[junit4] 2> 549823 T1652 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/'
[junit4] 2> 549831 T1652 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/solr.xml
[junit4] 2> 549888 T1652 oasc.CoreContainer.<init> New CoreContainer
-262881534
[junit4] 2> 549889 T1652 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/]
[junit4] 2> 549891 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 549892 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 549893 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 549894 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 549894 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 549895 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 549896 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 549897 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 549898 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 549899 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 549908 T1652 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 549914 T1652 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 549915 T1652 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 549915 T1652 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 549917 T1652 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:48537/solr
[junit4] 2> 549917 T1652 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 549919 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 549922 T1718 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@788fc9f6
name:ZooKeeperConnection Watcher:127.0.0.1:48537 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 549922 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 549925 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 549928 T1720 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@a9a6075a
name:ZooKeeperConnection Watcher:127.0.0.1:48537/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 549928 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 549934 T1652
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 550936 T1652 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57398_
[junit4] 2> 550937 T1652 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:57398_
[junit4] 2> 550941 T1652 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 550948 T1722 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 550949 T1722 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 550950 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 550950 T1722 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 550951 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"https://127.0.0.1:57398";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:57398_",
[junit4] 2> "core":"collection1"}
[junit4] 2> 550951 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 550952 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 551054 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 551054 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 551054 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 551054 T1721 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 551951 T1722 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 551951 T1722 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 551952 T1722 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 551952 T1722 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 551953 T1722 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 551953 T1722 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/'
[junit4] 2> 551954 T1722 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 551954 T1722 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 551955 T1722 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 552001 T1722 oasc.SolrConfig.<init> Using Lucene MatchVersion:
4.10.5
[junit4] 2> 552049 T1722 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 552051 T1722 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 552057 T1722 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 552549 T1722 oass.ByteField.init WARN ByteField is deprecated
and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 552550 T1722 oass.ShortField.init WARN ShortField is
deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 552551 T1722 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 552560 T1722 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 552564 T1722 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 552590 T1722 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 552596 T1722 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 552606 T1722 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 552607 T1722
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 552607 T1722
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 552608 T1722 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 552609 T1722
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 552609 T1722
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 552610 T1722 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 552610 T1722 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 552611 T1722 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/,
dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/data/
[junit4] 2> 552611 T1722 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5b434ba
[junit4] 2> 552613 T1722 oasc.CachingDirectoryFactory.get return new
directory for
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/data
[junit4] 2> 552613 T1722 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/data/index/
[junit4] 2> 552614 T1722 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 552614 T1722 oasc.CachingDirectoryFactory.get return new
directory for
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-004/collection1/data/index
[junit4] 2> 552615 T1722 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=73.939453125,
floorSegmentMB=1.447265625, forceMergeDeletesPctAllowed=5.959537686378934,
segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.28425457461540415
[junit4] 2> 552616 T1722 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4af0196b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@575b2a46),segFN=segments_1,generation=1}
[junit4] 2> 552617 T1722 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 552628 T1722 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 552628 T1722 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 552629 T1722 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 552629 T1722 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 552630 T1722 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 552630 T1722 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 552631 T1722 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 552632 T1722 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 552632 T1722 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 552633 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 552634 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 552635 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 552635 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 552636 T1722 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 552637 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 552637 T1722 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 552638 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 552638 T1722 oasc.RequestHandlers.initHandlersFromConfig WARN
Multiple requestHandler registered to the same name: /update ignoring:
org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 552639 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 552639 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 552640 T1722 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 552642 T1722 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 552644 T1722 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 552646 T1722 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 552649 T1722 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 552651 T1722 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 552655 T1722 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 552656 T1722 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 552657 T1722 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 552658 T1722 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4af0196b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@575b2a46),segFN=segments_1,generation=1}
[junit4] 2> 552658 T1722 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 552659 T1722 oass.SolrIndexSearcher.<init> Opening
Searcher@9fb1ea70[collection1] main
[junit4] 2> 552659 T1722 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 552664 T1722 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 552664 T1722 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 552666 T1722
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 552667 T1722 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 552667 T1722 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 552671 T1722
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 552672 T1722 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 552672 T1722 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 552673 T1722 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 552676 T1723 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@9fb1ea70[collection1]
main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 552681 T1722 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 552683 T1726 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:57398 collection:collection1
shard:shard2
[junit4] 2> 552684 T1652 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1
[junit4] 2> 552686 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 552706 T1726 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 552723 T1726 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 552726 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 552731 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552731 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552732 T1721 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552732 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552733 T1726
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 552735 T1726 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C626 name=collection1
org.apache.solr.core.SolrCore@3c910731 url=https://127.0.0.1:57398/collection1
node=127.0.0.1:57398_ C626_STATE=coll:collection1 core:collection1
props:{state=down, base_url=https://127.0.0.1:57398,
node_name=127.0.0.1:57398_, core=collection1}
[junit4] 2> 552736 T1726 C626 P57398 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:57398/collection1/
[junit4] 2> 552738 T1726 C626 P57398 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 552738 T1726 C626 P57398 oasc.SyncStrategy.syncToMe
https://127.0.0.1:57398/collection1/ has no replicas
[junit4] 2> 552739 T1726 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:57398/collection1/ shard2
[junit4] 2> 552739 T1726 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 552745 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 552850 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552850 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552850 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552850 T1721 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 552898 T1726 oasc.ZkController.register We are
https://127.0.0.1:57398/collection1/ and leader is
https://127.0.0.1:57398/collection1/
[junit4] 2> 552898 T1726 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:57398
[junit4] 2> 552898 T1726 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 552899 T1726 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 552899 T1726 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 552906 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 552908 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"https://127.0.0.1:57398";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":"shard2",
[junit4] 2> "node_name":"127.0.0.1:57398_",
[junit4] 2> "core":"collection1"}
[junit4] 2> 553012 T1688 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 553013 T1702 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 553013 T1721 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 553014 T1681 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 553194 T1652 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 553195 T1652 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 553199 T1652 oejus.SslContextFactory.doStart Enabled Protocols
[TLSv1] of [SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 553201 T1652 oejs.AbstractConnector.doStart Started
[email protected]:58594
[junit4] 2> 553204 T1652 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 553204 T1652 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 553205 T1652 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-005
[junit4] 2> 553206 T1652 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-005/'
[junit4] 2> 553219 T1652 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-005/solr.xml
[junit4] 2> 553275 T1652 oasc.CoreContainer.<init> New CoreContainer
1671194042
[junit4] 2> 553276 T1652 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=./solr.cloud.MultiThreadedOCPTest-2A8AFE9F121C5450-001/tempDir-005/]
[junit4] 2> 553278 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 553279 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 553280 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 553281 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 553282 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 553283 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 553283 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 553284 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 553285 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 553286 T1652 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 553299 T1652 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 553301 T1652 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 553302 T1652 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 553303 T1652 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 553303 T1652 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:48537/solr
[junit4] 2> 553304 T1652 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 553305 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 553308 T1737 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@cecaefd6
name:ZooKeeperConnection Watcher:127.0.0.1:48537 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 553308 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 553311 T1652 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 553320 T1739 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@15061c5 name:ZooKeeperConnection
Watcher:127.0.0.1:48537/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 553321 T1652 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 553325 T1652
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 554328 T1652 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58594_
[junit4] 2> 554329 T1652 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:58594_
[junit4] 2> 554334 T1652 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 554346 T1741 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 554346 T1741 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 554349 T1741 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 554349 T1681 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 554351 T1677 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"https://127.0.0.1:58594";,
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:58594_",
[junit4] 2> "core":"collection1"}
[ju
[...truncated too long message...]
t4] 2>
[junit4] 2> 801829 T1839
oasc.OverseerCollectionProcessor$Runner.resetTaskWithException WARN Resetting
task: /overseer/collection-queue-work/qn-0000000010, requestid: 2000,
collectionName: collection1
[junit4] 2> 801829 T1975 oasc.SolrException.log ERROR
:org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode
= Session expired for /overseer/collection-map-failure/mn-2000
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:274)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:271)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:74)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:271)
[junit4] 2> at
org.apache.solr.cloud.DistributedMap.createData(DistributedMap.java:116)
[junit4] 2> at
org.apache.solr.cloud.DistributedMap.put(DistributedMap.java:129)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2685)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.lang.Thread.run(Thread.java:853)
[junit4] 2>
[junit4] 2> 801830 T1839 oasc.SolrException.log ERROR
:org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode
= Session expired for /overseer/collection-map-running/mn-2000
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:190)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:187)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:74)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:187)
[junit4] 2> at
org.apache.solr.cloud.DistributedMap.remove(DistributedMap.java:171)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.resetTaskWithException(OverseerCollectionProcessor.java:2745)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2713)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.lang.Thread.run(Thread.java:853)
[junit4] 2>
[junit4] 2> 801830 T1975
oasc.OverseerCollectionProcessor$Runner.resetTaskWithException WARN Resetting
task: /overseer/collection-queue-work/qn-0000000010, requestid: 2000,
collectionName: collection1
[junit4] 2> 801830 T1975 oasc.SolrException.log ERROR
:org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode
= Session expired for /overseer/collection-map-running/mn-2000
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:190)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:187)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:74)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:187)
[junit4] 2> at
org.apache.solr.cloud.DistributedMap.remove(DistributedMap.java:171)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.resetTaskWithException(OverseerCollectionProcessor.java:2745)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2713)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.lang.Thread.run(Thread.java:853)
[junit4] 2>
[junit4] 2> 258491 T1651 ccr.ThreadLeakControl.tryToInterruptAll All
leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Lucene40,
sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=tr_TR,
timezone=Australia/Perth
[junit4] 2> NOTE: Linux 3.13.0-46-generic x86/IBM Corporation 1.7.0
(32-bit)/cpus=8,threads=1,free=147091856,total=242286592
[junit4] 2> NOTE: All tests run in this JVM:
[TestLMDirichletSimilarityFactory, ZkSolrClientTest, DeleteInactiveReplicaTest,
RollingRestartTest, TestXIncludeConfig, SolrInfoMBeanTest,
LeaderElectionIntegrationTest, TestFastWriter, CircularListTest,
SystemInfoHandlerTest, TestCloudManagedSchemaConcurrent,
AnalysisAfterCoreReloadTest, TestSchemaNameResource, EnumFieldTest,
TestLFUCache, TestSolrQueryParserResource, CollectionsAPIDistributedZkTest,
ConvertedLegacyTest, AtomicUpdatesTest, MultiThreadedOCPTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=MultiThreadedOCPTest -Dtests.seed=2A8AFE9F121C5450
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=tr_TR
-Dtests.timezone=Australia/Perth -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | MultiThreadedOCPTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.MultiThreadedOCPTest:
[junit4] > 1) Thread[id=1839, name=OverseerThreadFactory-812-thread-5,
state=TIMED_WAITING, group=Overseer collection creation process.]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at java.lang.Thread.sleep(Thread.java:977)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor.waitForCoreNodeName(OverseerCollectionProcessor.java:1662)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor.splitShard(OverseerCollectionProcessor.java:1544)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:564)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2677)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] > at java.lang.Thread.run(Thread.java:853)
[junit4] > 2) Thread[id=1975, name=OverseerThreadFactory-998-thread-1,
state=TIMED_WAITING, group=Overseer collection creation process.]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at java.lang.Thread.sleep(Thread.java:977)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor.waitForCoreNodeName(OverseerCollectionProcessor.java:1662)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor.splitShard(OverseerCollectionProcessor.java:1418)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:564)
[junit4] > at
org.apache.solr.cloud.OverseerCollectionProcessor$Runner.run(OverseerCollectionProcessor.java:2677)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1176)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] > at java.lang.Thread.run(Thread.java:853)
[junit4] > at
__randomizedtesting.SeedInfo.seed([2A8AFE9F121C5450]:0)
[junit4] Completed on J1 in 258.64s, 1 test, 1 error <<< FAILURES!
[...truncated 1178 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/build.xml:474: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/build.xml:467: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/build.xml:45: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/extra-targets.xml:37: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build.xml:189: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/common-build.xml:494:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/lucene/common-build.xml:1351:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/lucene/common-build.xml:966:
There were test failures: 424 suites, 1762 tests, 1 suite-level error, 47
ignored (18 assumptions)
Total time: 121 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/ibm-j9-jdk7
-Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
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]
