Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1946/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionTest.testDistribSearch
Error Message:
-2
Stack Trace:
java.lang.ArrayIndexOutOfBoundsException: -2
at
__randomizedtesting.SeedInfo.seed([AD6E885588021F21:2C88064DFF5D7F1D]:0)
at java.util.ArrayList.elementData(ArrayList.java:418)
at java.util.ArrayList.get(ArrayList.java:431)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.getDocCollection(CloudSolrClient.java:1091)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:754)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:729)
at
org.apache.solr.cloud.HttpPartitionTest.sendDoc(HttpPartitionTest.java:481)
at
org.apache.solr.cloud.HttpPartitionTest.testRf3(HttpPartitionTest.java:271)
at
org.apache.solr.cloud.HttpPartitionTest.doTest(HttpPartitionTest.java:120)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:878)
at sun.reflect.GeneratedMethodAccessor47.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$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 9871 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/init-core-data-001
[junit4] 2> 3795907 T16242
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /
[junit4] 2> 3795912 T16242 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 3795913 T16242 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3795914 T16243 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 3796014 T16242 oasc.ZkTestServer.run start zk server on
port:52924
[junit4] 2> 3796015 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3796016 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3796021 T16250 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@45de2721
name:ZooKeeperConnection Watcher:127.0.0.1:52924 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3796021 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3796022 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3796022 T16242 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 3796030 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3796033 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3796036 T16253 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@66dd1c1d
name:ZooKeeperConnection Watcher:127.0.0.1:52924/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3796036 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3796037 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3796037 T16242 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 3796044 T16242 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 3796050 T16242 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 3796055 T16242 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 3796062 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 3796063 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 3796073 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 3796074 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 3796080 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3796081 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3796087 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 3796088 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 3796093 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 3796094 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 3796102 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 3796102 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 3796108 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 3796109 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 3796115 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 3796116 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 3796121 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3796122 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3796128 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 3796129 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 3796135 T16242 oasc.AbstractZkTestCase.putConfig put
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 3796136 T16242 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 3797765 T16242 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/cores/collection1
[junit4] 2> 3797770 T16242 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3797776 T16242 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@5b2d6f92{/,null,AVAILABLE}
[junit4] 2> 3797780 T16242 oejs.AbstractConnector.doStart Started
ServerConnector@15ee9ef9{HTTP/1.1}{127.0.0.1:52928}
[junit4] 2> 3797781 T16242 oejs.Server.doStart Started @3799480ms
[junit4] 2> 3797781 T16242 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3797782 T16242 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 3797782 T16242 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001
[junit4] 2> 3797782 T16242 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/'
[junit4] 2> 3797832 T16242 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/solr.xml
[junit4] 2> 3797843 T16242 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/cores
[junit4] 2> 3797844 T16242 oasc.CoreContainer.<init> New CoreContainer
1861338045
[junit4] 2> 3797845 T16242 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/]
[junit4] 2> 3797847 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 3797847 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 3797847 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 3797848 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 3797849 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 3797849 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 3797850 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 3797850 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 3797850 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 3797851 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 3797851 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 3797854 T16242 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3797854 T16242 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3797855 T16242 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3797855 T16242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3797856 T16242 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52924/solr
[junit4] 2> 3797856 T16242 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 3797857 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3797859 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3797866 T16267 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@9450516 name:ZooKeeperConnection
Watcher:127.0.0.1:52924 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 3797866 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3797867 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3797877 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3797877 T16270 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@39dacff name:ZooKeeperConnection
Watcher:127.0.0.1:52924/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 3797878 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3797882 T16242 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 3797888 T16242 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 3797894 T16242 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 3797902 T16242 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 3797910 T16242 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 3797918 T16242 oascc.SolrZkClient.makePath makePath:
/live_nodes
[junit4] 2> 3797923 T16242 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52927_
[junit4] 2> 3797924 T16242 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52927_
[junit4] 2> 3797931 T16242 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 3797936 T16242 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 3797941 T16242 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3797945 T16242 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:52927_
[junit4] 2> 3797945 T16242 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 3797950 T16242 oasc.Overseer.start Overseer
(id=93188079123562499-127.0.0.1:52927_-n_0000000000) starting
[junit4] 2> 3797955 T16242 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 3797978 T16242 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 3797980 T16272 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 3797982 T16242 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 3797988 T16242 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 3797992 T16242
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 3798004 T16271 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 3798006 T16242 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/cores
[junit4] 2> 3798008 T16242 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/cores/collection1/
[junit4] 2> 3798008 T16242 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 3798010 T16274 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 3798011 T16274 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 3798012 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3798012 T16274 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 3798022 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52927";,
[junit4] 2> "node_name":"127.0.0.1:52927_",
[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> 3798024 T16271 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52927";,
[junit4] 2> "node_name":"127.0.0.1:52927_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 3798024 T16271 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 3798025 T16271 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 3798029 T16270 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> 3799016 T16274 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 3799016 T16274 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 3799018 T16274 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 3799018 T16274 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 3799021 T16274 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 3799021 T16274 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/cores/collection1/'
[junit4] 2> 3799048 T16274 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 3799054 T16274 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 3799065 T16274 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 3799091 T16274 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 3799100 T16274 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 3799104 T16274 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 3799108 T16274 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 3799228 T16274 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3799236 T16274 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 3799236 T16274 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3799269 T16274 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 3799275 T16274 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 3799279 T16274 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3799282 T16274
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 3799283 T16274
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 3799283 T16274 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3799286 T16274
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 3799286 T16274
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 3799286 T16274 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 3799286 T16274 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3799287 T16274 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/control-001/cores/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/control/data/
[junit4] 2> 3799287 T16274 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@549cca2f
[junit4] 2> 3799288 T16274 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/control/data
[junit4] 2> 3799288 T16274 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/control/data/index/
[junit4] 2> 3799288 T16274 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 3799289 T16274 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/control/data/index
[junit4] 2> 3799289 T16274 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=31.9892578125,
floorSegmentMB=2.1171875, forceMergeDeletesPctAllowed=18.87649253119419,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 3799290 T16274 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@277d9e35
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3970bde8),segFN=segments_1,generation=1}
[junit4] 2> 3799290 T16274 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 3799296 T16274 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 3799297 T16274 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 3799298 T16274 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 3799298 T16274 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 3799299 T16274 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 3799299 T16274 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3799300 T16274 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3799300 T16274 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 3799301 T16274 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3799302 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3799303 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3799303 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3799304 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3799304 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 3799305 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 3799306 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 3799306 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 3799307 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 3799308 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 3799308 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 3799309 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 3799309 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 3799310 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 3799310 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 3799311 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 3799311 T16274 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 3799311 T16274 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3799319 T16274 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3799321 T16274 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3799322 T16274 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3799323 T16274 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3799323 T16274 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3799326 T16274 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 3799327 T16274 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 3799328 T16274 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=35, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=6.07421875,
floorSegmentMB=1.8740234375, forceMergeDeletesPctAllowed=29.373445862670998,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3094775221388596
[junit4] 2> 3799328 T16274 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@277d9e35
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3970bde8),segFN=segments_1,generation=1}
[junit4] 2> 3799329 T16274 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 3799329 T16274 oass.SolrIndexSearcher.<init> Opening
Searcher@74e92c9f[collection1] main
[junit4] 2> 3799330 T16274 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 3799333 T16274 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 3799333 T16274 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 3799335 T16274
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3799335 T16274 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 3799335 T16274 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3799337 T16274
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3799338 T16274 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3799338 T16274 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 3799338 T16274 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 3799338 T16274 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 3799339 T16275 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@74e92c9f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3799341 T16278 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:52927 collection:control_collection
shard:shard1
[junit4] 2> 3799342 T16242 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3799343 T16242 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 3799344 T16278 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 3799346 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3799352 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3799355 T16282 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@103b29ae
name:ZooKeeperConnection Watcher:127.0.0.1:52924/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3799356 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3799357 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3799360 T16242
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 3799368 T16278
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard1
[junit4] 2> 3799372 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3799373 T16242 oasc.ChaosMonkey.monkeyLog monkey: init -
expire sessions:false cause connection loss:false
[junit4] 2> 3799373 T16242 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 3799374 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3799375 T16278
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 3799375 T16278
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C6015 name=collection1
org.apache.solr.core.SolrCore@666eb02a url=http://127.0.0.1:52927/collection1
node=127.0.0.1:52927_ C6015_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:52927,
node_name=127.0.0.1:52927_, state=down}
[junit4] 2> 3799375 T16278 C6015 P52927 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:52927/collection1/
[junit4] 2> 3799376 T16278 C6015 P52927 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 3799376 T16278 C6015 P52927 oasc.SyncStrategy.syncToMe
http://127.0.0.1:52927/collection1/ has no replicas
[junit4] 2> 3799377 T16278
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:52927/collection1/ shard1
[junit4] 2> 3799376 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3799377 T16278 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 3799381 T16271 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> 3799382 T16285 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@30a1a5dc
name:ZooKeeperConnection Watcher:127.0.0.1:52924/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3799384 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3799384 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3799393 T16244 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14b121071760005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 3799401 T16271 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> 3799402 T16271 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 3799406 T16270 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> 3799407 T16282 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> 3799430 T16286 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3799444 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:52927";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 3799447 T16271 oasco.ZkStateWriter.writePendingUpdates going
to create_collection /collections/collection1/state.json
[junit4] 2> 3799451 T16282 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> 3799465 T16286 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3799469 T16282 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> 3799489 T16278 oasc.ZkController.register We are
http://127.0.0.1:52927/collection1/ and leader is
http://127.0.0.1:52927/collection1/
[junit4] 2> 3799490 T16278 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:52927
[junit4] 2> 3799490 T16278 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 3799491 T16278 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 3799491 T16278 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 3799494 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3799506 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52927";,
[junit4] 2> "node_name":"127.0.0.1:52927_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 3799508 T16271 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":"http://127.0.0.1:52927";,
[junit4] 2> "node_name":"127.0.0.1:52927_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 3799618 T16282 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> 3799620 T16270 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> 3801578 T16242 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/cores/collection1
[junit4] 2> 3801582 T16242 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 3801583 T16242 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3801590 T16242 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@5597a09b{/,null,AVAILABLE}
[junit4] 2> 3801590 T16242 oejs.AbstractConnector.doStart Started
ServerConnector@281fabe9{HTTP/1.1}{127.0.0.1:52934}
[junit4] 2> 3801591 T16242 oejs.Server.doStart Started @3803289ms
[junit4] 2> 3801591 T16242 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3801592 T16242 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 3801592 T16242 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001
[junit4] 2> 3801593 T16242 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/'
[junit4] 2> 3801639 T16242 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/solr.xml
[junit4] 2> 3801650 T16242 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/cores
[junit4] 2> 3801652 T16242 oasc.CoreContainer.<init> New CoreContainer
1998531082
[junit4] 2> 3801652 T16242 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/]
[junit4] 2> 3801653 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 3801653 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 3801653 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 3801654 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 3801654 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 3801654 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 3801655 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 3801655 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 3801656 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 3801656 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 3801656 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 3801657 T16242 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3801658 T16242 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3801658 T16242 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3801658 T16242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3801659 T16242 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52924/solr
[junit4] 2> 3801659 T16242 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 3801660 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3801661 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3801665 T16300 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2417e843
name:ZooKeeperConnection Watcher:127.0.0.1:52924 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3801665 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3801666 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3801671 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3801675 T16303 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7af98b6b
name:ZooKeeperConnection Watcher:127.0.0.1:52924/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3801676 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3801693 T16242
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 3802713 T16242 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52933_
[junit4] 2> 3802715 T16242 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52933_
[junit4] 2> 3802728 T16242 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3802736 T16242 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/cores
[junit4] 2> 3802738 T16242 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/cores/collection1/
[junit4] 2> 3802738 T16242 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 3802747 T16304 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 3802748 T16304 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 3802752 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3802754 T16304 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 3802755 T16304 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 3802757 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52933";,
[junit4] 2> "node_name":"127.0.0.1:52933_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 3802758 T16304 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 3802759 T16271 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52933";,
[junit4] 2> "node_name":"127.0.0.1:52933_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 3802760 T16271 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 3802760 T16271 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 3802762 T16304 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 3802763 T16304 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 3802766 T16271 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 3802768 T16303 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 3802770 T16303 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 3803766 T16304 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 3803767 T16304 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 3803769 T16304 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 3803769 T16304 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 3803772 T16304 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 3803773 T16304 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/cores/collection1/'
[junit4] 2> 3803797 T16304 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 3803804 T16304 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 3803820 T16304 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 3803827 T16304 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 3803839 T16304 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 3803842 T16304 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 3803847 T16304 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 3804041 T16304 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3804051 T16304 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 3804052 T16304 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3804083 T16304 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 3804088 T16304 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 3804094 T16304 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3804097 T16304
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 3804098 T16304
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 3804098 T16304 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3804101 T16304
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 3804102 T16304
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 3804103 T16304 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 3804103 T16304 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3804103 T16304 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-1-001/cores/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty1/
[junit4] 2> 3804104 T16304 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@549cca2f
[junit4] 2> 3804104 T16304 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty1
[junit4] 2> 3804104 T16304 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty1/index/
[junit4] 2> 3804105 T16304 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 3804105 T16304 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty1/index
[junit4] 2> 3804106 T16304 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=31.9892578125,
floorSegmentMB=2.1171875, forceMergeDeletesPctAllowed=18.87649253119419,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 3804107 T16304 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@2b7fb646
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73cf3f99),segFN=segments_1,generation=1}
[junit4] 2> 3804107 T16304 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 3804113 T16304 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 3804113 T16304 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 3804114 T16304 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 3804114 T16304 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 3804114 T16304 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 3804115 T16304 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3804115 T16304 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3804116 T16304 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 3804116 T16304 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3804117 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804118 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804119 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804119 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3804120 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 3804121 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 3804122 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 3804123 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 3804124 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 3804129 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 3804130 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 3804130 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 3804131 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 3804131 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 3804132 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 3804132 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 3804133 T16304 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 3804134 T16304 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3804141 T16304 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804143 T16304 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804144 T16304 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804146 T16304 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3804146 T16304 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3804150 T16304 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 3804151 T16304 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 3804152 T16304 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=35, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=6.07421875,
floorSegmentMB=1.8740234375, forceMergeDeletesPctAllowed=29.373445862670998,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3094775221388596
[junit4] 2> 3804152 T16304 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@2b7fb646
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73cf3f99),segFN=segments_1,generation=1}
[junit4] 2> 3804153 T16304 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 3804153 T16304 oass.SolrIndexSearcher.<init> Opening
Searcher@601b1eb[collection1] main
[junit4] 2> 3804154 T16304 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 3804157 T16304 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 3804158 T16304 oasr.ManagedResourceStorage.newStorageIO
Setting up ZooKeeper-based storage for the RestManager with znodeBase:
/configs/conf1
[junit4] 2> 3804160 T16304
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 3804161 T16304 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 3804161 T16304 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3804163 T16304
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 3804164 T16304 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 3804164 T16304 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 3804165 T16304 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 3804166 T16305 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@601b1eb[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3804166 T16304 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 3804170 T16308 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:52933 collection:collection1
shard:shard2
[junit4] 2> 3804171 T16242 oass.SolrDispatchFilter.init
user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 3804173 T16242 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 3804172 T16308 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 3804191 T16308
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for
shard shard2
[junit4] 2> 3804195 T16308
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 3804195 T16308
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try
and sync
[junit4] 2> ASYNC NEW_CORE C6016 name=collection1
org.apache.solr.core.SolrCore@1542cdd8 url=http://127.0.0.1:52933/collection1
node=127.0.0.1:52933_ C6016_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:52933,
node_name=127.0.0.1:52933_, state=down}
[junit4] 2> 3804196 T16308 C6016 P52933 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:52933/collection1/
[junit4] 2> 3804197 T16308 C6016 P52933 oasc.SyncStrategy.syncReplicas
Sync Success - now sync replicas to me
[junit4] 2> 3804197 T16308 C6016 P52933 oasc.SyncStrategy.syncToMe
http://127.0.0.1:52933/collection1/ has no replicas
[junit4] 2> 3804198 T16308
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
http://127.0.0.1:52933/collection1/ shard2
[junit4] 2> 3804198 T16308 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 3804202 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3804210 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 3804215 T16271 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 1
[junit4] 2> 3804220 T16303 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 3804223 T16303 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 3804248 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:52933";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 3804252 T16271 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 2
[junit4] 2> 3804256 T16303 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 3804258 T16303 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 3804267 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3804269 T16308 oasc.ZkController.register We are
http://127.0.0.1:52933/collection1/ and leader is
http://127.0.0.1:52933/collection1/
[junit4] 2> 3804270 T16308 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:52933
[junit4] 2> 3804270 T16308 oasc.ZkController.checkRecovery I am the
leader, no recovery necessary
[junit4] 2> 3804270 T16308 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 3804270 T16308 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 3804275 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3804285 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52933";,
[junit4] 2> "node_name":"127.0.0.1:52933_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 3804287 T16271 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":"http://127.0.0.1:52933";,
[junit4] 2> "node_name":"127.0.0.1:52933_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 3804290 T16308 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 3804291 T16271 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 3
[junit4] 2> 3804293 T16303 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 3804295 T16303 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 3806216 T16242 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/cores/collection1
[junit4] 2> 3806219 T16242 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 3806221 T16242 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 3806226 T16242 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@592bc0f2{/,null,AVAILABLE}
[junit4] 2> 3806227 T16242 oejs.AbstractConnector.doStart Started
ServerConnector@1a1928c5{HTTP/1.1}{127.0.0.1:52938}
[junit4] 2> 3806227 T16242 oejs.Server.doStart Started @3807923ms
[junit4] 2> 3806228 T16242 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 3806229 T16242 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 3806229 T16242 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001
[junit4] 2> 3806229 T16242 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/'
[junit4] 2> 3806289 T16242 oasc.ConfigSolr.fromFile Loading container
configuration from
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/solr.xml
[junit4] 2> 3806301 T16242 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/cores
[junit4] 2> 3806302 T16242 oasc.CoreContainer.<init> New CoreContainer
526779453
[junit4] 2> 3806303 T16242 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/]
[junit4] 2> 3806304 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 3806305 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 3806305 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 3806306 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 3806306 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 3806306 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 3806307 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 3806307 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 3806307 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 3806308 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 3806308 T16242 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 3806309 T16242 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 3806309 T16242 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3806309 T16242 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3806310 T16242 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 3806310 T16242 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:52924/solr
[junit4] 2> 3806311 T16242 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 3806311 T16242
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 3806314 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3806317 T16323 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@492a0964
name:ZooKeeperConnection Watcher:127.0.0.1:52924 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3806317 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3806318 T16242 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 3806323 T16242 oascc.ConnectionManager.waitForConnected
Waiting for client to connect to ZooKeeper
[junit4] 2> 3806325 T16326 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@19c3a29a
name:ZooKeeperConnection Watcher:127.0.0.1:52924/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3806326 T16242 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 3806343 T16242
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 3807361 T16242 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52937_
[junit4] 2> 3807363 T16242 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52937_
[junit4] 2> 3807379 T16242 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 3807387 T16242 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/cores
[junit4] 2> 3807392 T16242 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/cores/collection1/
[junit4] 2> 3807393 T16242 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 3807403 T16327 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 3807404 T16327 oasc.ZkController.publish numShards not found
on descriptor - reading it from system property
[junit4] 2> 3807409 T16270 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3807412 T16327 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 3807413 T16327 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 3807414 T16271 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52937";,
[junit4] 2> "node_name":"127.0.0.1:52937_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 3807415 T16327 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 3807416 T16271 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52937";,
[junit4] 2> "node_name":"127.0.0.1:52937_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 3807416 T16271 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 3807417 T16271 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 3807420 T16327 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 3807421 T16327 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 3807421 T16271 oasco.ZkStateWriter.writePendingUpdates going
to update_collection /collections/collection1/state.json version: 4
[junit4] 2> 3807423 T16326 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 3807423 T16303 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 3807425 T16326 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 3807426 T16303 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 3808423 T16327 oasc.ZkController.waitForShardId waiting to
find shard id in clusterstate for collection1
[junit4] 2> 3808424 T16327 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 3808426 T16327 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 3808426 T16327 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 3808429 T16327 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 3808429 T16327 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/cores/collection1/'
[junit4] 2> 3808454 T16327 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 3808459 T16327 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 3808470 T16327 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 3808475 T16327 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 3808484 T16327 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 3808488 T16327 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 3808494 T16327 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 3808631 T16327 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 3808638 T16327 oass.IndexSchema.readSchema default search
field in schema is text
[junit4] 2> 3808639 T16327 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3808669 T16327 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 3808678 T16327 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 3808683 T16327 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3808687 T16327
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 3808687 T16327
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 3808688 T16327 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 3808691 T16327
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 3808692 T16327
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 3808692 T16327 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 3808693 T16327 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3808693 T16327 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/shard-2-001/cores/collection1/,
dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty2/
[junit4] 2> 3808694 T16327 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@549cca2f
[junit4] 2> 3808695 T16327 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty2
[junit4] 2> 3808695 T16327 oasc.SolrCore.getNewIndexDir New index
directory detected: old=null
new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty2/index/
[junit4] 2> 3808696 T16327 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 3808697 T16327 oasc.CachingDirectoryFactory.get return new
directory for
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest
AD6E885588021F21-001/tempDir-001/jetty2/index
[junit4] 2> 3808698 T16327 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=48, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=31.9892578125,
floorSegmentMB=2.1171875, forceMergeDeletesPctAllowed=18.87649253119419,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 3808699 T16327 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3864eaaa
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@71b06032),segFN=segments_1,generation=1}
[junit4] 2> 3808700 T16327 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 3808708 T16327 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 3808709 T16327 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 3808710 T16327 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"dedupe"
[junit4] 2> 3808710 T16327 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 3808711 T16327 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"stored_sig"
[junit4] 2> 3808711 T16327 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3808712 T16327 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3808712 T16327 oasup.UpdateRequestProcessorChain.init
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 3808712 T16327 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3808713 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3808714 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3808715 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3808715 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 3808716 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 3808717 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 3808718 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 3808718 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 3808719 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 3808721 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 3808721 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 3808722 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 3808723 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 3808723 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 3808725 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 3808726 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 3808727 T16327 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 3808727 T16327 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 3808735 T16327 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3808737 T16327 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3808740 T16327 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3808742 T16327 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3808742 T16327 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 3808746 T16327 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 3808746 T16327 oasu.CommitTr
[...truncated too long message...]
E: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_25
(64-bit)/cpus=2,threads=1,free=126232232,total=412160000
[junit4] 2> NOTE: All tests run in this JVM: [TestInitQParser,
TestSolrDeletionPolicy2, TestCSVResponseWriter, TestCustomSort,
SolrTestCaseJ4Test, MBeansHandlerTest, SolrRequestParserTest,
TestDefaultStatsCache, TestObjectReleaseTracker, TestJmxIntegration,
HdfsUnloadDistributedZkTest, TestMaxScoreQueryParser, SpellCheckComponentTest,
TestFaceting, TestOverriddenPrefixQueryForCustomFieldType, MinimalSchemaTest,
UpdateParamsTest, RankQueryTest, TestAtomicUpdateErrorCases,
DistributedSuggestComponentTest, HdfsBasicDistributedZk2Test,
TestSolrQueryParserDefaultOperatorResource, TestStressVersions,
PingRequestHandlerTest, AnalysisErrorHandlingTest, TestLFUCache,
ParsingFieldUpdateProcessorsTest, TestFieldTypeResource, SolrIndexConfigTest,
JSONWriterTest, TestRecovery, CollectionsAPIAsyncDistributedZkTest,
DistributedDebugComponentTest, TimeZoneUtilsTest,
DocExpirationUpdateProcessorFactoryTest, TestDistribDocBasedVersion,
ZkSolrClientTest, ResponseLogComponentTest, TestInitParams,
StatelessScriptUpdateProcessorFactoryTest, TestSuggestSpellingConverter,
SSLMigrationTest, HdfsDirectoryFactoryTest, TestBlendedInfixSuggestions,
SpellPossibilityIteratorTest, DocumentBuilderTest,
TestManagedSchemaFieldTypeResource, DistribDocExpirationUpdateProcessorTest,
TestSolrConfigHandler, DirectSolrConnectionTest,
SharedFSAutoReplicaFailoverTest, TestIndexSearcher, TestTrie,
FullSolrCloudDistribCmdsTest, DistributedFacetPivotLargeTest,
TestPivotHelperCode, TestDynamicFieldCollectionResource, TestDocumentBuilder,
TestSolrDeletionPolicy1, HdfsWriteToMultipleCollectionsTest, TestManagedSchema,
TestRandomFaceting, BadComponentTest, SharedFSAutoReplicaFailoverUtilsTest,
SuggestComponentTest, TestConfigSets, CursorMarkTest,
TestManagedSynonymFilterFactory, TestGroupingSearch, TestInfoStreamLogging,
DisMaxRequestHandlerTest, TestSolrConfigHandlerConcurrent, CloudMLTQParserTest,
TestUpdate, SOLR749Test, TestWriterPerf, TestBadConfig, TestHashPartitioner,
LeaderInitiatedRecoveryOnCommitTest, CustomCollectionTest,
UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter,
TestIndexingPerformance, TestJoin, TestSweetSpotSimilarityFactory,
TestFastWriter, ZkCLITest, TestBulkSchemaAPI, TestBlobHandler,
TestDynamicFieldResource, TestWordDelimiterFilterFactory, TestFastOutputStream,
TestRecoveryHdfs, HdfsDirectoryTest, LeaderElectionTest, OverseerRolesTest,
TestReplicaProperties, OutputWriterTest, TestReRankQParserPlugin,
TestSerializedLuceneMatchVersion, FieldAnalysisRequestHandlerTest,
TestFunctionQuery, TestCodecSupport, BasicDistributedZk2Test,
TestSolrCoreProperties, RecoveryZkTest, ShardRoutingCustomTest,
ChaosMonkeySafeLeaderTest, TermVectorComponentTest, TestDFRSimilarityFactory,
TestXIncludeConfig, UUIDFieldTest, TestOrdValues,
TestManagedSchemaFieldResource, TestPostingsSolrHighlighter,
LukeRequestHandlerTest, ShowFileRequestHandlerTest,
BinaryUpdateRequestHandlerTest, SolrInfoMBeanTest, TestExceedMaxTermLength,
BadCopyFieldTest, TestCloudManagedSchema, CircularListTest,
RequestHandlersTest, CoreAdminCreateDiscoverTest, ClusterStateUpdateTest,
TestPseudoReturnFields, TestCoreDiscovery, DocValuesTest, StressHdfsTest,
DistributedSpellCheckComponentTest, CoreMergeIndexesAdminHandlerTest,
BufferStoreTest, TestExactStatsCache, TestRealTimeGet, SynonymTokenizerTest,
HighlighterMaxOffsetTest, DefaultValueUpdateProcessorTest, TestCoreContainer,
TestNRTOpen, PrimUtilsTest, ChaosMonkeyNothingIsSafeTest,
TestElisionMultitermQuery, CurrencyFieldXmlFileTest, TestSurroundQueryParser,
RegexBoostProcessorTest, TestCopyFieldCollectionResource,
TestPhraseSuggestions, TestIntervalFaceting, AnalysisAfterCoreReloadTest,
TestDynamicLoading, TestValueSourceCache, DistributedFacetPivotSmallTest,
SolrCoreCheckLockOnStartupTest, MultiThreadedOCPTest, SolrCoreTest,
PreAnalyzedUpdateProcessorTest, DistributedFacetPivotWhiteBoxTest,
AsyncMigrateRouteKeyTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest,
StatsComponentTest, SchemaVersionSpecificBehaviorTest,
CoreAdminRequestStatusTest, LeaderFailoverAfterPartitionTest, HighlighterTest,
TestArbitraryIndexDir, TestQuerySenderNoQuery, DirectSolrSpellCheckerTest,
TestFuzzyAnalyzedSuggestions, TestLRUStatsCache, TestDefaultSimilarityFactory,
ExternalCollectionsTest, DeleteShardTest, TestLeaderElectionZkExpiry,
FacetPivotSmallTest, LoggingHandlerTest, TestFastLRUCache,
CachingDirectoryFactoryTest, TestNoOpRegenerator, TestChildDocTransformer,
SliceStateTest, TestShardHandlerFactory, TestRequestStatusCollectionAPI,
TestMiniSolrCloudClusterSSL, CacheHeaderTest, TestManagedResourceStorage,
TestStressRecovery, TestAnalyzedSuggestions, BasicDistributedZkTest,
RollingRestartTest, HardAutoCommitTest, TestConfigOverlay, QueryEqualityTest,
TestBM25SimilarityFactory, OutOfBoxZkACLAndCredentialsProvidersTest,
DistribCursorPagingTest, TestCloudSchemaless, AtomicUpdatesTest,
TestMergePolicyConfig, SortByFunctionTest, TestRandomMergePolicy,
PreAnalyzedFieldTest, TestCollectionAPI, TestFieldSortValues,
TestUniqueKeyFieldResource, TestPartialUpdateDeduplication,
RecoveryAfterSoftCommitTest, TestBinaryField, TestQuerySenderListener,
StandardRequestHandlerTest, DocValuesMissingTest, TestSearchPerf,
QueryParsingTest, TestMiniSolrCloudCluster, TestNonDefinedSimilarityFactory,
ChangedSchemaMergeTest, ResponseHeaderTest, CloudExitableDirectoryReaderTest,
HighlighterConfigTest, TestQueryUtils,
AddSchemaFieldsUpdateProcessorFactoryTest, TestSchemaResource, DateFieldTest,
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest,
OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest,
ShardRoutingTest, BasicZkTest, TestReplicationHandler, TestZkChroot,
TestRandomDVFaceting, TestDistributedSearch, AutoCommitTest,
TestDistributedGrouping, TermVectorComponentDistributedTest, ZkControllerTest,
TestStressReorder, TestReload, DistributedTermsComponentTest, TestRangeQuery,
SimpleFacetsTest, TestSolr4Spatial, SolrCmdDistributorTest,
QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest,
ConvertedLegacyTest, TestSort, TestFiltering, BasicFunctionalityTest,
TestLazyCores, DirectUpdateHandlerTest, OverseerCollectionProcessorTest,
SoftAutoCommitTest, CurrencyFieldOpenExchangeTest,
DistributedQueryElevationComponentTest, SolrIndexSplitterTest,
SimplePostToolTest, SignatureUpdateProcessorFactoryTest,
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest,
CoreAdminHandlerTest, TestFoldingMultitermQuery, SuggesterTSTTest,
SuggesterTest, TestStressLucene, SpatialFilterTest, SuggesterWFSTTest,
TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest,
FieldMutatingUpdateProcessorTest, DirectUpdateHandlerOptimizeTest,
TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest,
XsltUpdateRequestHandlerTest, DebugComponentTest, IndexBasedSpellCheckerTest,
TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest,
TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions,
FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest,
PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, RequiredFieldsTest,
TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest,
SolrPluginUtilsTest, IndexSchemaTest, TestCollationField, ReturnFieldsTest,
UpdateRequestProcessorFactoryTest, JsonLoaderTest, CSVRequestHandlerTest,
TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter,
AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, MultiTermTest,
SampleTest, TestDocSet, NumericFieldsTest, TestConfig,
ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion,
TestCharFilters, EchoParamsTest, TestPerFieldSimilarity,
TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory,
TestIBSimilarityFactory, TestJmxMonitoredMap, ResourceLoaderTest,
ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest,
PluginInfoTest, DateMathParserTest, TestSystemIdResolver,
SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest,
ClusterStateTest, TestSolrJ, TestLRUCache, TestUtils, ZkNodePropsTest,
SystemInfoHandlerTest, FileUtilsTest, TestRTGBase, CursorPagingTest,
DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey,
TestDistributedMissingSort, TestHighlightDedupGrouping, TestTolerantSearch,
AliasIntegrationTest, AssignTest, ConnectionManagerTest,
DeleteInactiveReplicaTest, DeleteReplicaTest, DistributedQueueTest,
HttpPartitionTest]
[junit4] Completed in 88.75s, 1 test, 1 error <<< FAILURES!
[...truncated 285 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:198: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956:
There were test failures: 461 suites, 1844 tests, 1 error, 50 ignored (19
assumptions)
Total time: 122 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0
-XX:+UseCompressedOops -XX:+UseSerialGC
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]
