Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/1898/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.DistribCursorPagingTest
Error Message:
Some resources were not closed, shutdown, or released.
Stack Trace:
java.lang.AssertionError: Some resources were not closed, shutdown, or released.
at __randomizedtesting.SeedInfo.seed([EA0806878DEC3B70]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:189)
at sun.reflect.GeneratedMethodAccessor80.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10000 lines...]
[junit4] Suite: org.apache.solr.cloud.DistribCursorPagingTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/init-core-data-001
[junit4] 2> 4395771 T16787 oas.SolrTestCaseJ4.buildSSLConfig Randomized
ssl (true) and clientAuth (false)
[junit4] 2> 4395771 T16787
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 4395777 T16787 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 4395778 T16787 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4395780 T16788 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 4395881 T16787 oasc.ZkTestServer.run start zk server on
port:60121
[junit4] 2> 4395881 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4395885 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4395898 T16795 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23fefb2 name:ZooKeeperConnection
Watcher:127.0.0.1:60121 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 4395900 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4395900 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4395902 T16787 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 4395911 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4395914 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4395917 T16798 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@41fdf2d7
name:ZooKeeperConnection Watcher:127.0.0.1:60121/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4395918 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4395918 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4395918 T16787 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 4395923 T16787 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 4395930 T16787 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 4395938 T16787 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 4395944 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-deeppaging.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4395944 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 4395952 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-sorts.xml
to /configs/conf1/schema.xml
[junit4] 2> 4395952 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 4395956 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4395957 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4395962 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4395963 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 4395971 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4395972 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 4395982 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4395983 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 4395988 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4395989 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 4395998 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4395999 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 4396005 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4396005 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4396011 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4396012 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 4396016 T16787 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4396017 T16787 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 4396024 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4396026 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4396029 T16801 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2aba4f16
name:ZooKeeperConnection Watcher:127.0.0.1:60121/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4396029 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4396030 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4398094 T16787 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4398106 T16787 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4398109 T16787 oejs.AbstractConnector.doStart Started
[email protected]:60125
[junit4] 2> 4398113 T16787 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 4398113 T16787 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 4398114 T16787 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-002
[junit4] 2> 4398115 T16787 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-002/'
[junit4] 2> 4398162 T16787 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-002/solr.xml
[junit4] 2> 4398207 T16787 oasc.CoreContainer.<init> New CoreContainer
783822754
[junit4] 2> 4398207 T16787 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-002/]
[junit4] 2> 4398209 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4398210 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4398210 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4398211 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4398211 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4398214 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4398214 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4398215 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4398215 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4398216 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4398217 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4398222 T16787 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4398226 T16787 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4398226 T16787 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4398227 T16787 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4398227 T16787 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:60121/solr
[junit4] 2> 4398227 T16787 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4398228 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4398234 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4398241 T16813 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5ce0230 name:ZooKeeperConnection
Watcher:127.0.0.1:60121 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 4398243 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4398243 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4398248 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4398250 T16816 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@14d5ef76
name:ZooKeeperConnection Watcher:127.0.0.1:60121/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4398251 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4398255 T16787 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 4398265 T16787 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 4398273 T16787 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 4398280 T16787 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 4398289 T16787 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 4398298 T16787 oascc.SolrZkClient.makePath makePath:
/live_nodes
[junit4] 2> 4398303 T16787 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60125_
[junit4] 2> 4398307 T16787 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:60125_
[junit4] 2> 4398315 T16787 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 4398320 T16787 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 4398325 T16787 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4398330 T16787 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:60125_
[junit4] 2> 4398330 T16787 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 4398336 T16787 oasc.Overseer.start Overseer
(id=93174511416115204-127.0.0.1:60125_-n_0000000000) starting
[junit4] 2> 4398343 T16787 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 4398369 T16787 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 4398373 T16818 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 4398377 T16817 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 4398380 T16787 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 4398391 T16787 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 4398397 T16787
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4398418 T16820 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 4398419 T16820 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4398421 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4398421 T16820 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4398435 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60125";,
[junit4] 2> "node_name":"127.0.0.1:60125_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4398440 T16817 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60125";,
[junit4] 2> "node_name":"127.0.0.1:60125_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4398440 T16817 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 4398441 T16817 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 4398445 T16816 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> 4399423 T16820 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4399424 T16820 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 4399425 T16820 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4399426 T16820 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 4399430 T16820 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 4399430 T16820 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-002/collection1/'
[junit4] 2> 4399432 T16820 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 4399433 T16820 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 4399434 T16820 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 4399472 T16820 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4399479 T16820 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4399493 T16820 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4399497 T16820 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 4399504 T16820 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4399509 T16820 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4399515 T16820 oass.IndexSchema.readSchema [collection1]
Schema name=sortable
[junit4] 2> 4399683 T16820 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4399683 T16820 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 4399684 T16820 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4399685 T16820 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-002/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/control/data/
[junit4] 2> 4399685 T16820 oasc.SolrCore.<init> JMX monitoring not
detected for core: collection1
[junit4] 2> 4399685 T16820 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/control/data
[junit4] 2> 4399685 T16820 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/control/data/index/
[junit4] 2> 4399686 T16820 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 4399687 T16820 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/control/data/index
[junit4] 2> 4399687 T16820 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 4399688 T16820 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@26611695
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7220b7e4),segFN=segments_1,generation=1}
[junit4] 2> 4399688 T16820 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4399690 T16820 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4399692 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4399693 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4399693 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4399693 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4399694 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 4399694 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 4399695 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 4399695 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 4399696 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 4399697 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 4399697 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 4399697 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 4399697 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 4399698 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 4399698 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 4399699 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 4399699 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 4399700 T16820 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 4399709 T16820 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4399710 T16820 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4399712 T16820 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4399714 T16820 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4399714 T16820 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4399720 T16820 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4399720 T16820 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4399721 T16820 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=36,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 4399722 T16820 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@26611695
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7220b7e4),segFN=segments_1,generation=1}
[junit4] 2> 4399723 T16820 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4399723 T16820 oass.SolrIndexSearcher.<init> Opening
Searcher@2f2d40b[collection1] main
[junit4] 2> 4399724 T16820 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 4399733 T16820 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 4399734 T16820 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4399735 T16820
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4399735 T16820 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4399735 T16820 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4399736 T16820
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4399737 T16820 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4399737 T16820 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4399737 T16820 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4399738 T16820 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4399738 T16821 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@2f2d40b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4399740 T16824 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:60125 collection:control_collection
shard:shard1
[junit4] 2> 4399741 T16787 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4399742 T16787 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4399746 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4399748 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4399752 T16827 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1aa57ce name:ZooKeeperConnection
Watcher:127.0.0.1:60121/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 4399752 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4399753 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4399755 T16787
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4399759 T16824 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 4399762 T16787 oasc.ChaosMonkey.monkeyLog monkey: init -
expire sessions:false cause connection loss:false
[junit4] 2> 4399762 T16787 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 4399763 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4399769 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4399771 T16830 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7878c272
name:ZooKeeperConnection Watcher:127.0.0.1:60121/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4399772 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4399773 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4399789 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4399803 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 1
[junit4] 2> 4399804 T16817 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 4399805 T16824
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 4399820 T16824
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4399820 T16824
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C16069 name=collection1
org.apache.solr.core.SolrCore@76efca17 url=https://127.0.0.1:60125/collection1
node=127.0.0.1:60125_ C16069_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=https://127.0.0.1:60125,
node_name=127.0.0.1:60125_, state=down}
[junit4] 2> 4399820 T16824 C16069 P60125 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:60125/collection1/
[junit4] 2> 4399821 T16824 C16069 P60125 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4399821 T16824 C16069 P60125 oasc.SyncStrategy.syncToMe
https://127.0.0.1:60125/collection1/ has no replicas
[junit4] 2> 4399821 T16824
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
https://127.0.0.1:60125/collection1/ shard1
[junit4] 2> 4399821 T16824 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 4399830 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 4399834 T16817 oasco.ZkStateWriter.writePendingUpdates going
to create_collection /collections/collection1/state.json
[junit4] 2> 4399840 T16827 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> 4399840 T16816 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> 4399853 T16831 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4399859 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:60125";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 4399865 T16831 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4399868 T16827 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> 4399907 T16824 oasc.ZkController.register We are
https://127.0.0.1:60125/collection1/ and leader is
https://127.0.0.1:60125/collection1/
[junit4] 2> 4399908 T16824 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:60125
[junit4] 2> 4399908 T16824 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4399908 T16824 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 4399909 T16824 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4399915 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4399923 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60125";,
[junit4] 2> "node_name":"127.0.0.1:60125_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 4399925 T16817 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60125";,
[junit4] 2> "node_name":"127.0.0.1:60125_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4400035 T16816 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> 4400035 T16827 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> 4401824 T16787 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 4401825 T16787 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4401834 T16787 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4401836 T16787 oejs.AbstractConnector.doStart Started
[email protected]:60130
[junit4] 2> 4401839 T16787 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 4401839 T16787 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 4401840 T16787 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-003
[junit4] 2> 4401841 T16787 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-003/'
[junit4] 2> 4401889 T16787 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-003/solr.xml
[junit4] 2> 4401902 T16787 oasc.CoreContainer.<init> New CoreContainer
893181655
[junit4] 2> 4401902 T16787 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-003/]
[junit4] 2> 4401903 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4401903 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4401903 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4401904 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4401904 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4401904 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4401905 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4401905 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4401905 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4401906 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4401907 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4401910 T16787 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4401912 T16787 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4401912 T16787 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4401913 T16787 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4401913 T16787 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:60121/solr
[junit4] 2> 4401913 T16787 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4401914 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4401916 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4401921 T16843 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@14e3be93
name:ZooKeeperConnection Watcher:127.0.0.1:60121 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4401921 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4401921 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4401929 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4401930 T16846 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@74cf2fe2
name:ZooKeeperConnection Watcher:127.0.0.1:60121/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4401931 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4401952 T16787
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4402972 T16787 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60130_
[junit4] 2> 4402977 T16787 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:60130_
[junit4] 2> 4402993 T16787 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4403013 T16847 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 4403014 T16847 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4403019 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4403021 T16847 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 4403022 T16847 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 4403022 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60130";,
[junit4] 2> "node_name":"127.0.0.1:60130_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 4403024 T16847 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 4403025 T16817 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60130";,
[junit4] 2> "node_name":"127.0.0.1:60130_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4403025 T16817 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 4403026 T16817 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 4403028 T16847 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 4403028 T16847 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4403030 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 4403031 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4403034 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 4404030 T16847 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4404030 T16847 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 4404037 T16847 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4404038 T16847 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4404044 T16847 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4404044 T16847 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-003/collection1/'
[junit4] 2> 4404047 T16847 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 4404048 T16847 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 4404048 T16847 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 4404083 T16847 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4404088 T16847 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4404104 T16847 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4404111 T16847 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 4404119 T16847 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4404127 T16847 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4404131 T16847 oass.IndexSchema.readSchema [collection1]
Schema name=sortable
[junit4] 2> 4404279 T16847 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4404283 T16847 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 4404284 T16847 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4404285 T16847 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-003/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty1/
[junit4] 2> 4404285 T16847 oasc.SolrCore.<init> JMX monitoring not
detected for core: collection1
[junit4] 2> 4404286 T16847 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty1
[junit4] 2> 4404287 T16847 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty1/index/
[junit4] 2> 4404287 T16847 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 4404289 T16847 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty1/index
[junit4] 2> 4404289 T16847 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 4404290 T16847 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@48b5b203
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44e7140a),segFN=segments_1,generation=1}
[junit4] 2> 4404290 T16847 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4404293 T16847 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4404294 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4404294 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4404294 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4404295 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4404295 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 4404297 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 4404297 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 4404298 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 4404298 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 4404301 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 4404302 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 4404302 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 4404303 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 4404303 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 4404304 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 4404304 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 4404304 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 4404304 T16847 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 4404311 T16847 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4404313 T16847 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4404316 T16847 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4404316 T16847 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4404317 T16847 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4404319 T16847 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4404319 T16847 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4404320 T16847 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=36,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 4404320 T16847 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@48b5b203
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@44e7140a),segFN=segments_1,generation=1}
[junit4] 2> 4404320 T16847 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4404321 T16847 oass.SolrIndexSearcher.<init> Opening
Searcher@71768a07[collection1] main
[junit4] 2> 4404321 T16847 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4404323 T16847 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4404323 T16847 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4404325 T16847
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4404325 T16847 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4404325 T16847 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4404326 T16847
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4404327 T16847 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4404327 T16847 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4404327 T16847 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4404327 T16847 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4404327 T16848 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@71768a07[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4404331 T16851 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:60130 collection:collection1
shard:shard2
[junit4] 2> 4404331 T16787 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4404332 T16787 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4404333 T16851 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 4404363 T16851
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard2
[junit4] 2> 4404372 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4404396 T16851
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4404396 T16851
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C16070 name=collection1
org.apache.solr.core.SolrCore@57c4b0ab url=https://127.0.0.1:60130/collection1
node=127.0.0.1:60130_ C16070_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=https://127.0.0.1:60130,
node_name=127.0.0.1:60130_, state=down}
[junit4] 2> 4404397 T16851 C16070 P60130 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:60130/collection1/
[junit4] 2> 4404397 T16851 C16070 P60130 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4404397 T16851 C16070 P60130 oasc.SyncStrategy.syncToMe
https://127.0.0.1:60130/collection1/ has no replicas
[junit4] 2> 4404397 T16851
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
https://127.0.0.1:60130/collection1/ shard2
[junit4] 2> 4404398 T16851 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 4404403 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 4404406 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 4404416 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4404418 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 4404440 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4404443 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:60130";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 4404447 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 2
[junit4] 2> 4404451 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4404455 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 4404499 T16851 oasc.ZkController.register We are
https://127.0.0.1:60130/collection1/ and leader is
https://127.0.0.1:60130/collection1/
[junit4] 2> 4404499 T16851 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:60130
[junit4] 2> 4404499 T16851 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4404500 T16851 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 4404500 T16851 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4404515 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4404518 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60130";,
[junit4] 2> "node_name":"127.0.0.1:60130_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 4404520 T16817 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60130";,
[junit4] 2> "node_name":"127.0.0.1:60130_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4404524 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 3
[junit4] 2> 4404525 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4404540 T16851 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 4404544 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 4406644 T16787 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 4406645 T16787 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4406656 T16787 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3,
TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 4406658 T16787 oejs.AbstractConnector.doStart Started
[email protected]:60133
[junit4] 2> 4406661 T16787 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 4406662 T16787 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 4406663 T16787 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-004
[junit4] 2> 4406663 T16787 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-004/'
[junit4] 2> 4406714 T16787 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-004/solr.xml
[junit4] 2> 4406728 T16787 oasc.CoreContainer.<init> New CoreContainer
885618957
[junit4] 2> 4406729 T16787 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-004/]
[junit4] 2> 4406730 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 4406730 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 4406731 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 4406731 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 4406731 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 4406731 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 4406732 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 4406732 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 4406732 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 4406733 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 4406733 T16787 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 4406736 T16787 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4406739 T16787 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4406739 T16787 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4406739 T16787 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4406740 T16787 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:60121/solr
[junit4] 2> 4406741 T16787 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 4406741 T16787
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 4406742 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4406746 T16863 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@13d6941e
name:ZooKeeperConnection Watcher:127.0.0.1:60121 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4406746 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4406747 T16787 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 4406751 T16787 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 4406755 T16866 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@73c668ea
name:ZooKeeperConnection Watcher:127.0.0.1:60121/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4406755 T16787 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 4406781 T16787
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 4407801 T16787 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60133_
[junit4] 2> 4407803 T16787 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:60133_
[junit4] 2> 4407814 T16787 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4407831 T16867 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 4407831 T16867 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4407835 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4407838 T16867 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 4407838 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60133";,
[junit4] 2> "node_name":"127.0.0.1:60133_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 4407838 T16867 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 4407841 T16867 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 4407841 T16817 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60133";,
[junit4] 2> "node_name":"127.0.0.1:60133_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4407842 T16817 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 4407842 T16817 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 4407844 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 4
[junit4] 2> 4407844 T16867 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 4407845 T16867 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 4407846 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4407847 T16866 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4407848 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 4407849 T16866 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 4408847 T16867 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 4408848 T16867 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 4408851 T16867 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 4408852 T16867 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4408859 T16867 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4408861 T16867 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-004/collection1/'
[junit4] 2> 4408868 T16867 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 4408869 T16867 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 4408871 T16867 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%20EA0806878DEC3B70-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 4408915 T16867 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 4408921 T16867 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 4408938 T16867 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 4408942 T16867 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 4408949 T16867 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 4408956 T16867 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 4408966 T16867 oass.IndexSchema.readSchema [collection1]
Schema name=sortable
[junit4] 2> 4409102 T16867 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4409103 T16867 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 4409104 T16867 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4409105 T16867 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-004/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty2/
[junit4] 2> 4409105 T16867 oasc.SolrCore.<init> JMX monitoring not
detected for core: collection1
[junit4] 2> 4409106 T16867 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty2
[junit4] 2> 4409106 T16867 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty2/index/
[junit4] 2> 4409106 T16867 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 4409107 T16867 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
EA0806878DEC3B70-001/tempDir-001/jetty2/index
[junit4] 2> 4409107 T16867 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 4409109 T16867 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@6928023c
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70c3f4bb),segFN=segments_1,generation=1}
[junit4] 2> 4409110 T16867 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4409113 T16867 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4409113 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4409114 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4409114 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4409114 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 4409115 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 4409115 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 4409115 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 4409116 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 4409117 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 4409118 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 4409119 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 4409119 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 4409119 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 4409120 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 4409121 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 4409122 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 4409122 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 4409123 T16867 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 4409130 T16867 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4409131 T16867 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4409132 T16867 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4409134 T16867 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4409135 T16867 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 4409137 T16867 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 4409137 T16867 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 4409138 T16867 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=36,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 4409138 T16867 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@6928023c
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70c3f4bb),segFN=segments_1,generation=1}
[junit4] 2> 4409138 T16867 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 4409139 T16867 oass.SolrIndexSearcher.<init> Opening
Searcher@7c59a024[collection1] main
[junit4] 2> 4409139 T16867 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 4409143 T16867 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 4409143 T16867 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 4409145 T16867
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4409145 T16867 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 4409146 T16867 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4409148 T16867
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4409148 T16867 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4409149 T16867 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 4409149 T16867 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 4409149 T16867 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 4409149 T16868 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@7c59a024[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4409152 T16871 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:60133 collection:collection1
shard:shard1
[junit4] 2> 4409153 T16787 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4409155 T16787 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 4409156 T16871 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 4409170 T16871
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 4409174 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4409178 T16871
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 4409178 T16871
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C16071 name=collection1
org.apache.solr.core.SolrCore@6351e13f url=https://127.0.0.1:60133/collection1
node=127.0.0.1:60133_ C16071_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=https://127.0.0.1:60133,
node_name=127.0.0.1:60133_, state=down}
[junit4] 2> 4409179 T16871 C16071 P60133 oasc.SyncStrategy.sync Sync
replicas to https://127.0.0.1:60133/collection1/
[junit4] 2> 4409180 T16871 C16071 P60133 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 4409180 T16871 C16071 P60133 oasc.SyncStrategy.syncToMe
https://127.0.0.1:60133/collection1/ has no replicas
[junit4] 2> 4409180 T16871
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
https://127.0.0.1:60133/collection1/ shard1
[junit4] 2> 4409181 T16871 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 4409182 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 4409185 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 5
[junit4] 2> 4409187 T16866 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4409187 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4409191 T16866 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 4409191 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 6
[junit4] 2> 4409202 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:60133";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 4409205 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 6
[junit4] 2> 4409206 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4409207 T16866 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4409208 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 7
[junit4] 2> 4409210 T16866 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 7
[junit4] 2> 4409219 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4409253 T16871 oasc.ZkController.register We are
https://127.0.0.1:60133/collection1/ and leader is
https://127.0.0.1:60133/collection1/
[junit4] 2> 4409253 T16871 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:60133
[junit4] 2> 4409253 T16871 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 4409253 T16871 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 4409254 T16871 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 4409266 T16816 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4409268 T16817 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60133";,
[junit4] 2> "node_name":"127.0.0.1:60133_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 4409273 T16817 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60133";,
[junit4] 2> "node_name":"127.0.0.1:60133_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 4409280 T16817 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 7
[junit4] 2> 4409283 T16846 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4409285 T16846 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 8
[junit4] 2> 4409286 T16866 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 4409292 T16871 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 8
[junit4] 2> 4409293 T16866 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 8
[junit4] 2> 4411140 T16787 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 4411141 T16787 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4411151 T16787 oejus.SslContextFactory.doStart Enabled
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, T
[...truncated too long message...]
, SchemaVersionSpecificBehaviorTest, AliasIntegrationTest,
DistributedQueryElevationComponentTest, TestLFUCache,
TestDistributedMissingSort, HdfsWriteToMultipleCollectionsTest,
TestStressRecovery, TestFieldTypeCollectionResource, DocValuesMissingTest,
FieldAnalysisRequestHandlerTest, CoreAdminRequestStatusTest, TestCustomSort,
OverseerTest, SolrRequestParserTest, DistributedFacetPivotSmallTest,
TestDistributedGrouping, ResponseHeaderTest,
DistribDocExpirationUpdateProcessorTest, TestManagedResourceStorage,
AnalyticsMergeStrategyTest, TestSystemIdResolver, ExternalFileFieldSortTest,
DistributedQueryComponentCustomSortTest, TestCursorMarkWithoutUniqueKey,
TestExactSharedStatsCache, TestIndexingPerformance, QueryParsingTest,
TestBadConfig, TestCloudSchemaless, UnloadDistributedZkTest, QueryEqualityTest,
LukeRequestHandlerTest, QueryElevationComponentTest, CoreAdminHandlerTest,
IndexSchemaRuntimeFieldTest, QueryResultKeyTest, ExternalCollectionsTest,
XmlUpdateRequestHandlerTest, MultiThreadedOCPTest, TestImplicitCoreProperties,
JsonLoaderTest, TestNonDefinedSimilarityFactory, TestCoreContainer,
DistributedQueryComponentOptimizationTest, BasicDistributedZkTest,
TestZkChroot, LoggingHandlerTest, TestFastLRUCache, TestTolerantSearch,
TestComponentsName, TestDefaultSimilarityFactory, CacheHeaderTest,
ShardRoutingCustomTest, TestDynamicFieldCollectionResource, TestCoreDiscovery,
TestExpandComponent, DeleteShardTest, DisMaxRequestHandlerTest, TestLazyCores,
TestRangeQuery, RollingRestartTest, TestPostingsSolrHighlighter,
TestSolrConfigHandler, RegexBoostProcessorTest, LeaderElectionIntegrationTest,
AutoCommitTest, TestSearcherReuse, TestQuerySenderListener,
FileBasedSpellCheckerTest, TestExtendedDismaxParser, TestRTGBase,
MergeStrategyTest, HttpPartitionTest, TestFieldSortValues, DocValuesMultiTest,
OverriddenZkACLAndCredentialsProvidersTest, CoreAdminCreateDiscoverTest,
TestSweetSpotSimilarityFactory, TestSolrDeletionPolicy1,
StandardRequestHandlerTest, TestConfigReload, TestCollapseQParserPlugin,
TestPHPSerializedResponseWriter, TestManagedSchemaFieldResource,
OverseerCollectionProcessorTest, TestDistribDocBasedVersion,
ChangedSchemaMergeTest, AlternateDirectoryTest, MinimalSchemaTest,
ParsingFieldUpdateProcessorsTest, TestJoin, TestDynamicLoading,
TestReloadAndDeleteDocs, StatelessScriptUpdateProcessorFactoryTest,
DistributedTermsComponentTest, ZkSolrClientTest, TestSuggestSpellingConverter,
TestLMJelinekMercerSimilarityFactory, ZkStateWriterTest, TestSolr4Spatial,
NoCacheHeaderTest, DirectSolrConnectionTest, TestLRUCache, PluginInfoTest,
BJQParserTest, TestMissingGroups, OverseerRolesTest, TestSolrXmlPersistor,
TestHashPartitioner, TestCollationField, EnumFieldTest, SimpleMLTQParserTest,
FieldMutatingUpdateProcessorTest, FacetPivotSmallTest, SortByFunctionTest,
DirectSolrSpellCheckerTest, TimeZoneUtilsTest, CustomCollectionTest,
RequestHandlersTest, DistributedExpandComponentTest,
CollectionsAPIDistributedZkTest, VMParamsZkACLAndCredentialsProvidersTest,
TestHighFrequencyDictionaryFactory, CursorPagingTest,
FullSolrCloudDistribCmdsTest, BasicZkTest, TestSurroundQueryParser,
ConnectionManagerTest, HdfsUnloadDistributedZkTest, TestFaceting,
TestSolrConfigHandlerConcurrent, InfoHandlerTest, SolrTestCaseJ4Test,
DocValuesTest, HdfsCollectionsAPIDistributedZkTest, TestRandomDVFaceting,
TestRestManager, TestSchemaSimilarityResource, HdfsLockFactoryTest,
TestFreeTextSuggestions, TestFileDictionaryLookup, TestBM25SimilarityFactory,
TestAddFieldRealTimeGet, HardAutoCommitTest, TestCloudManagedSchema,
TestPhraseSuggestions, DistributedQueueTest, NotRequiredUniqueKeyTest,
TestLRUStatsCache, SoftAutoCommitTest, HdfsRecoveryZkTest,
HdfsChaosMonkeySafeLeaderTest, SSLMigrationTest, TestPseudoReturnFields,
TestOmitPositions, TestFieldCollectionResource,
TestLMDirichletSimilarityFactory, TestArbitraryIndexDir, TestSearchPerf,
FileUtilsTest, SolrCoreTest, DocumentBuilderTest, ClusterStateUpdateTest,
TestConfigOverlay, ShowFileRequestHandlerTest, TestCloudPivotFacet,
HdfsBasicDistributedZkTest, TestConfigSets, TestFuzzyAnalyzedSuggestions,
CurrencyFieldOpenExchangeTest, NumericFieldsTest, TestAtomicUpdateErrorCases,
SuggesterTest, OverseerStatusTest, BasicDistributedZk2Test, TestSchemaManager,
TestDistributedSearch, BadCopyFieldTest, SearchHandlerTest,
DirectUpdateHandlerOptimizeTest, BadIndexSchemaTest,
TestAnalyzeInfixSuggestions, SpellingQueryConverterTest,
SolrCmdDistributorTest, OpenExchangeRatesOrgProviderTest,
SharedFSAutoReplicaFailoverTest, TestStressLucene, ReplicationFactorTest,
AtomicUpdatesTest, HdfsDirectoryFactoryTest, TestSolrXmlPersistence,
UniqFieldsUpdateProcessorFactoryTest, TestAnalyzedSuggestions,
TestFieldTypeResource, IndexSchemaTest, EchoParamsTest,
TestManagedSynonymFilterFactory, MBeansHandlerTest, TestManagedSchema,
DateRangeFieldTest, CursorMarkTest, TestStressReorder,
PreAnalyzedUpdateProcessorTest, SpatialFilterTest,
TestCloudManagedSchemaConcurrent, BasicFunctionalityTest,
URLClassifyProcessorTest, TestCloudInspectUtil, TestSort, AnalyticsQueryTest,
PolyFieldTest, TestFastOutputStream, MoreLikeThisHandlerTest,
ChaosMonkeySafeLeaderTest, SyncSliceTest, OpenCloseCoreStressTest,
ShardRoutingTest, RecoveryZkTest, TestReplicationHandler, TestRandomFaceting,
LeaderElectionTest, ZkCLITest, TestRecovery,
DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest,
ZkControllerTest, TestRealTimeGet, TestReload, TestStressVersions,
StatsComponentTest, SpellCheckComponentTest, PeerSyncTest, ConvertedLegacyTest,
TestFiltering, TestFunctionQuery, TestIndexSearcher, HighlighterTest,
CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest,
AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest,
SpellCheckCollatorTest, SuggesterFSTTest, TestFoldingMultitermQuery,
SuggesterTSTTest, TestTrie, SuggesterWFSTTest, TestCSVLoader,
SolrCoreCheckLockOnStartupTest, TestUpdate, TestWordDelimiterFilterFactory,
DefaultValueUpdateProcessorTest, TestRemoteStreaming, DistanceFunctionTest,
SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest,
IndexBasedSpellCheckerTest, TestQueryUtils, TestWriterPerf,
TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest,
TestQueryTypes, PrimitiveFieldTypeTest, TermsComponentTest,
TestValueSourceCache, PathHierarchyTokenizerFactoryTest,
TermVectorComponentTest, RequiredFieldsTest, TestSolrQueryParser,
FastVectorHighlighterTest, SolrPluginUtilsTest, TestJmxIntegration,
ReturnFieldsTest, UpdateRequestProcessorFactoryTest, JSONWriterTest,
TestCSVResponseWriter, BinaryUpdateRequestHandlerTest,
TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest,
CoreContainerCoreInitFailuresTest, TestBinaryResponseWriter,
HighlighterConfigTest, SOLR749Test, UpdateParamsTest, TestSolrIndexConfig,
TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest,
SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig,
TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestDocSet,
TestBinaryField, TestElisionMultitermQuery, TestConfig, OutputWriterTest,
TestSolrCoreProperties, TestLuceneMatchVersion, SpellPossibilityIteratorTest,
TestCharFilters, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig,
TestDFRSimilarityFactory, TestPerFieldSimilarity, TestIBSimilarityFactory,
TestFastWriter, LegacyHTMLStripCharFilterTest, TestJmxMonitoredMap,
ResourceLoaderTest, ScriptEngineTest, DateMathParserTest, PreAnalyzedFieldTest,
PrimUtilsTest, DateFieldTest, DOMUtilTest, RAMDirectoryFactoryTest,
ClusterStateTest, TestSolrJ, TestUtils, TestDocumentBuilder, ZkNodePropsTest,
SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest,
DistributedIntervalFacetingTest, AssignTest, AsyncMigrateRouteKeyTest,
CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest,
DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest,
DeleteReplicaTest, DistribCursorPagingTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=DistribCursorPagingTest -Dtests.seed=EA0806878DEC3B70
-Dtests.slow=true -Dtests.locale=en_US -Dtests.timezone=ECT
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s | DistribCursorPagingTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: Some resources were
not closed, shutdown, or released.
[junit4] > at
__randomizedtesting.SeedInfo.seed([EA0806878DEC3B70]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:189)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed in 74.49s, 1 test, 1 failure <<< FAILURES!
[...truncated 299 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:529: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:477: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:190: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:508: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:970:
There were test failures: 463 suites, 1873 tests, 1 suite-level error, 52
ignored (20 assumptions)
Total time: 135 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0
-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
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]
