Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/720/
2 tests failed.
REGRESSION: org.apache.solr.cloud.AliasIntegrationTest.testDistribSearch
Error Message:
Stack Trace:
java.lang.NullPointerException
at
__randomizedtesting.SeedInfo.seed([6A3703BC82601E40:EBD18DA4F53F7E7C]:0)
at
org.apache.solr.cloud.AliasIntegrationTest.doTest(AliasIntegrationTest.java:232)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
Error from server at http://127.0.0.1:24500: Error CREATEing SolrCore
'halfcollection_shard1_replica1': Unable to create core
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core:
halfcollection_shard1_replica1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:24500: Error CREATEing SolrCore
'halfcollection_shard1_replica1': Unable to create core
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core:
halfcollection_shard1_replica1
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:449)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:201)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9892 lines...]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/init-core-data-001
[junit4] 2> 300267 T1319 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 300267 T1319 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /_l/h
[junit4] 2> 300274 T1319 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 300276 T1319 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 300276 T1320 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 300376 T1319 oasc.ZkTestServer.run start zk server on
port:13193
[junit4] 2> 300377 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 300378 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 300383 T1327 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3b70ff2b
name:ZooKeeperConnection Watcher:127.0.0.1:13193 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 300383 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 300384 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 300384 T1319 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 300388 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 300389 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 300391 T1330 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@153f3b33
name:ZooKeeperConnection Watcher:127.0.0.1:13193/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 300391 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 300391 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 300392 T1319 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 300395 T1319 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 300396 T1319 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 300398 T1319 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 300400 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 300400 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 300403 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 300404 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 300507 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 300508 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 300510 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 300511 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 300513 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 300513 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 300515 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 300516 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 300518 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 300519 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 300521 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 300521 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 300523 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 300524 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 300526 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 300526 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 300529 T1319 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 300529 T1319 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 301696 T1319 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 301702 T1319 oejs.AbstractConnector.doStart Started
[email protected]:13196
[junit4] 2> 301703 T1319 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@749cd006
[junit4] 2> 301703 T1319 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 301703 T1319 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002
[junit4] 2> 301704 T1319 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/'
[junit4] 2> 301734 T1319 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/solr.xml
[junit4] 2> 301802 T1319 oasc.CoreContainer.<init> New CoreContainer
1775337738
[junit4] 2> 301802 T1319 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/]
[junit4] 2> 301804 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 301804 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 301804 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 301805 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 301805 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 301805 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 301806 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 301806 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 301807 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 301807 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 301808 T1319 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 301808 T1319 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 301809 T1319 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 301809 T1319 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 301809 T1319 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:13193/solr
[junit4] 2> 301810 T1319 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 301810 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 301811 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 301813 T1342 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@62736f7e
name:ZooKeeperConnection Watcher:127.0.0.1:13193 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 301814 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 301815 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 301817 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 301819 T1345 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@19c36aad
name:ZooKeeperConnection Watcher:127.0.0.1:13193/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 301819 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 301821 T1319 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 301825 T1319 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 301827 T1319 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 301829 T1319 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 301831 T1319 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 301833 T1319 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 301835 T1319 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:13196__l%2Fh
[junit4] 2> 301835 T1319 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:13196__l%2Fh
[junit4] 2> 301837 T1319 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 301839 T1319 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 301854 T1319 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 301858 T1319 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:13196__l%2Fh
[junit4] 2> 301859 T1319 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 301861 T1319 oasc.Overseer.start Overseer
(id=93064405938733059-127.0.0.1:13196__l%2Fh-n_0000000000) starting
[junit4] 2> 301865 T1319 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 301871 T1319 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 301873 T1347 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 301873 T1319 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 301875 T1319 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 301877 T1319
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 301880 T1346 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 301884 T1349 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 301885 T1349 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 301886 T1349 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 301886 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 301888 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:13196/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13196__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1"} current state version: 0
[junit4] 2> 301889 T1346 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:13196/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13196__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1"}
[junit4] 2> 301889 T1346 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 301889 T1346 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 301891 T1345 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> 302886 T1349 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 302887 T1349 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 302888 T1349 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 302888 T1349 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 302889 T1349 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 302889 T1349 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/collection1/'
[junit4] 2> 302890 T1349 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 302891 T1349 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 302892 T1349 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 302926 T1349 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 302928 T1349 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 302962 T1349 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 303009 T1349 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 303110 T1349 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 303119 T1349 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 303507 T1349 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 303520 T1349 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 303523 T1349 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 303535 T1349 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 303540 T1349 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 303544 T1349 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 303544 T1349
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 303546 T1349
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 303546 T1349 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 303547 T1349
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 303547 T1349
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 303547 T1349 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 303548 T1349 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 303548 T1349 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-002/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/control/data/
[junit4] 2> 303548 T1349 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16552add
[junit4] 2> 303549 T1349 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/control/data
[junit4] 2> 303549 T1349 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/control/data/index/
[junit4] 2> 303550 T1349 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/control/data/index'
doesn't exist. Creating new index...
[junit4] 2> 303550 T1349 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/control/data/index
[junit4] 2> 303550 T1349 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=38, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=65.9404296875,
floorSegmentMB=1.884765625, forceMergeDeletesPctAllowed=11.134789867608193,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=0.9462890625, noCFSRatio=1.0
[junit4] 2> 303551 T1349 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@32e149f6
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64becf92),segFN=segments_1,generation=1}
[junit4] 2> 303552 T1349 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 303556 T1349 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 303556 T1349 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 303557 T1349 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 303557 T1349 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 303557 T1349 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 303557 T1349 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 303558 T1349 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 303558 T1349 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 303558 T1349 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 303559 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 303560 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 303560 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 303560 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 303561 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 303561 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 303562 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 303562 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 303563 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 303564 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 303565 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 303565 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 303566 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 303566 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 303567 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 303567 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 303568 T1349 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 303568 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 303569 T1349 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 303587 T1349 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 303589 T1349 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 303591 T1349 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 303593 T1349 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 303596 T1349 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 303599 T1349 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 303599 T1349 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 303600 T1349 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=8,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.83480000822237]
[junit4] 2> 303601 T1349 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@32e149f6
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64becf92),segFN=segments_1,generation=1}
[junit4] 2> 303601 T1349 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 303602 T1349 oass.SolrIndexSearcher.<init> Opening
Searcher@1dd288fd[collection1] main
[junit4] 2> 303602 T1349 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 303603 T1349 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 303603 T1349 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 303604 T1349
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 303604 T1349 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 303604 T1349 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 303605 T1349
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 303605 T1349 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 303605 T1349 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 303606 T1349 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 303606 T1349 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 303606 T1350 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1dd288fd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 303607 T1353 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:13196/_l/h
collection:control_collection shard:shard1
[junit4] 2> 303607 T1319 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0
[junit4] 2> 303608 T1319 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 303608 T1353 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 303608 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 303609 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 303611 T1356 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@44ade290
name:ZooKeeperConnection Watcher:127.0.0.1:13193/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 303611 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 303611 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 303613 T1319
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 303614 T1353 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 303615 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 303616 T1353
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 303616 T1353 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 303616 T1319 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 303616 T1346 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> ASYNC NEW_CORE C464 name=collection1
org.apache.solr.core.SolrCore@18b3ef79
url=http://127.0.0.1:13196/_l/h/collection1 node=127.0.0.1:13196__l%2Fh
C464_STATE=coll:control_collection core:collection1
props:{base_url=http://127.0.0.1:13196/_l/h, node_name=127.0.0.1:13196__l%2Fh,
core=collection1, state=down}
[junit4] 2> 303616 T1353 C464 P13196 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:13196/_l/h/collection1/
[junit4] 2> 303616 T1319 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 303617 T1353 C464 P13196 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 303617 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 303618 T1345 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> 303618 T1356 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> 303618 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 303618 T1353 C464 P13196 oasc.SyncStrategy.syncToMe
http://127.0.0.1:13196/_l/h/collection1/ has no replicas
[junit4] 2> 303620 T1353 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:13196/_l/h/collection1/ shard1
[junit4] 2> 303620 T1353 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 303621 T1359 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3644fdd4
name:ZooKeeperConnection Watcher:127.0.0.1:13193/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 303621 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 303622 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 303624 T1360 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 303626 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":2} current state version: 2
[junit4] 2> 303626 T1346 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 303631 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:13196/_l/h";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 303632 T1346 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 303634 T1356 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> 303636 T1360 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 303637 T1356 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> 303676 T1353 oasc.ZkController.register We are
http://127.0.0.1:13196/_l/h/collection1/ and leader is
http://127.0.0.1:13196/_l/h/collection1/
[junit4] 2> 303677 T1353 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:13196/_l/h
[junit4] 2> 303677 T1353 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 303677 T1353 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 303678 T1353 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 303679 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 303681 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:13196/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:13196__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "numShards":"1"} current state version: 4
[junit4] 2> 303681 T1346 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:13196/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:13196__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "numShards":"1"}
[junit4] 2> 303785 T1345 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> 303785 T1356 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> 304764 T1319 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 304765 T1319 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 304769 T1319 oejs.AbstractConnector.doStart Started
[email protected]:13220
[junit4] 2> 304769 T1319 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@749cd006
[junit4] 2> 304769 T1319 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 304770 T1319 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003
[junit4] 2> 304770 T1319 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/'
[junit4] 2> 304800 T1319 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/solr.xml
[junit4] 2> 304853 T1319 oasc.CoreContainer.<init> New CoreContainer
1526381141
[junit4] 2> 304854 T1319 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/]
[junit4] 2> 304855 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 304855 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 304855 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 304855 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 304856 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 304856 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 304856 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 304856 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 304857 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 304857 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 304857 T1319 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 304858 T1319 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 304858 T1319 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 304858 T1319 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 304858 T1319 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:13193/solr
[junit4] 2> 304859 T1319 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 304859 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 304859 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 304861 T1372 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4eb3255a
name:ZooKeeperConnection Watcher:127.0.0.1:13193 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 304862 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 304863 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 304865 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 304866 T1375 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7b946dac
name:ZooKeeperConnection Watcher:127.0.0.1:13193/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 304866 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 304871 T1319
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 305875 T1319 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:13220__l%2Fh
[junit4] 2> 305876 T1319 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:13220__l%2Fh
[junit4] 2> 305880 T1319 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 305887 T1376 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 305888 T1376 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 305889 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 305890 T1376 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 305890 T1376 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 305890 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:13220/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13220__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1"} current state version: 5
[junit4] 2> 305890 T1376 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 305891 T1346 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:13220/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13220__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1"}
[junit4] 2> 305891 T1346 oasco.ReplicaMutator.updateState Collection
already exists with numShards=1
[junit4] 2> 305891 T1346 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 305891 T1376 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 305892 T1376 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 305893 T1346 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 305893 T1375 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 305894 T1375 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 306892 T1376 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 306893 T1376 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 306894 T1376 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 306894 T1376 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 306894 T1376 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 306895 T1376 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/collection1/'
[junit4] 2> 306896 T1376 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 306897 T1376 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 306898 T1376 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 306934 T1376 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 306937 T1376 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 306971 T1376 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 307018 T1376 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 307119 T1376 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 307129 T1376 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 307506 T1376 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 307519 T1376 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 307522 T1376 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 307534 T1376 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 307539 T1376 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 307543 T1376 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 307544 T1376
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 307545 T1376
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 307546 T1376 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 307547 T1376
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 307547 T1376
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 307547 T1376 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 307547 T1376 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 307548 T1376 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-003/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty1/
[junit4] 2> 307548 T1376 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16552add
[junit4] 2> 307549 T1376 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty1
[junit4] 2> 307549 T1376 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty1/index/
[junit4] 2> 307549 T1376 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty1/index'
doesn't exist. Creating new index...
[junit4] 2> 307550 T1376 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty1/index
[junit4] 2> 307550 T1376 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=38, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=65.9404296875,
floorSegmentMB=1.884765625, forceMergeDeletesPctAllowed=11.134789867608193,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=0.9462890625, noCFSRatio=1.0
[junit4] 2> 307551 T1376 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@7ce29336
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f2efe47),segFN=segments_1,generation=1}
[junit4] 2> 307551 T1376 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 307557 T1376 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 307557 T1376 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 307557 T1376 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 307558 T1376 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 307558 T1376 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 307558 T1376 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 307559 T1376 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 307559 T1376 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 307559 T1376 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 307560 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 307561 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 307561 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 307562 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 307562 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 307563 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 307563 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 307564 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 307565 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 307566 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 307566 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 307567 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 307567 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 307568 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 307569 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 307569 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 307570 T1376 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 307570 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 307571 T1376 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 307590 T1376 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 307592 T1376 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 307594 T1376 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 307596 T1376 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 307598 T1376 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 307601 T1376 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 307601 T1376 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 307602 T1376 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=8,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.83480000822237]
[junit4] 2> 307602 T1376 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@7ce29336
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7f2efe47),segFN=segments_1,generation=1}
[junit4] 2> 307603 T1376 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 307603 T1376 oass.SolrIndexSearcher.<init> Opening
Searcher@4cbb095e[collection1] main
[junit4] 2> 307603 T1376 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 307604 T1376 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 307604 T1376 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 307605 T1376
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 307605 T1376 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 307605 T1376 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 307606 T1376
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 307606 T1376 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 307606 T1376 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 307607 T1376 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 307608 T1377 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@4cbb095e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 307608 T1376 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 307609 T1380 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:13220/_l/h collection:collection1
shard:shard1
[junit4] 2> 307609 T1319 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0
[junit4] 2> 307610 T1319 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 307610 T1380 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 307614 T1380 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 307616 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 307616 T1380
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 307616 T1380 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C465 name=collection1
org.apache.solr.core.SolrCore@88fb02c
url=http://127.0.0.1:13220/_l/h/collection1 node=127.0.0.1:13220__l%2Fh
C465_STATE=coll:collection1 core:collection1
props:{base_url=http://127.0.0.1:13220/_l/h, node_name=127.0.0.1:13220__l%2Fh,
core=collection1, state=down}
[junit4] 2> 307617 T1380 C465 P13220 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:13220/_l/h/collection1/
[junit4] 2> 307617 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 307617 T1380 C465 P13220 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 307617 T1380 C465 P13220 oasc.SyncStrategy.syncToMe
http://127.0.0.1:13220/_l/h/collection1/ has no replicas
[junit4] 2> 307618 T1380 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:13220/_l/h/collection1/ shard1
[junit4] 2> 307618 T1380 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 307618 T1346 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 307619 T1375 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 307619 T1375 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 307622 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:13220/_l/h";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 307623 T1346 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 307624 T1375 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 307624 T1375 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 307626 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 307672 T1380 oasc.ZkController.register We are
http://127.0.0.1:13220/_l/h/collection1/ and leader is
http://127.0.0.1:13220/_l/h/collection1/
[junit4] 2> 307673 T1380 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:13220/_l/h
[junit4] 2> 307673 T1380 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 307673 T1380 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 307673 T1380 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 307675 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 307676 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:13220/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:13220__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "numShards":"1"} current state version: 5
[junit4] 2> 307677 T1346 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:13220/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:13220__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "numShards":"1"}
[junit4] 2> 307678 T1380 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 307678 T1346 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 307679 T1375 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 307679 T1375 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 308750 T1319 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 308752 T1319 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 308755 T1319 oejs.AbstractConnector.doStart Started
[email protected]:13223
[junit4] 2> 308756 T1319 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@749cd006
[junit4] 2> 308756 T1319 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 308756 T1319 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004
[junit4] 2> 308756 T1319 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/'
[junit4] 2> 308787 T1319 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/solr.xml
[junit4] 2> 308857 T1319 oasc.CoreContainer.<init> New CoreContainer
328274594
[junit4] 2> 308858 T1319 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/]
[junit4] 2> 308859 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 308859 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 308859 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 308859 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 308860 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 308860 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 308860 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 308860 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 308861 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 308861 T1319 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 308861 T1319 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 308862 T1319 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 308862 T1319 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 308862 T1319 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 308863 T1319 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:13193/solr
[junit4] 2> 308863 T1319 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 308863 T1319
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 308864 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 308866 T1392 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@64a03d94
name:ZooKeeperConnection Watcher:127.0.0.1:13193 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 308867 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 308867 T1319 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 308870 T1319 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 308872 T1395 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6dc47481
name:ZooKeeperConnection Watcher:127.0.0.1:13193/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 308872 T1319 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 308877 T1319
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 309882 T1319 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:13223__l%2Fh
[junit4] 2> 309883 T1319 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:13223__l%2Fh
[junit4] 2> 309887 T1319 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 309895 T1396 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 309895 T1396 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 309897 T1345 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 309897 T1396 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 309898 T1396 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 309898 T1346 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:13223/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13223__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1"} current state version: 5
[junit4] 2> 309898 T1396 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 309899 T1346 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:13223/_l/h";,
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:13223__l%2Fh",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1"}
[junit4] 2> 309899 T1346 oasco.ReplicaMutator.updateState Collection
already exists with numShards=1
[junit4] 2> 309899 T1346 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 309899 T1396 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 309900 T1396 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 309900 T1346 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 309901 T1395 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 309901 T1375 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 309902 T1395 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 309902 T1375 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 310900 T1396 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 310901 T1396 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 310902 T1396 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 310902 T1396 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 310902 T1396 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 310903 T1396 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/collection1/'
[junit4] 2> 310904 T1396 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 310905 T1396 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 310906 T1396 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 310939 T1396 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 310942 T1396 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 310977 T1396 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 311025 T1396 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 311127 T1396 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 311137 T1396 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 311520 T1396 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 311533 T1396 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 311536 T1396 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 311548 T1396 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 311552 T1396 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 311556 T1396 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 311557 T1396
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 311558 T1396
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 311559 T1396 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 311559 T1396
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 311560 T1396
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 311560 T1396 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 311560 T1396 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 311561 T1396 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-004/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty2/
[junit4] 2> 311561 T1396 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16552add
[junit4] 2> 311562 T1396 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty2
[junit4] 2> 311563 T1396 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty2/index/
[junit4] 2> 311563 T1396 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty2/index'
doesn't exist. Creating new index...
[junit4] 2> 311563 T1396 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest-6A3703BC82601E40-001/tempDir-001/jetty2/index
[junit4] 2> 311564 T1396 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=38, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=65.9404296875,
floorSegmentMB=1.884765625, forceMergeDeletesPctAllowed=11.134789867608193,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=0.9462890625, noCFSRatio=1.0
[junit4] 2> 311565 T1396 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@61ab1ef8
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@297e5de0),segFN=segments_1,generation=1}
[junit4] 2> 311565 T1396 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 311570 T1396 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 311571 T1396 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 311571 T1396 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 311571 T1396 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 311572 T1396 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 311572 T1396 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 311573 T1396 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 311573 T1396 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 311573 T1396 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 311574 T1396 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 311574 T1396 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 311575 T1396 oasc.RequestHandlers.initHandlersFromConfig
created /upd
[...truncated too long message...]
127.0.0.1:24507: Could not fully create collection:
awholynewstresscollection_collection4_7
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:353)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312)
[junit4] > ... 6 moreThrowable #5:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=3673, name=Thread-1449, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.RuntimeException:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available
to handle this request:[http://127.0.0.1:24500, http://127.0.0.1:24524,
http://127.0.0.1:24507, http://127.0.0.1:24518, http://127.0.0.1:24492]
[junit4] > at
__randomizedtesting.SeedInfo.seed([6A3703BC82601E40]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:915)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException:
No live SolrServers available to handle this request:[http://127.0.0.1:24500,
http://127.0.0.1:24524, http://127.0.0.1:24507, http://127.0.0.1:24518,
http://127.0.0.1:24492]
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:332)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1015)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:793)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:736)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1640)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1661)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:905)
[junit4] > Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:24507: Could not fully create collection:
awholynewstresscollection_collection5_8
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:353)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312)
[junit4] > ... 6 moreThrowable #6:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=3669, name=Thread-1445, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by: java.lang.RuntimeException:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available
to handle this request:[http://127.0.0.1:24500, http://127.0.0.1:24524,
http://127.0.0.1:24507, http://127.0.0.1:24518, http://127.0.0.1:24492]
[junit4] > at
__randomizedtesting.SeedInfo.seed([6A3703BC82601E40]:0)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:915)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException:
No live SolrServers available to handle this request:[http://127.0.0.1:24500,
http://127.0.0.1:24524, http://127.0.0.1:24507, http://127.0.0.1:24518,
http://127.0.0.1:24492]
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:332)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1015)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:793)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:736)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1640)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1661)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:905)
[junit4] > Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:24507: Could not fully create collection:
awholynewstresscollection_collection1_8
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:353)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312)
[junit4] > ... 6 more
[junit4] 2> 1987685 T3322 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-6A3703BC82601E40-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{multiDefault=PostingsFormat(name=LuceneFixedGap),
intDefault=Lucene50(blocksize=128),
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
a_t=PostingsFormat(name=LuceneFixedGap),
a_i1=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=Direct),
range_facet_l=PostingsFormat(name=LuceneFixedGap),
timestamp=PostingsFormat(name=LuceneFixedGap),
other_tl1=Lucene50(blocksize=128), _version_=Lucene50(blocksize=128)},
docValues:{timestamp=DocValuesFormat(name=Direct)},
sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=sq,
timezone=America/Iqaluit
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=16,threads=1,free=168234992,total=431489024
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedResourceStorage,
TestClusterStateMutator, SolrCoreTest, TestManagedStopFilterFactory,
HdfsRecoveryZkTest, SolrXmlInZkTest, TestLeaderElectionZkExpiry,
RAMDirectoryFactoryTest, TestConfigReload, TestQueryTypes, TestInitParams,
TestIndexSearcher, TestSchemaManager, TestJoin, BadIndexSchemaTest,
BinaryUpdateRequestHandlerTest, ShardRoutingTest, TestRestManager,
AddBlockUpdateTest, TestRawResponseWriter, TestManagedSynonymFilterFactory,
CoreAdminHandlerTest, ClusterStateUpdateTest, SolrIndexConfigTest,
TestInfoStreamLogging, XsltUpdateRequestHandlerTest, LeaderElectionTest,
TestPivotHelperCode, TermVectorComponentDistributedTest, RemoteQueryErrorTest,
TestManagedResource, AnalysisAfterCoreReloadTest, AssignTest, UUIDFieldTest,
IndexSchemaRuntimeFieldTest, TestIntervalFaceting,
HdfsCollectionsAPIDistributedZkTest, TestPostingsSolrHighlighter,
CachingDirectoryFactoryTest, TestRangeQuery, URLClassifyProcessorTest,
DocValuesTest, TestConfigOverlay, UnloadDistributedZkTest, OverseerStatusTest,
SuggesterWFSTTest, TestSystemIdResolver, TestIndexingPerformance,
TestArbitraryIndexDir, TestFieldTypeResource, TestUniqueKeyFieldResource,
TestFiltering, TestNoOpRegenerator, TestXIncludeConfig, DocValuesMultiTest,
BasicDistributedZkTest, CollectionsAPIDistributedZkTest]
[junit4] Completed on J3 in 1110.22s, 1 test, 1 error <<< FAILURES!
[...truncated 843 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:536:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:477:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:190:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:508:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:970:
There were test failures: 458 suites, 1859 tests, 2 errors, 57 ignored (10
assumptions)
Total time: 157 minutes 26 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #716
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 1271740603 bytes
Compression is 0.0%
Took 3 min 37 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
