Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/3/
2 tests failed.
FAILED: org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test
Error Message:
Exactly one shard should have changed, instead: [shard2, shard1]
nodes=([core_node3(shard2), core_node2(shard1), core_node4(shard1)])
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: Exactly one shard should have changed, instead:
[shard2, shard1] nodes=([core_node3(shard2), core_node2(shard1),
core_node4(shard1)]) expected:<1> but was:<2>
at
__randomizedtesting.SeedInfo.seed([4547B0A0B15D4AF0:CD138F7A1FA12708]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test(DistribDocExpirationUpdateProcessorTest.java:118)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.handler.TestReqParamsAPI.test
Error Message:
Could not get expected value 'CY val' for path 'params/c' full output: {
"responseHeader":{ "status":0, "QTime":0}, "params":{ "a":"A
val", "b":"B val", "wt":"json", "useParams":""}, "context":{
"webapp":"/_dz/b", "path":"/dump1", "httpMethod":"GET"}}
Stack Trace:
java.lang.AssertionError: Could not get expected value 'CY val' for path
'params/c' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"params":{
"a":"A val",
"b":"B val",
"wt":"json",
"useParams":""},
"context":{
"webapp":"/_dz/b",
"path":"/dump1",
"httpMethod":"GET"}}
at
__randomizedtesting.SeedInfo.seed([4547B0A0B15D4AF0:CD138F7A1FA12708]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:458)
at
org.apache.solr.handler.TestReqParamsAPI.testReqParams(TestReqParamsAPI.java:177)
at
org.apache.solr.handler.TestReqParamsAPI.test(TestReqParamsAPI.java:67)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11152 lines...]
[junit4] Suite: org.apache.solr.handler.TestReqParamsAPI
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/init-core-data-001
[junit4] 2> 159938 INFO
(SUITE-TestReqParamsAPI-seed#[4547B0A0B15D4AF0]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
[junit4] 2> 159939 INFO
(SUITE-TestReqParamsAPI-seed#[4547B0A0B15D4AF0]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_dz/b
[junit4] 2> 159941 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 159942 INFO (Thread-755) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 159942 INFO (Thread-755) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 160042 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.ZkTestServer start zk server on port:40785
[junit4] 2> 160042 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 160044 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160049 INFO (zkCallback-88-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2112de33
name:ZooKeeperConnection Watcher:127.0.0.1:40785 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 160049 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160049 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 160049 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 160054 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 160054 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160059 INFO (zkCallback-89-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@75d65e43
name:ZooKeeperConnection Watcher:127.0.0.1:40785/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 160059 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160059 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 160060 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 160063 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 160065 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 160067 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 160070 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 160070 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 160074 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 160074 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 160077 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 160077 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 160083 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 160083 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 160085 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 160085 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 160087 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 160087 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 160089 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 160089 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 160091 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 160091 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 160092 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 160092 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 160094 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 160094 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 160095 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 160096 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 160098 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 160098 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160100 INFO (zkCallback-90-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@55dabaa9
name:ZooKeeperConnection Watcher:127.0.0.1:40785/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 160100 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160100 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 160200 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1
[junit4] 2> 160231 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.e.j.s.Server
jetty-9.3.6.v20151106
[junit4] 2> 160233 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e22f0b1{/_dz/b,null,AVAILABLE}
[junit4] 2> 160242 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.e.j.u.s.SslContextFactory x509=X509@6421cb0b(solrtest,h=[],w=[]) for
SslContextFactory@6fd42af5(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/server/etc/test/solrtest.keystore)
[junit4] 2> 160379 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@540bba22{SSL,[ssl,
http/1.1]}{127.0.0.1:41085}
[junit4] 2> 160380 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.e.j.s.Server
Started @164286ms
[junit4] 2> 160380 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/tempDir-001/control/data,
hostContext=/_dz/b, hostPort=41085,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores}
[junit4] 2> 160380 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 160380 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001'
[junit4] 2> 160380 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 160380 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 160381 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 160381 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160383 INFO (zkCallback-91-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@755e987 name:ZooKeeperConnection
Watcher:127.0.0.1:40785/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 160383 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160383 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 160385 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 160385 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/solr.xml
[junit4] 2> 160391 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores
[junit4] 2> 160391 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CoreContainer New CoreContainer 2003609143
[junit4] 2> 160391 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001]
[junit4] 2> 160391 WARN
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/lib
[junit4] 2> 160391 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 160401 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 160403 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.l.LogWatcher
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 160403 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.l.LogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 160403 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:40785/solr
[junit4] 2> 160403 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 160403 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 160403 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160411 INFO (zkCallback-94-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@53e7d0c4
name:ZooKeeperConnection Watcher:127.0.0.1:40785 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 160415 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160415 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 160420 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160437 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@115c60fd
name:ZooKeeperConnection Watcher:127.0.0.1:40785/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 160437 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160439 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 160441 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 160444 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 160445 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 160447 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 160450 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 160452 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 160453 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 160454 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 160455 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 160457 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 160458 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 160459 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 160464 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 160464 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.LeaderElector Joined leadership election with path:
/overseer_elect/election/95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000
[junit4] 2> 160464 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.OverseerElectionContext I am going to be the leader
127.0.0.1:41085__dz%2Fb
[junit4] 2> 160464 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 160469 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.Overseer Overseer
(id=95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000) starting
[junit4] 2> 160471 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 160476 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 160477 INFO
(OverseerCollectionConfigSetProcessor-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.OverseerTaskProcessor Process current
queue of overseer operations
[junit4] 2> 160477 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer Starting to work on the main
queue
[junit4] 2> 160477 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41085__dz%2Fb
[junit4] 2> 160478 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41085__dz%2Fb
[junit4] 2> 160479 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (0)
[junit4] 2> 160480 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A live node change:
[WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes],
has occurred - updating... (live nodes size: [0])
[junit4] 2> 160480 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 160500 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 160500 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 160501 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores
[junit4] 2> 160502 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1,
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true,
configSetProperties=configsetprops.json, collection=control_collection,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 160502 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.CorePropertiesLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1
[junit4] 2> 160502 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 160512 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 160513 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 160514 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 160518 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41085/_dz/b";,
[junit4] 2> "node_name":"127.0.0.1:41085__dz%2Fb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 160519 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ReplicaMutator Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41085/_dz/b";,
[junit4] 2> "node_name":"127.0.0.1:41085__dz%2Fb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 160519 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ClusterStateMutator building a new
cName: control_collection
[junit4] 2> 160520 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 160521 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 160534 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
[junit4] 2> 160534 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:41085__dz%2Fb
] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 161515 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 161515 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 161517 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 161517 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 161518 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 161518 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1'
[junit4] 2> 161518 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 161519 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 161522 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1] o.a.s.c.Config
loaded config solrconfig.xml with version 0
[junit4] 2> 161530 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 161535 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 161546 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 161554 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 162055 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 162063 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 162064 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 162077 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 162080 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 162082 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 162083 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 162085 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
[junit4] 2> 162085 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 162086 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 162086 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data/]
[junit4] 2> 162086 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e625b48
[junit4] 2> 162087 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data
[junit4] 2> 162087 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data/index/
[junit4] 2> 162087 WARN
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 162087 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data/index
[junit4] 2> 162088 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=50, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=47.837890625,
floorSegmentMB=0.56640625, forceMergeDeletesPctAllowed=13.703291873643428,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7933585054449298
[junit4] 2> 162089 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@e9ab558
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50628d01),segFN=segments_1,generation=1}
[junit4] 2> 162089 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 162093 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 162093 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 162093 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 162093 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 162094 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 162094 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 162094 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 162094 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 162094 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
default, creating implicit default
[junit4] 2> 162094 WARN
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 162097 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 162098 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 162099 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
Looking for old index directories to cleanup for core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data/
[junit4] 2> 162099 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.DirectoryFactory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 162100 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 162102 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 162108 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 162108 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 162109 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 162109 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 162110 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 162110 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 162115 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=18, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=52.4296875,
floorSegmentMB=0.6337890625, forceMergeDeletesPctAllowed=26.579332383166168,
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 162116 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@e9ab558
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@50628d01),segFN=segments_1,generation=1}
[junit4] 2> 162116 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 162116 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@53771e11[collection1] main]
[junit4] 2> 162117 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 162118 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 162118 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based
storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 162119 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 162119 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 162119 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 162119 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 162120 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 162120 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 162120 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 162121 INFO
(searcherExecutor-350-thread-1-processing-n:127.0.0.1:41085__dz%2Fb
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@53771e11[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 162121 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed
version buckets
[junit4] 2> 162121 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for
65536 version buckets from index
[junit4] 2> 162121 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed
version bucket highest value from index
[junit4] 2> 162121 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1528041320293072896
[junit4] 2> 162123 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with
highest version 1528041320293072896
[junit4] 2> 162124 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 162124 INFO
(coreLoadExecutor-349-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 162125 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
Register replica - core:collection1 address:https://127.0.0.1:41085/_dz/b
collection:control_collection shard:shard1
[junit4] 2> 162126 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 162129 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector
Joined leadership election with path:
/collections/control_collection/leader_elect/shard1/election/95502582375579653-core_node1-n_0000000000
[junit4] 2> 162130 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1
and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 162132 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 162132 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 162132 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:41085/_dz/b/collection1/
[junit4] 2> 162132 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 162132 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 162132 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
https://127.0.0.1:41085/_dz/b/collection1/ has no replicas
[junit4] 2> 162134 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 162134 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 162137 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/control_collection/leaders/shard1/leader after winning as
/collections/control_collection/leader_elect/shard1/election/95502582375579653-core_node1-n_0000000000
[junit4] 2> 162139 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:41085/_dz/b/collection1/ shard1
[junit4] 2> 162140 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:41085/_dz/b";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 162243 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 162292 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are https://127.0.0.1:41085/_dz/b/collection1/ and leader is
https://127.0.0.1:41085/_dz/b/collection1/
[junit4] 2> 162292 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:41085/_dz/b
[junit4] 2> 162292 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 162292 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 162292 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 162294 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41085/_dz/b";,
[junit4] 2> "node_name":"127.0.0.1:41085__dz%2Fb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 162295 INFO
(coreZkRegister-343-thread-1-processing-n:127.0.0.1:41085__dz%2Fb x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41085__dz%2Fb
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 162295 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ReplicaMutator Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41085/_dz/b";,
[junit4] 2> "node_name":"127.0.0.1:41085__dz%2Fb",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 162398 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 162549 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 162550 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162553 INFO (zkCallback-97-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@21313858
name:ZooKeeperConnection Watcher:127.0.0.1:40785/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 162553 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162553 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 162553 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 162556 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 162557 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.c.ChaosMonkey
monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 162671 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/cores/collection1
[junit4] 2> 162672 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001
[junit4] 2> 162674 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.e.j.s.Server
jetty-9.3.6.v20151106
[junit4] 2> 162676 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2b45159{/_dz/b,null,AVAILABLE}
[junit4] 2> 162677 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.e.j.u.s.SslContextFactory x509=X509@6f2c0872(solrtest,h=[],w=[]) for
SslContextFactory@561870d1(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/server/etc/test/solrtest.keystore)
[junit4] 2> 162683 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@3858ef82{SSL,[ssl,
http/1.1]}{127.0.0.1:47354}
[junit4] 2> 162683 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.e.j.s.Server
Started @166589ms
[junit4] 2> 162683 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/_dz/b, hostPort=47354,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/cores}
[junit4] 2> 162683 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 162683 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001'
[junit4] 2> 162683 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 162684 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 162684 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 162685 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162691 INFO (zkCallback-98-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@641a0c32
name:ZooKeeperConnection Watcher:127.0.0.1:40785/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 162691 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162692 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 162693 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 162694 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/solr.xml
[junit4] 2> 162700 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/cores
[junit4] 2> 162700 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CoreContainer New CoreContainer 226791255
[junit4] 2> 162700 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001]
[junit4] 2> 162701 WARN
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/lib
[junit4] 2> 162701 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 162705 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 162706 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.l.LogWatcher
SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 162706 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.l.LogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 162707 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:40785/solr
[junit4] 2> 162707 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 162707 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 162707 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162712 INFO (zkCallback-101-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4dd1efb3
name:ZooKeeperConnection Watcher:127.0.0.1:40785 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 162713 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162714 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 162719 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162722 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7c130919
name:ZooKeeperConnection Watcher:127.0.0.1:40785/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 162722 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162731 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 162733 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 162735 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 162735 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.LeaderElector Joined leadership election with path:
/overseer_elect/election/95502582375579657-127.0.0.1:47354__dz%2Fb-n_0000000001
[junit4] 2> 162736 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000
to know if I could be the leader
[junit4] 2> 162736 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.ZkController Publish node=127.0.0.1:47354__dz%2Fb as DOWN
[junit4] 2> 162739 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:47354__dz%2Fb"} current state
version: 4
[junit4] 2> 162739 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.NodeMutator DownNode state invoked
for node: 127.0.0.1:47354__dz%2Fb
[junit4] 2> 162842 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 162842 INFO (zkCallback-97-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [1])
[junit4] 2> 162843 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
[junit4] 2> 163739 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47354__dz%2Fb
[junit4] 2> 163740 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:47354__dz%2Fb
[junit4] 2> 163741 INFO (zkCallback-97-thread-1) [ ]
o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 163741 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A live node change:
[WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes],
has occurred - updating... (live nodes size: [1])
[junit4] 2> 163741 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb ] o.a.s.c.c.ZkStateReader A live node change:
[WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes],
has occurred - updating... (live nodes size: [1])
[junit4] 2> 163742 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 163742 INFO (zkCallback-97-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 163742 INFO
(zkCallback-102-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 163804 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 163804 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 163807 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/cores
[junit4] 2> 163809 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1,
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true,
configSetProperties=configsetprops.json, collection=collection1, coreNodeName=,
dataDir=data/, shard=}
[junit4] 2> 163809 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.CorePropertiesLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.handler.TestReqParamsAPI_4547B0A0B15D4AF0-001/shard-1-001/cores/collection1
[junit4] 2> 163809 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 163813 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
[junit4] 2> 163813 INFO
(TEST-TestReqParamsAPI.test-seed#[4547B0A0B15D4AF0]) [n:127.0.0.1:47354__dz%2Fb
] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 163813 INFO
(coreLoadExecutor-360-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 163815 INFO
(coreLoadExecutor-360-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 163817 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:47354/_dz/b";,
[junit4] 2> "node_name":"127.0.0.1:47354__dz%2Fb",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 163817 INFO
(coreLoadExecutor-360-thread-1-processing-n:127.0.0.1:47354__dz%2Fb)
[n:127.0.0.1:47354__dz%2Fb c:collection1 x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 163818 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ReplicaMutator Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:47354/_dz/b";,
[junit4] 2> "node_name":"127.0.0.1:47354__dz%2Fb",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 163818 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ClusterStateMutator building a new
cName: collection1
[junit4] 2> 163818 INFO
(OverseerStateUpdate-95502582375579653-127.0.0.1:41085__dz%2Fb-n_0000000000)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 163922 INFO
(zkCallback-95-thread-1-processing-n:127.0.0.1:41085__dz%2Fb)
[n:127.0.0.1:41085__dz%2Fb ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json], has occurred -
[...truncated too long message...]
/shard-4-001/cores/collection1/data/index;done=false>>]
[junit4] 2> 3620468 INFO
(zkCallback-3708-thread-1-processing-n:127.0.0.1:41593_) [n:127.0.0.1:41593_
c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.CachingDirectoryFactory Closing directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_4547B0A0B15D4AF0-001/shard-4-001/cores/collection1/data/index
[junit4] 2> 3620469 INFO
(TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[4547B0A0B15D4AF0]) [
] o.e.j.s.ServerConnector Stopped
ServerConnector@44336f39{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 3620469 INFO
(TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[4547B0A0B15D4AF0]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2056210e{/,null,UNAVAILABLE}
[junit4] 2> 3620471 INFO
(TEST-DistribDocExpirationUpdateProcessorTest.test-seed#[4547B0A0B15D4AF0]) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40624 40624
[junit4] 2> 3620673 INFO (Thread-54437) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:40624 40624
[junit4] 2> 3620674 WARN (Thread-54437) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=DistribDocExpirationUpdateProcessorTest -Dtests.method=test
-Dtests.seed=4547B0A0B15D4AF0 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=zh-SG -Dtests.timezone=Pacific/Rarotonga -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 59.1s J1 | DistribDocExpirationUpdateProcessorTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Exactly one shard
should have changed, instead: [shard2, shard1] nodes=([core_node3(shard2),
core_node2(shard1), core_node4(shard1)]) expected:<1> but was:<2>
[junit4] > at
__randomizedtesting.SeedInfo.seed([4547B0A0B15D4AF0:CD138F7A1FA12708]:0)
[junit4] > at
org.apache.solr.cloud.DistribDocExpirationUpdateProcessorTest.test(DistribDocExpirationUpdateProcessorTest.java:118)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3620678 INFO
(SUITE-DistribDocExpirationUpdateProcessorTest-seed#[4547B0A0B15D4AF0]-worker)
[ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.DistribDocExpirationUpdateProcessorTest_4547B0A0B15D4AF0-001
[junit4] 2> Mar 06, 2016 9:40:20 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60),
sim=ClassicSimilarity, locale=zh-SG, timezone=Pacific/Rarotonga
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_66 (64-bit)/cpus=4,threads=1,free=97602488,total=525336576
[junit4] 2> NOTE: All tests run in this JVM:
[TestReplicationHandlerBackup, CollectionsAPIAsyncDistributedZkTest,
TestImplicitCoreProperties, RemoteQueryErrorTest,
OverriddenZkACLAndCredentialsProvidersTest, SolrIndexConfigTest,
BasicFunctionalityTest, TestQuerySenderListener,
UniqFieldsUpdateProcessorFactoryTest, TestSurroundQueryParser,
DistributedFacetPivotWhiteBoxTest, CSVRequestHandlerTest, JavabinLoaderTest,
TestBinaryResponseWriter, TestPartialUpdateDeduplication, BJQParserTest,
AddBlockUpdateTest, ZkSolrClientTest, CoreAdminCreateDiscoverTest,
TestLeaderElectionZkExpiry, TestManagedStopFilterFactory, TestSystemIdResolver,
InfoHandlerTest, TestMinMaxOnMultiValuedField, HighlighterTest,
DeleteShardTest, AliasIntegrationTest, TestQueryWrapperFilter,
HdfsBasicDistributedZkTest, IndexBasedSpellCheckerTest, SolrPluginUtilsTest,
ChaosMonkeySafeLeaderTest, TestCloudPivotFacet, TestMissingGroups,
ResponseHeaderTest, SolrInfoMBeanTest, TestSearcherReuse, TestFastLRUCache,
CdcrReplicationHandlerTest, TestNonDefinedSimilarityFactory,
TestDocumentBuilder, DirectoryFactoryTest, TestCursorMarkWithoutUniqueKey,
TestNRTOpen, TestCustomSort, ShardRoutingTest, TestSolrQueryResponse,
TestSolrDeletionPolicy2, TestLFUCache, SpellingQueryConverterTest,
TestReRankQParserPlugin, TestScoreJoinQPScore, LoggingHandlerTest,
TestFieldResource, HdfsCollectionsAPIDistributedZkTest, DocValuesTest,
DOMUtilTest, ChangedSchemaMergeTest, TestMiniSolrCloudClusterBase,
TestHighFrequencyDictionaryFactory, DistanceFunctionTest, ZkCLITest,
TestQueryUtils, TestQueryTypes, TestSolr4Spatial2, TestConfigSetProperties,
WordBreakSolrSpellCheckerTest, TestClassNameShortening, SpatialFilterTest,
TestSQLHandler, RollingRestartTest, RuleEngineTest,
DistributedIntervalFacetingTest, DistributedFacetPivotLongTailTest,
TermVectorComponentTest, SchemaVersionSpecificBehaviorTest,
TestCloudManagedSchema, TestDynamicFieldCollectionResource,
TestCollationFieldDocValues, MoreLikeThisHandlerTest, BufferStoreTest,
ClusterStateUpdateTest, TestExpandComponent, ChaosMonkeyNothingIsSafeTest,
TestHashPartitioner, MinimalSchemaTest, TestDistribDocBasedVersion,
TermsComponentTest, HdfsThreadLeakTest, FastVectorHighlighterTest,
DistributedMLTComponentTest, TestDFRSimilarityFactory,
DeleteInactiveReplicaTest, TestObjectReleaseTracker, TestQuerySenderNoQuery,
TestRandomRequestDistribution, ZkControllerTest, TestRealTimeGet,
TestStressVersions, DistributedTermsComponentTest, SolrCoreTest,
StatsComponentTest, ConvertedLegacyTest, TestSort, DirectUpdateHandlerTest,
TestIndexSearcher, DistributedQueryElevationComponentTest,
CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest,
SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest,
SolrRequestParserTest, TestTrie, SuggesterWFSTTest,
SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest,
QueryEqualityTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest,
DebugComponentTest, StandardRequestHandlerTest,
TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest,
XmlUpdateRequestHandlerTest, TestIndexingPerformance, TestArbitraryIndexDir,
TestJmxIntegration, MBeansHandlerTest, TestAnalyzedSuggestions,
TestComponentsName, UpdateParamsTest, AlternateDirectoryTest, BadComponentTest,
TestMergePolicyConfig, MultiTermTest, NumericFieldsTest, TestConfig,
OutputWriterTest, ExternalFileFieldSortTest, TestSolrCoreProperties,
DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestPhraseSuggestions,
SpellPossibilityIteratorTest, TestCodecSupport, TestSweetSpotSimilarityFactory,
TestPerFieldSimilarity, TestLMDirichletSimilarityFactory,
TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory,
TestBM25SimilarityFactory, TestJmxMonitoredMap, TimeZoneUtilsTest,
ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest,
PluginInfoTest, PreAnalyzedFieldTest, PrimUtilsTest,
TestSuggestSpellingConverter, TestSolrJ, TestLRUCache, TestUtils,
UUIDFieldTest, FileUtilsTest, CircularListTest, TestRTGBase,
SolrTestCaseJ4Test, TestCrossCoreJoin, TestSimpleTrackingShardHandler,
TestEmbeddedSolrServerConstructors, ActionThrottleTest, AssignTest,
AsyncMigrateRouteKeyTest, CdcrRequestHandlerTest, CleanupOldIndexTest,
CollectionReloadTest, CollectionStateFormat2Test,
CollectionTooManyReplicasTest, ConfigSetsAPITest,
DistribDocExpirationUpdateProcessorTest]
[junit4] Completed [438/583 (2!)] on J1 in 59.92s, 1 test, 1 failure <<<
FAILURES!
[...truncated 482 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:524:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014:
There were test failures: 583 suites (8 ignored), 2351 tests, 2 failures, 34
ignored (17 assumptions) [seed: 4547B0A0B15D4AF0]
Total time: 276 minutes 19 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
