Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11720/
Java: 32bit/jdk1.8.0_31 -client -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.handler.TestBlobHandler.doBlobHandlerTest
Error Message:
{responseHeader={status=0, QTime=2}, response={numFound=0, start=0, docs=[]}}
Stack Trace:
java.lang.AssertionError: {responseHeader={status=0, QTime=2},
response={numFound=0, start=0, docs=[]}}
at
__randomizedtesting.SeedInfo.seed([31DD296D6E140D59:D11C0B3FD5F87BAB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.handler.TestBlobHandler.doBlobHandlerTest(TestBlobHandler.java:96)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
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
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915)
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 9324 lines...]
[junit4] Suite: org.apache.solr.handler.TestBlobHandler
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/init-core-data-001
[junit4] 2> 328972 T1307 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (false)
[junit4] 2> 328973 T1307 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /ocojy/
[junit4] 2> 328982 T1307 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 328993 T1308 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 329092 T1307 oasc.ZkTestServer.run start zk server on
port:53027
[junit4] 2> 329093 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 329097 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 329108 T1315 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@12a80ac name:ZooKeeperConnection
Watcher:127.0.0.1:53027 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 329108 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 329110 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 329110 T1307 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 329113 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 329120 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 329122 T1318 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@e821d4 name:ZooKeeperConnection
Watcher:127.0.0.1:53027/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 329123 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 329124 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 329124 T1307 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 329135 T1307 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 329138 T1307 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 329141 T1307 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 329144 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 329144 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 329149 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 329149 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 329153 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 329154 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 329156 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 329157 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 329160 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 329160 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 329163 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 329163 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 329166 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 329166 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 329170 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 329171 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 329173 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 329174 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 329176 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 329177 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 329179 T1307 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 329180 T1307 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 329186 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 329187 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 329195 T1321 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@bd8760 name:ZooKeeperConnection
Watcher:127.0.0.1:53027/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 329202 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 329203 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 329670 T1307 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/cores/collection1
[junit4] 2> 329673 T1307 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 329689 T1307 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1,
TLSv1.2]
[junit4] 2> 329709 T1307 oejs.AbstractConnector.doStart Started
[email protected]:?????
[junit4] 2> 329714 T1307 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 329715 T1307 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 329715 T1307 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001
[junit4] 2> 329716 T1307 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/'
[junit4] 2> 329779 T1307 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/solr.xml
[junit4] 2> 329810 T1307 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/cores
[junit4] 2> 329811 T1307 oasc.CoreContainer.<init> New CoreContainer
5027438
[junit4] 2> 329811 T1307 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/]
[junit4] 2> 329812 T1307 oasc.CoreContainer.load loading shared library:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/lib
[junit4] 2> 329813 T1307 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/lib).
[junit4] 2> 329831 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 329831 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 329832 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 329832 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 329833 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 329833 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 329834 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 329834 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 329835 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 329835 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 329836 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 329839 T1307 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 329842 T1307 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 329842 T1307 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 329843 T1307 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 329843 T1307 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53027/solr
[junit4] 2> 329844 T1307 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 329844 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 329845 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 329848 T1333 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@16054f3 name:ZooKeeperConnection
Watcher:127.0.0.1:53027 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 329848 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 329850 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 329856 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 329859 T1336 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@b2f75a name:ZooKeeperConnection
Watcher:127.0.0.1:53027/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 329859 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 329862 T1307 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 329865 T1307 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 329869 T1307 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 329872 T1307 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 329875 T1307 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 329887 T1307 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 329890 T1307 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49442_ocojy
[junit4] 2> 329891 T1307 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:49442_ocojy
[junit4] 2> 329894 T1307 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 329896 T1307 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 329898 T1307 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 329900 T1307 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:49442_ocojy
[junit4] 2> 329901 T1307 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 329903 T1307 oasc.Overseer.start Overseer
(id=93384872757886980-127.0.0.1:49442_ocojy-n_0000000000) starting
[junit4] 2> 329905 T1307 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 329920 T1307 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 329921 T1338 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 329922 T1307 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 329922 T1337 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 329925 T1307 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 329927 T1307
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 329932 T1307 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/cores
[junit4] 2> 329933 T1307 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/cores/collection1/
[junit4] 2> 329934 T1307 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 329935 T1340 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 329936 T1340 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 329937 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 329937 T1340 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 329938 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:49442/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:49442_ocojy",
[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> 329939 T1337 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:49442/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:49442_ocojy",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 329940 T1337 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 329943 T1337 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 329945 T1336 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> 330937 T1340 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 330938 T1340 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 330938 T1340 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 330939 T1340 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 330940 T1340 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 330940 T1340 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/cores/collection1/'
[junit4] 2> 330973 T1340 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 330977 T1340 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 330997 T1340 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 331022 T1340 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.1.0
[junit4] 2> 331069 T1340 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 331071 T1340 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 331130 T1340 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 331579 T1340 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 331591 T1340 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 331594 T1340 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 331626 T1340 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 331631 T1340 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 331635 T1340 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 331637 T1340
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 331637 T1340
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 331637 T1340 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 331639 T1340
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 331639 T1340
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 331639 T1340 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 331640 T1340 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 331640 T1340 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/control-001/cores/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/control/data/
[junit4] 2> 331641 T1340 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1ceec11
[junit4] 2> 331642 T1340 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/control/data
[junit4] 2> 331642 T1340 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/control/data/index/
[junit4] 2> 331643 T1340 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 331643 T1340 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/control/data/index
[junit4] 2> 331644 T1340 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=3.8134765625,
floorSegmentMB=1.771484375, forceMergeDeletesPctAllowed=2.078780726020927,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42309052211592024
[junit4] 2> 331645 T1340 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@a0b74
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@379070),segFN=segments_1,generation=1}
[junit4] 2> 331646 T1340 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 331655 T1340 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 331656 T1340 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 331656 T1340 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 331657 T1340 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 331657 T1340 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 331658 T1340 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 331658 T1340 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 331659 T1340 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 331659 T1340 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 331660 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 331661 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 331662 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 331663 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 331664 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 331665 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 331667 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 331668 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 331669 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 331671 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 331672 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 331673 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 331674 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 331675 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 331676 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 331677 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 331678 T1340 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 331679 T1340 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 331685 T1340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 331687 T1340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 331689 T1340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 331690 T1340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 331692 T1340 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 331693 T1340 oasu.UpdateHandler.<init> Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 331694 T1340 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 331694 T1340 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 331695 T1340 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 331696 T1340 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@a0b74
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@379070),segFN=segments_1,generation=1}
[junit4] 2> 331697 T1340 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 331697 T1340 oass.SolrIndexSearcher.<init> Opening
Searcher@1c07c99[collection1] main
[junit4] 2> 331698 T1340 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 331699 T1340 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 331700 T1340 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 331700 T1340
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 331700 T1340 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 331701 T1340 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 331702 T1340
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 331702 T1340 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 331702 T1340 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 331703 T1340 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 331704 T1341 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1c07c99[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 331705 T1340 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 331706 T1344 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:49442/ocojy
collection:control_collection shard:shard1
[junit4] 2> 331706 T1307 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 331707 T1307 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 331709 T1344 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 331712 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 331712 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 331714 T1347 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4a039e name:ZooKeeperConnection
Watcher:127.0.0.1:53027/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 331715 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 331715 T1344 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 331716 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 331718 T1307
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 331719 T1344
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 331719 T1344 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 331718 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> ASYNC NEW_CORE C1220 name=collection1
org.apache.solr.core.SolrCore@181b876
url=https://127.0.0.1:49442/ocojy/collection1 node=127.0.0.1:49442_ocojy
C1220_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:49442/ocojy, node_name=127.0.0.1:49442_ocojy,
state=down}
[junit4] 2> 331719 T1344 C1220 P49442 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:49442/ocojy/collection1/
[junit4] 2> 331720 T1344 C1220 P49442 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 331721 T1344 C1220 P49442 oasc.SyncStrategy.syncToMe
https://127.0.0.1:49442/ocojy/collection1/ has no replicas
[junit4] 2> 331721 T1344 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:49442/ocojy/collection1/ shard1
[junit4] 2> 331721 T1344 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 331722 T1337 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> 331725 T1336 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> 331725 T1347 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 331727 T1307 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 331738 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:49442/ocojy";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 331741 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 331742 T1336 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> 331742 T1347 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> 331784 T1344 oasc.ZkController.register We are
https://127.0.0.1:49442/ocojy/collection1/ and leader is
https://127.0.0.1:49442/ocojy/collection1/
[junit4] 2> 331785 T1344 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:49442/ocojy
[junit4] 2> 331785 T1344 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 331785 T1344 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 331786 T1344 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 331788 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 331791 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:49442/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:49442_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 331797 T1337 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:49442/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:49442_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 331901 T1336 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> 331901 T1347 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> 332108 T1307 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/cores/collection1
[junit4] 2> 332110 T1307 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 332111 T1307 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 332120 T1307 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1,
TLSv1.2]
[junit4] 2> 332124 T1307 oejs.AbstractConnector.doStart Started
[email protected]:?????
[junit4] 2> 332128 T1307 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 332128 T1307 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 332128 T1307 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001
[junit4] 2> 332129 T1307 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/'
[junit4] 2> 332162 T1307 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/solr.xml
[junit4] 2> 332186 T1307 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/cores
[junit4] 2> 332186 T1307 oasc.CoreContainer.<init> New CoreContainer
6941965
[junit4] 2> 332187 T1307 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/]
[junit4] 2> 332187 T1307 oasc.CoreContainer.load loading shared library:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/lib
[junit4] 2> 332188 T1307 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/lib).
[junit4] 2> 332210 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 332211 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 332211 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 332211 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 332212 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 332212 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 332213 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 332213 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 332214 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 332214 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 332215 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 332218 T1307 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 332221 T1307 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 332221 T1307 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 332222 T1307 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 332222 T1307 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53027/solr
[junit4] 2> 332223 T1307 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 332223 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 332224 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 332226 T1359 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@48732c name:ZooKeeperConnection
Watcher:127.0.0.1:53027 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 332227 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 332228 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 332232 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 332241 T1362 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1e0a940 name:ZooKeeperConnection
Watcher:127.0.0.1:53027/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 332243 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 332256 T1307
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 333260 T1307 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55053_ocojy
[junit4] 2> 333262 T1307 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:55053_ocojy
[junit4] 2> 333268 T1307 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 333271 T1307 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/cores
[junit4] 2> 333273 T1307 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/cores/collection1/
[junit4] 2> 333274 T1307 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 333275 T1363 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 333275 T1363 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 333276 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 333276 T1363 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 333278 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:55053/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:55053_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 333279 T1337 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:55053/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:55053_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 333279 T1337 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 333279 T1337 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 333383 T1336 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 333383 T1362 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 333384 T1347 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 334277 T1363 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 334277 T1363 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 334278 T1363 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 334278 T1363 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 334279 T1363 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 334280 T1363 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/cores/collection1/'
[junit4] 2> 334310 T1363 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 334312 T1363 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 334324 T1363 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 334342 T1363 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.1.0
[junit4] 2> 334392 T1363 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 334394 T1363 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 334421 T1363 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 334768 T1363 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 334793 T1363 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 334795 T1363 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 334842 T1363 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 334846 T1363 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 334849 T1363 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 334851 T1363
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 334851 T1363
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 334851 T1363 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 334852 T1363
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 334853 T1363
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 334853 T1363 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 334853 T1363 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 334854 T1363 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-1-001/cores/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty1/
[junit4] 2> 334854 T1363 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1ceec11
[junit4] 2> 334855 T1363 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty1
[junit4] 2> 334855 T1363 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty1/index/
[junit4] 2> 334856 T1363 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 334856 T1363 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty1/index
[junit4] 2> 334857 T1363 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=3.8134765625,
floorSegmentMB=1.771484375, forceMergeDeletesPctAllowed=2.078780726020927,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42309052211592024
[junit4] 2> 334858 T1363 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1b5c59f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f2d656),segFN=segments_1,generation=1}
[junit4] 2> 334858 T1363 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 334865 T1363 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 334865 T1363 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 334866 T1363 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 334866 T1363 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 334866 T1363 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 334867 T1363 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 334867 T1363 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 334867 T1363 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 334868 T1363 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 334869 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 334873 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 334874 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 334875 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 334875 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 334876 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 334877 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 334878 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 334879 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 334880 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 334881 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 334882 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 334892 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 334893 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 334895 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 334896 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 334897 T1363 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 334899 T1363 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 334902 T1363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 334904 T1363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 334906 T1363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 334907 T1363 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 334909 T1363 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 334909 T1363 oasu.UpdateHandler.<init> Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 334910 T1363 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 334911 T1363 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 334913 T1363 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 334914 T1363 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1b5c59f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f2d656),segFN=segments_1,generation=1}
[junit4] 2> 334914 T1363 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 334914 T1363 oass.SolrIndexSearcher.<init> Opening
Searcher@2ebf80[collection1] main
[junit4] 2> 334915 T1363 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 334916 T1363 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 334916 T1363 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 334917 T1363
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 334917 T1363 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 334917 T1363 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 334937 T1363
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 334937 T1363 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 334937 T1363 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 334938 T1363 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 334940 T1364 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@2ebf80[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 334940 T1363 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 334941 T1367 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:55053/ocojy collection:collection1
shard:shard2
[junit4] 2> 334942 T1307 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 334942 T1307 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 334942 T1367 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 334949 T1367 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 334951 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 334952 T1367
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 334953 T1337 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> 334953 T1367 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1221 name=collection1
org.apache.solr.core.SolrCore@184d074
url=https://127.0.0.1:55053/ocojy/collection1 node=127.0.0.1:55053_ocojy
C1221_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=https://127.0.0.1:55053/ocojy, node_name=127.0.0.1:55053_ocojy,
state=down}
[junit4] 2> 334954 T1367 C1221 P55053 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:55053/ocojy/collection1/
[junit4] 2> 334954 T1367 C1221 P55053 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 334955 T1367 C1221 P55053 oasc.SyncStrategy.syncToMe
https://127.0.0.1:55053/ocojy/collection1/ has no replicas
[junit4] 2> 334955 T1367 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:55053/ocojy/collection1/ shard2
[junit4] 2> 334956 T1347 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 334955 T1336 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 334955 T1362 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 334958 T1367 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 334967 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 334968 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:55053/ocojy";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 6
[junit4] 2> 335073 T1362 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 335073 T1347 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 335073 T1336 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 335119 T1367 oasc.ZkController.register We are
https://127.0.0.1:55053/ocojy/collection1/ and leader is
https://127.0.0.1:55053/ocojy/collection1/
[junit4] 2> 335119 T1367 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:55053/ocojy
[junit4] 2> 335119 T1367 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 335120 T1367 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 335120 T1367 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 335125 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 335126 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:55053/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:55053_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 7
[junit4] 2> 335128 T1337 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:55053/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:55053_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 335232 T1336 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 335232 T1347 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 335232 T1362 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 335304 T1307 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/cores/collection1
[junit4] 2> 335305 T1307 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 335306 T1307 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 335318 T1307 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1,
TLSv1.2]
[junit4] 2> 335323 T1307 oejs.AbstractConnector.doStart Started
[email protected]:?????
[junit4] 2> 335326 T1307 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 335326 T1307 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 335326 T1307 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001
[junit4] 2> 335327 T1307 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/'
[junit4] 2> 335357 T1307 oasc.SolrXmlConfig.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/solr.xml
[junit4] 2> 335368 T1307 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/cores
[junit4] 2> 335369 T1307 oasc.CoreContainer.<init> New CoreContainer
29757554
[junit4] 2> 335369 T1307 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/]
[junit4] 2> 335369 T1307 oasc.CoreContainer.load loading shared library:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/lib
[junit4] 2> 335370 T1307 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/lib).
[junit4] 2> 335380 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 335380 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 335380 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 335381 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 335381 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 335381 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 335382 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 335382 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 335382 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 335383 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 335383 T1307 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 335385 T1307 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 335387 T1307 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 335387 T1307 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 335388 T1307 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 335388 T1307 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53027/solr
[junit4] 2> 335389 T1307 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 335389 T1307
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 335390 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 335392 T1379 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@13d4668 name:ZooKeeperConnection
Watcher:127.0.0.1:53027 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 335392 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 335393 T1307 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 335396 T1307 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 335397 T1382 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@244b1b name:ZooKeeperConnection
Watcher:127.0.0.1:53027/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 335397 T1307 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 335405 T1307
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 336409 T1307 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38335_ocojy
[junit4] 2> 336410 T1307 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:38335_ocojy
[junit4] 2> 336418 T1307 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 336434 T1307 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/cores
[junit4] 2> 336436 T1307 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/cores/collection1/
[junit4] 2> 336436 T1307 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 336439 T1383 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 336439 T1383 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 336441 T1336 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 336441 T1383 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 336444 T1337 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:38335/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:38335_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 8
[junit4] 2> 336446 T1337 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:38335/ocojy";,
[junit4] 2> "node_name":"127.0.0.1:38335_ocojy",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 336446 T1337 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 336446 T1337 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 336550 T1347 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 336550 T1336 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 336550 T1362 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 336550 T1382 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 337442 T1383 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 337442 T1383 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 337443 T1383 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 337443 T1383 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 337444 T1383 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 337444 T1383 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/cores/collection1/'
[junit4] 2> 337480 T1383 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 337483 T1383 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 337491 T1383 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 337507 T1383 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.1.0
[junit4] 2> 337553 T1383 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 337554 T1383 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 337576 T1383 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 337903 T1383 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 337914 T1383 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 337916 T1383 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 337935 T1383 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 337939 T1383 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 337943 T1383 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 337944 T1383
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 337944 T1383
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 337945 T1383 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 337946 T1383
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 337946 T1383
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 337946 T1383 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 337947 T1383 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 337950 T1383 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/shard-2-001/cores/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty2/
[junit4] 2> 337950 T1383 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1ceec11
[junit4] 2> 337951 T1383 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty2
[junit4] 2> 337951 T1383 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty2/index/
[junit4] 2> 337951 T1383 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 337951 T1383 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty2/index
[junit4] 2> 337952 T1383 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=3.8134765625,
floorSegmentMB=1.771484375, forceMergeDeletesPctAllowed=2.078780726020927,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42309052211592024
[junit4] 2> 337953 T1383 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@17fed9
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d487b),segFN=segments_1,generation=1}
[junit4] 2> 337953 T1383 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 337963 T1383 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 337964 T1383 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 337964 T1383 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 337964 T1383 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 337965 T1383 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 337965 T1383 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 337966 T1383 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 337966 T1383 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 337967 T1383 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 337969 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 337970 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 337971 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 337972 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 337973 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 337974 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 337976 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 337991 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 337992 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 337993 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 337993 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 337994 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 337995 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 337996 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 337997 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 337998 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 337999 T1383 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 337999 T1383 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 338003 T1383 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 338027 T1383 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 338029 T1383 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 338030 T1383 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 338032 T1383 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 338033 T1383 oasu.UpdateHandler.<init> Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 338034 T1383 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 338034 T1383 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 338036 T1383 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23,
maxMergeSize=2147483648, maxMergeSize
[...truncated too long message...]
ByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 353778 T1424 oasu.SolrCoreState.decrefSolrCoreState Closing
SolrCoreState
[junit4] 2> 353778 T1424 oasu.DefaultSolrCoreState.closeIndexWriter
SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 353778 T1424 oasu.DefaultSolrCoreState.closeIndexWriter
closing IndexWriter with IndexWriterCloser
[junit4] 2> 353780 T1424 oasc.SolrCore.closeSearcher [collection1] Closing
main searcher on request.
[junit4] 2> 353846 T1424 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 353847 T1424 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty4/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty4/index;done=false>>]
[junit4] 2> 353847 T1424 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty4/index
[junit4] 2> 353847 T1424 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty4
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty4;done=false>>]
[junit4] 2> 353848 T1424 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001/tempDir-001/jetty4
[junit4] 2> 353848 T1424 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 353848 T1424 oasc.SolrException.log ERROR There was a problem
trying to register as the leader:org.apache.solr.common.SolrException: Could
not register as the leader because creating the ephemeral registration node in
ZooKeeper failed
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:147)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:323)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:390)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:262)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /collections
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:292)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:289)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:289)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:484)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:453)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:440)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:139)
[junit4] 2> at
org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> ... 11 more
[junit4] 2>
[junit4] 2> 354751 T1466 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 354774 T1307 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/ocojy,null}
[junit4] 2> 354972 T1307 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:53027 53027
[junit4] 2> 355677 T1308 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:53027 53027
[junit4] 2> 355680 T1308 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN
Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 2 /solr/configs/.system
[junit4] 2> 2 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBlobHandler
-Dtests.method=doBlobHandlerTest -Dtests.seed=31DD296D6E140D59
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th_TH_TH_#u-nu-thai
-Dtests.timezone=Asia/Bahrain -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 26.7s J0 | TestBlobHandler.doBlobHandlerTest <<<
[junit4] > Throwable #1: java.lang.AssertionError:
{responseHeader={status=0, QTime=2}, response={numFound=0, start=0, docs=[]}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([31DD296D6E140D59:D11C0B3FD5F87BAB]:0)
[junit4] > at
org.apache.solr.handler.TestBlobHandler.doBlobHandlerTest(TestBlobHandler.java:96)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 355722 T1307 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestBlobHandler
31DD296D6E140D59-001
[junit4] 2> 26770 T1306 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{blobName=BlockTreeOrds(blocksize=128), size=Lucene50(blocksize=128),
_version_=FST50, id=PostingsFormat(name=Memory doPackFST= false),
version=FST50, md5=BlockTreeOrds(blocksize=128),
timestamp=BlockTreeOrds(blocksize=128)}, docValues:{}, sim=DefaultSimilarity,
locale=th_TH_TH_#u-nu-thai, timezone=Asia/Bahrain
[junit4] 2> NOTE: Linux 3.13.0-45-generic i386/Oracle Corporation 1.8.0_31
(32-bit)/cpus=8,threads=1,free=118760984,total=276410368
[junit4] 2> NOTE: All tests run in this JVM: [TestFieldCollectionResource,
HttpPartitionTest, TestDistribDocBasedVersion, CacheHeaderTest,
TestSurroundQueryParser, FieldAnalysisRequestHandlerTest,
CollectionsAPIAsyncDistributedZkTest, UniqFieldsUpdateProcessorFactoryTest,
CustomCollectionTest, TestHighlightDedupGrouping, TestBlobHandler]
[junit4] Completed on J0 in 27.11s, 1 test, 1 failure <<< FAILURES!
[...truncated 1379 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:191: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:509: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1370:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:976:
There were test failures: 468 suites, 1870 tests, 1 failure, 48 ignored (21
assumptions)
Total time: 84 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_31 -client
-XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
