Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/864/
2 tests failed.
FAILED:
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp
Error Message:
No such processor Template
Stack Trace:
org.apache.solr.common.SolrException: No such processor Template
at
__randomizedtesting.SeedInfo.seed([8471A478B8CBDACA:21C3E4B01E85133E]:0)
at
org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:286)
at
org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:235)
at
org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1413)
at
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:53)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:368)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.TestLeaderElectionWithEmptyReplica.test
Error Message:
Shard shard1 replicas do not have same number of documents expected:<10> but
was:<0>
Stack Trace:
java.lang.AssertionError: Shard shard1 replicas do not have same number of
documents expected:<10> but was:<0>
at
__randomizedtesting.SeedInfo.seed([8471A478B8CBDACA:C259BA21637B732]: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.TestLeaderElectionWithEmptyReplica.assertConsistentReplicas(TestLeaderElectionWithEmptyReplica.java:119)
at
org.apache.solr.cloud.TestLeaderElectionWithEmptyReplica.test(TestLeaderElectionWithEmptyReplica.java:101)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11091 lines...]
[junit4] Suite:
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_8471A478B8CBDACA-001/init-core-data-001
[junit4] 2> 16403 WARN
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 16404 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 16407 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 16407 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 16408 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 16442 WARN
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 16443 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 16479 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 16552 WARN
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
[solr.WordDelimiterFilterFactory]. Please consult documentation how to replace
it accordingly.
[junit4] 2> 16696 WARN
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 16700 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 16738 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 16782 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d52d12c
[junit4] 2> 16801 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d52d12c
[junit4] 2> 16801 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d52d12c
[junit4] 2> 16829 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 16914 WARN (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 16915 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 16983 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 17153 WARN (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 17157 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 17172 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 17173 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry
'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2d52d12c
[junit4] 2> 17173 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 17173 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_8471A478B8CBDACA-001/init-core-data-001/]
[junit4] 2> 17176 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=33, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=20.0888671875,
floorSegmentMB=2.078125, forceMergeDeletesPctAllowed=1.1627990964846813,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 17297 WARN (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.c.RequestHandlers no default request handler is registered (either
'/select' or 'standard')
[junit4] 2> 17301 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 17301 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 17304 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=61.4091796875,
floorSegmentMB=0.931640625, forceMergeDeletesPctAllowed=24.440600119596592,
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 17304 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@64784e7f[collection1] main]
[junit4] 2> 17306 WARN (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 17306 INFO (coreLoadExecutor-74-thread-1) [ x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 17307 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 17313 INFO
(searcherExecutor-75-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@64784e7f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 17316 INFO
(TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[8471A478B8CBDACA])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testConfiguration
[junit4] 2> 17317 INFO
(TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[8471A478B8CBDACA])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testConfiguration
[junit4] 2> 17319 INFO
(TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[8471A478B8CBDACA])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateDistribChainSkipping
[junit4] 2> 17324 INFO
(TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[8471A478B8CBDACA])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateDistribChainSkipping
[junit4] 2> 17332 INFO
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[8471A478B8CBDACA])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testRequestTimeUrp
[junit4] 2> 17332 WARN
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[8471A478B8CBDACA])
[ ] o.a.s.c.PluginBag runtime library loading is not enabled, start Solr
with -Denable.runtime.lib=true
[junit4] 2> 17333 INFO
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[8471A478B8CBDACA])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testRequestTimeUrp
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=UpdateRequestProcessorFactoryTest -Dtests.method=testRequestTimeUrp
-Dtests.seed=8471A478B8CBDACA -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=es -Dtests.timezone=Australia/Sydney -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.01s J1 |
UpdateRequestProcessorFactoryTest.testRequestTimeUrp <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: No such
processor Template
[junit4] > at
__randomizedtesting.SeedInfo.seed([8471A478B8CBDACA:21C3E4B01E85133E]:0)
[junit4] > at
org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:286)
[junit4] > at
org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:235)
[junit4] > at
org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1413)
[junit4] > at
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:53)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 17340 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 17340 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=792497234
[junit4] 2> 17340 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 17341 INFO (coreCloseExecutor-79-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@79f54a89
[junit4] 2> 17355 INFO (coreCloseExecutor-79-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 17394 INFO
(SUITE-UpdateRequestProcessorFactoryTest-seed#[8471A478B8CBDACA]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_8471A478B8CBDACA-001
[junit4] 2> May 05, 2017 7:25:23 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(Lucene62): {},
docValues:{}, maxPointsInLeafNode=1628, maxMBSortInHeap=6.018785868277279,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=es,
timezone=Australia/Sydney
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=162987368,total=305135616
[junit4] 2> NOTE: All tests run in this JVM: [SolrIndexSplitterTest,
OverseerModifyCollectionTest, TestExportWriter,
UpdateRequestProcessorFactoryTest]
[junit4] Completed [7/712 (1!)] on J1 in 1.30s, 3 tests, 1 error <<<
FAILURES!
[...truncated 1658 lines...]
[junit4] Suite: org.apache.solr.cloud.TestLeaderElectionWithEmptyReplica
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/init-core-data-001
[junit4] 2> 1567676 WARN
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
[junit4] 2> 1567676 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 1567678 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1567678 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001
[junit4] 2> 1567679 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1567679 INFO (Thread-2515) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1567679 INFO (Thread-2515) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1567681 ERROR (Thread-2515) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 1567779 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.c.ZkTestServer start zk server on port:35762
[junit4] 2> 1567785 INFO (jetty-launcher-4576-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1567786 INFO (jetty-launcher-4576-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1567795 INFO (jetty-launcher-4576-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2d9ddd94{/solr,null,AVAILABLE}
[junit4] 2> 1567795 INFO (jetty-launcher-4576-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@999aad2{/solr,null,AVAILABLE}
[junit4] 2> 1567799 INFO (jetty-launcher-4576-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6044d7ff{HTTP/1.1,[http/1.1]}{127.0.0.1:47981}
[junit4] 2> 1567799 INFO (jetty-launcher-4576-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5c5da831{HTTP/1.1,[http/1.1]}{127.0.0.1:49785}
[junit4] 2> 1567799 INFO (jetty-launcher-4576-thread-2) [ ]
o.e.j.s.Server Started @1570981ms
[junit4] 2> 1567799 INFO (jetty-launcher-4576-thread-1) [ ]
o.e.j.s.Server Started @1570981ms
[junit4] 2> 1567799 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47981}
[junit4] 2> 1567799 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=49785}
[junit4] 2> 1567799 ERROR (jetty-launcher-4576-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1567799 ERROR (jetty-launcher-4576-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.0
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.0
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-05-05T07:51:13.706Z
[junit4] 2> 1567800 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-05-05T07:51:13.706Z
[junit4] 2> 1567803 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1567804 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1567812 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1567813 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1567814 INFO (jetty-launcher-4576-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35762/solr
[junit4] 2> 1567814 INFO (jetty-launcher-4576-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35762/solr
[junit4] 2> 1567818 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15bd7975fb20003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1567841 INFO (jetty-launcher-4576-thread-1)
[n:127.0.0.1:49785_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1567842 INFO (jetty-launcher-4576-thread-1)
[n:127.0.0.1:49785_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49785_solr
[junit4] 2> 1567843 INFO (jetty-launcher-4576-thread-1)
[n:127.0.0.1:49785_solr ] o.a.s.c.Overseer Overseer
(id=97908862064132102-127.0.0.1:49785_solr-n_0000000000) starting
[junit4] 2> 1567845 INFO (jetty-launcher-4576-thread-2)
[n:127.0.0.1:47981_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1567846 INFO (jetty-launcher-4576-thread-2)
[n:127.0.0.1:47981_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47981_solr
[junit4] 2> 1567857 INFO
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1567857 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1567866 INFO (jetty-launcher-4576-thread-1)
[n:127.0.0.1:49785_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49785_solr
[junit4] 2> 1567868 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1567868 INFO
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1567931 INFO (jetty-launcher-4576-thread-1)
[n:127.0.0.1:49785_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node1/.
[junit4] 2> 1567961 INFO (jetty-launcher-4576-thread-2)
[n:127.0.0.1:47981_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node2/.
[junit4] 2> 1569266 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1569266 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35762/solr ready
[junit4] 2> 1569273 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=815cd9fd-8395-47d1-95fd-0fbded81d303&replicationFactor=1&collection.configName=config&name=solr_9504&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1569274 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=815cd9fd-8395-47d1-95fd-0fbded81d303&replicationFactor=1&collection.configName=config&name=solr_9504&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1569276 INFO (qtp1588003981-18487) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1569276 INFO
(OverseerThreadFactory-5563-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.CreateCollectionCmd Create collection
solr_9504
[junit4] 2> 1569277 INFO (qtp1588003981-18487) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1569386 INFO (qtp1980965480-18502) [n:127.0.0.1:47981_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127&qt=/admin/cores&collection.configName=config&newCollection=true&name=solr_9504_shard1_replica1&action=CREATE&numShards=1&collection=solr_9504&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1569386 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127&qt=/admin/cores&collection.configName=config&newCollection=true&name=solr_9504_shard1_replica1&action=CREATE&numShards=1&collection=solr_9504&shard=shard1&wt=javabin&version=2
[junit4] 2> 1569386 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1569388 INFO (qtp1980965480-18503) [n:127.0.0.1:47981_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127
[junit4] 2> 1569388 INFO (qtp1980965480-18503) [n:127.0.0.1:47981_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1569491 INFO
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1570279 INFO (qtp1588003981-18490) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1570280 INFO (qtp1588003981-18490) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1570390 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127
[junit4] 2> 1570390 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1570402 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 1570411 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.s.IndexSchema [solr_9504_shard1_replica1] Schema name=minimal
[junit4] 2> 1570414 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1570414 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'solr_9504_shard1_replica1' using
configuration from collection solr_9504, trusted=true
[junit4] 2> 1570415 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1570415 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.SolrCore [[solr_9504_shard1_replica1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node2/solr_9504_shard1_replica1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node2/./solr_9504_shard1_replica1/data/]
[junit4] 2> 1570479 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1570479 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1570480 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1570480 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1570482 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6f4bb1d[solr_9504_shard1_replica1]
main]
[junit4] 2> 1570483 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/config
[junit4] 2> 1570484 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1570484 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1570484 INFO
(searcherExecutor-5568-thread-1-processing-x:solr_9504_shard1_replica1
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE
n:127.0.0.1:47981_solr s:shard1 c:solr_9504) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 x:solr_9504_shard1_replica1] o.a.s.c.SolrCore
[solr_9504_shard1_replica1] Registered new searcher
Searcher@6f4bb1d[solr_9504_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1570484 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1566541795966320640
[junit4] 2> 1570494 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1570494 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1570495 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:47981/solr/solr_9504_shard1_replica1/
[junit4] 2> 1570495 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1570495 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.SyncStrategy http://127.0.0.1:47981/solr/solr_9504_shard1_replica1/ has
no replicas
[junit4] 2> 1570495 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1570500 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:47981/solr/solr_9504_shard1_replica1/ shard1
[junit4] 2> 1570604 INFO
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1570652 INFO
(parallelCoreAdminExecutor-5566-thread-1-processing-n:127.0.0.1:47981_solr
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127 CREATE)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1570758 INFO
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1571283 INFO (qtp1588003981-18493) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1571284 INFO (qtp1588003981-18493) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1571393 INFO (qtp1980965480-18501) [n:127.0.0.1:47981_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127
[junit4] 2> 1571393 INFO (qtp1980965480-18501) [n:127.0.0.1:47981_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=815cd9fd-8395-47d1-95fd-0fbded81d303841147742114127&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1571396 INFO
(OverseerThreadFactory-5563-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1572286 INFO (qtp1588003981-18492) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1572288 INFO (qtp1588003981-18492) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1572289 INFO (qtp1588003981-18486) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with
params
requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=DELETESTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1572290 INFO (qtp1588003981-18486) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=815cd9fd-8395-47d1-95fd-0fbded81d303&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1572313 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1572318 INFO (qtp1980965480-18497) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[0 (1566541797886263296)]} 0 3
[junit4] 2> 1572320 INFO (qtp1980965480-18498) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[1 (1566541797891506176)]} 0 0
[junit4] 2> 1572322 INFO (qtp1980965480-18502) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[2 (1566541797893603328)]} 0 0
[junit4] 2> 1572324 INFO (qtp1980965480-18503) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[3 (1566541797895700480)]} 0 0
[junit4] 2> 1572326 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[4 (1566541797897797632)]} 0 0
[junit4] 2> 1572328 INFO (qtp1980965480-18501) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[5 (1566541797899894784)]} 0 0
[junit4] 2> 1572330 INFO (qtp1980965480-18497) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[6 (1566541797901991936)]} 0 0
[junit4] 2> 1572332 INFO (qtp1980965480-18498) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[7 (1566541797904089088)]} 0 0
[junit4] 2> 1572334 INFO (qtp1980965480-18502) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[8 (1566541797906186240)]} 0 0
[junit4] 2> 1572336 INFO (qtp1980965480-18503) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[9 (1566541797908283392)]} 0 0
[junit4] 2> 1572338 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1572339 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4ec9d941
[junit4] 2> 1572375 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@101829fb[solr_9504_shard1_replica1]
main]
[junit4] 2> 1572376 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1572376 INFO
(searcherExecutor-5568-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrCore [solr_9504_shard1_replica1]
Registered new searcher Searcher@101829fb[solr_9504_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C10)))}
[junit4] 2> 1572376 INFO (qtp1980965480-18496) [n:127.0.0.1:47981_solr
c:solr_9504 s:shard1 r:core_node1 x:solr_9504_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [solr_9504_shard1_replica1] webapp=/solr
path=/update
params={_stateVer_=solr_9504:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 37
[junit4] 2> 1572377 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.c.ChaosMonkey monkey: kill shard! 47981
[junit4] 2> 1572378 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@6044d7ff{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1572379 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=868456698
[junit4] 2> 1572381 INFO (coreCloseExecutor-5573-thread-1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrCore [solr_9504_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@52fc0fe6
[junit4] 2> 1572383 INFO (coreCloseExecutor-5573-thread-1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric reporters
for: solr.core.solr_9504.shard1.replica1
[junit4] 2> 1572383 INFO
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1572392 INFO (zkCallback-4594-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1572392 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 1573279 INFO
(OverseerCollectionConfigSetProcessor-97908862064132102-127.0.0.1:49785_solr-n_0000000000)
[n:127.0.0.1:49785_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1573892 WARN
(zkCallback-4587-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1573893 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1573894 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@999aad2{/solr,null,UNAVAILABLE}
[junit4] 2> 1573895 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.c.ChaosMonkey monkey: stop shard! 47981
[junit4] 2> 1573896 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
async=ec66e93c-4aca-4371-b082-48013a3f5849&action=ADDREPLICA&collection=solr_9504&shard=shard1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1573898 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=ec66e93c-4aca-4371-b082-48013a3f5849&action=ADDREPLICA&collection=solr_9504&shard=shard1&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1573901 INFO
(OverseerThreadFactory-5563-thread-2-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.AddReplicaCmd addReplica() : {
[junit4] 2> "collection":"solr_9504",
[junit4] 2> "shard":"shard1",
[junit4] 2> "async":"ec66e93c-4aca-4371-b082-48013a3f5849",
[junit4] 2> "operation":"addreplica"}
[junit4] 2> 1573902 INFO
(OverseerThreadFactory-5563-thread-2-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:49785_solr for creating new replica
[junit4] 2> 1573905 INFO (qtp1588003981-18490) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=ec66e93c-4aca-4371-b082-48013a3f5849841152262437802&qt=/admin/cores&collection.configName=config&name=solr_9504_shard1_replica2&action=CREATE&collection=solr_9504&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1573905 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=ec66e93c-4aca-4371-b082-48013a3f5849841152262437802&qt=/admin/cores&collection.configName=config&name=solr_9504_shard1_replica2&action=CREATE&collection=solr_9504&shard=shard1&wt=javabin&version=2
[junit4] 2> 1573905 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1573906 INFO (qtp1588003981-18493) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802
[junit4] 2> 1573907 INFO (qtp1588003981-18493) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=ec66e93c-4aca-4371-b082-48013a3f5849841152262437802&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1573908 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [1])
[junit4] 2> 1574900 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1574903 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@22ff300d{/solr,null,AVAILABLE}
[junit4] 2> 1574904 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3363b1b{HTTP/1.1,[http/1.1]}{127.0.0.1:47981}
[junit4] 2> 1574904 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.e.j.s.Server Started @1578086ms
[junit4] 2> 1574904 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47981}
[junit4] 2> 1574904 ERROR
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1574905 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.6.0
[junit4] 2> 1574906 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1574906 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1574906 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-05-05T07:51:20.812Z
[junit4] 2> 1574909 INFO (qtp1588003981-18492) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802
[junit4] 2> 1574909 INFO (qtp1588003981-18492) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=ec66e93c-4aca-4371-b082-48013a3f5849841152262437802&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1574909 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1574918 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1574919 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 1574919 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35762/solr
[junit4] 2> 1574929 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.s.IndexSchema [solr_9504_shard1_replica2] Schema name=minimal
[junit4] 2> 1574930 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA])
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1574933 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA])
[n:127.0.0.1:47981_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1574933 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1574934 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.CoreContainer Creating SolrCore 'solr_9504_shard1_replica2' using
configuration from collection solr_9504, trusted=true
[junit4] 2> 1574934 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1574934 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.SolrCore [[solr_9504_shard1_replica2] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node1/solr_9504_shard1_replica2],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node1/./solr_9504_shard1_replica2/data/]
[junit4] 2> 1574935 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA])
[n:127.0.0.1:47981_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47981_solr
[junit4] 2> 1574937 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1574938 INFO
(zkCallback-4599-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1574938 INFO (zkCallback-4594-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1574997 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1574997 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1574998 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1574998 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1575000 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening [Searcher@51e6cb8c[solr_9504_shard1_replica2]
main]
[junit4] 2> 1575001 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/config
[junit4] 2> 1575001 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1575002 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1575002 INFO
(searcherExecutor-5579-thread-1-processing-x:solr_9504_shard1_replica2
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE
n:127.0.0.1:49785_solr s:shard1 c:solr_9504) [n:127.0.0.1:49785_solr
c:solr_9504 s:shard1 x:solr_9504_shard1_replica2] o.a.s.c.SolrCore
[solr_9504_shard1_replica2] Registered new searcher
Searcher@51e6cb8c[solr_9504_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1575002 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1566541800703787008
[junit4] 2> 1575008 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1575009 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA])
[n:127.0.0.1:47981_solr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node2/.
[junit4] 2> 1575009 INFO
(TEST-TestLeaderElectionWithEmptyReplica.test-seed#[8471A478B8CBDACA])
[n:127.0.0.1:47981_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[solr_9504_shard1_replica1]
[junit4] 2> 1575010 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1575025 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 1575051 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.s.IndexSchema [solr_9504_shard1_replica1]
Schema name=minimal
[junit4] 2> 1575055 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 1575055 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore
'solr_9504_shard1_replica1' using configuration from collection solr_9504,
trusted=true
[junit4] 2> 1575055 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1575055 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrCore [[solr_9504_shard1_replica1] ]
Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node2/solr_9504_shard1_replica1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001/tempDir-001/node2/./solr_9504_shard1_replica1/data/]
[junit4] 2> 1575104 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1575104 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 1575106 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1575106 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1575109 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@16b3614e[solr_9504_shard1_replica1] main]
[junit4] 2> 1575111 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1575111 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path
_rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1575111 INFO
(coreLoadExecutor-5585-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 1575111 INFO
(searcherExecutor-5586-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrCore [solr_9504_shard1_replica1]
Registered new searcher Searcher@16b3614e[solr_9504_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C10)))}
[junit4] 2> 1575116 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1575116 INFO
(zkCallback-4599-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1575508 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1575508 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1575508 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:49785/solr/solr_9504_shard1_replica2/
[junit4] 2> 1575509 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.PeerSync PeerSync: core=solr_9504_shard1_replica2
url=http://127.0.0.1:49785/solr START
replicas=[http://127.0.0.1:47981/solr/solr_9504_shard1_replica1/] nUpdates=100
[junit4] 2> 1575509 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.u.PeerSync PeerSync: core=solr_9504_shard1_replica2
url=http://127.0.0.1:49785/solr DONE. We have no versions. sync failed.
[junit4] 2> 1575512 ERROR (qtp944578022-18560) [ ]
o.a.s.s.SolrDispatchFilter Error processing the request. CoreContainer is
either not initialized or shutting down.
[junit4] 2> 1575512 WARN (qtp944578022-18560) [ ]
o.e.j.s.ServletHandler /solr/solr_9504_shard1_replica1/get
[junit4] 2> org.apache.solr.common.SolrException: Error processing the
request. CoreContainer is either not initialized or shutting down.
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:311)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:301)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1575514 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 1575514 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1575514 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1575517 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:49785/solr/solr_9504_shard1_replica2/ shard1
[junit4] 2> 1575620 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1575620 INFO
(zkCallback-4599-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1575668 INFO
(parallelCoreAdminExecutor-5564-thread-1-processing-n:127.0.0.1:49785_solr
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802 CREATE)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 x:solr_9504_shard1_replica2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1575774 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1575774 INFO
(zkCallback-4599-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1575911 INFO (qtp1588003981-18486) [n:127.0.0.1:49785_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
ec66e93c-4aca-4371-b082-48013a3f5849841152262437802
[junit4] 2> 1575911 INFO (qtp1588003981-18486) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=ec66e93c-4aca-4371-b082-48013a3f5849841152262437802&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1575915 INFO
(OverseerThreadFactory-5563-thread-2-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1576122 INFO
(coreZkRegister-5575-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.ZkController Core needs to
recover:solr_9504_shard1_replica1
[junit4] 2> 1576122 INFO
(updateExecutor-4596-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1576123 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 1576124 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.RecoveryStrategy ######
startupVersions=[[1566541797908283392, 1566541797906186240,
1566541797904089088, 1566541797901991936, 1566541797899894784,
1566541797897797632, 1566541797895700480, 1566541797893603328,
1566541797891506176, 1566541797886263296]]
[junit4] 2> 1576124 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[solr_9504_shard1_replica1]
[junit4] 2> 1576124 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1576124 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.RecoveryStrategy Publishing state of core
[solr_9504_shard1_replica1] as recovering, leader is
[http://127.0.0.1:49785/solr/solr_9504_shard1_replica2/] and I am
[http://127.0.0.1:47981/solr/solr_9504_shard1_replica1/]
[junit4] 2> 1576128 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:49785/solr]; [WaitForState:
action=PREPRECOVERY&core=solr_9504_shard1_replica2&nodeName=127.0.0.1:47981_solr&coreNodeName=core_node1&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1576130 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node1, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1576130 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see
solr_9504_shard1_replica2 (shard1 of solr_9504) have state: recovering
[junit4] 2> 1576131 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=solr_9504,
shard=shard1, thisCore=solr_9504_shard1_replica2,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:47981_solr,
coreNodeName=core_node1, onlyIfActiveCheckResult=false, nodeProps:
core_node1:{"core":"solr_9504_shard1_replica1","base_url":"http://127.0.0.1:47981/solr","node_name":"127.0.0.1:47981_solr","state":"down"}
[junit4] 2> 1576233 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1576233 INFO (zkCallback-4594-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1576233 INFO
(zkCallback-4599-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/solr_9504/state.json] for collection [solr_9504] has occurred
- updating... (live nodes size: [2])
[junit4] 2> 1577131 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=solr_9504,
shard=shard1, thisCore=solr_9504_shard1_replica2,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:47981_solr,
coreNodeName=core_node1, onlyIfActiveCheckResult=false, nodeProps:
core_node1:{"core":"solr_9504_shard1_replica1","base_url":"http://127.0.0.1:47981/solr","node_name":"127.0.0.1:47981_solr","state":"recovering"}
[junit4] 2> 1577131 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node1, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1577131 INFO (qtp1588003981-18491) [n:127.0.0.1:49785_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:47981_solr&onlyIfLeaderActive=true&core=solr_9504_shard1_replica2&coreNodeName=core_node1&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1001
[junit4] 2> 1577632 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.RecoveryStrategy Attempting to PeerSync
from [http://127.0.0.1:49785/solr/solr_9504_shard1_replica2/] -
recoveringAfterStartup=[true]
[junit4] 2> 1577632 INFO
(recoveryExecutor-4597-thread-1-processing-n:127.0.0.1:47981_solr
x:solr_9504_shard1_replica1 s:shard1 c:solr_9504 r:core_node1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.u.PeerSync PeerSync:
core=solr_9504_shard1_replica1 url=http://127.0.0.1:47981/solr START
replicas=[http://127.0.0.1:49785/solr/solr_9504_shard1_replica2/] nUpdates=100
[junit4] 2> 1577637 INFO (qtp1588003981-18490) [n:127.0.0.1:49785_solr
c:solr_9504 s:shard1 r:core_node2 x:solr_9504_shard1_replica2]
o.a.s.u.IndexFingerprint IndexFingerprint millis:1.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1577638 INFO (qtp1588003981-18490) [n:127.0.0.1:49785_solr
c:solr_9504 s:shard1 r:core_node2 x:sol
[...truncated too long message...]
> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1577771 INFO (jetty-closer-4577-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3363b1b{HTTP/1.1,[http/1.1]}{127.0.0.1:47981}
[junit4] 2> 1577773 INFO (jetty-closer-4577-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2080532739
[junit4] 2> 1577774 INFO (jetty-closer-4577-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@5c5da831{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1577775 INFO (jetty-closer-4577-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=324814158
[junit4] 2> 1577779 INFO (coreCloseExecutor-5591-thread-1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.c.SolrCore [solr_9504_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@5895711f
[junit4] 2> 1577781 INFO (coreCloseExecutor-5592-thread-1)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 r:core_node2
x:solr_9504_shard1_replica2] o.a.s.c.SolrCore [solr_9504_shard1_replica2]
CLOSING SolrCore org.apache.solr.core.SolrCore@325d5f82
[junit4] 2> 1577786 INFO (coreCloseExecutor-5592-thread-1)
[n:127.0.0.1:49785_solr c:solr_9504 s:shard1 r:core_node2
x:solr_9504_shard1_replica2] o.a.s.m.SolrMetricManager Closing metric reporters
for: solr.core.solr_9504.shard1.replica2
[junit4] 2> 1577786 INFO (coreCloseExecutor-5591-thread-1)
[n:127.0.0.1:47981_solr c:solr_9504 s:shard1 r:core_node1
x:solr_9504_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric reporters
for: solr.core.solr_9504.shard1.replica1
[junit4] 2> 1577787 INFO (jetty-closer-4577-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=97908862064132102-127.0.0.1:49785_solr-n_0000000000) closing
[junit4] 2> 1577787 INFO
(OverseerStateUpdate-97908862064132102-127.0.0.1:49785_solr-n_0000000000)
[n:127.0.0.1:49785_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:49785_solr
[junit4] 2> 1577788 WARN
(zkCallback-4599-thread-1-processing-n:127.0.0.1:47981_solr)
[n:127.0.0.1:47981_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1577788 INFO (jetty-closer-4577-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1577789 INFO (jetty-closer-4577-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@22ff300d{/solr,null,UNAVAILABLE}
[junit4] 2> 1577789 INFO
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 1579291 WARN
(zkCallback-4588-thread-1-processing-n:127.0.0.1:49785_solr)
[n:127.0.0.1:49785_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1579292 INFO (jetty-closer-4577-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1579292 INFO (jetty-closer-4577-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2d9ddd94{/solr,null,UNAVAILABLE}
[junit4] 2> 1579293 ERROR
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1579294 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35762 35762
[junit4] 2> 1579472 INFO (Thread-2515) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35762 35762
[junit4] 2> 1579473 WARN (Thread-2515) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/config
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/solr_9504/state.json
[junit4] 2> 2
/solr/overseer_elect/election/97908862064132102-127.0.0.1:49785_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 1579473 INFO
(SUITE-TestLeaderElectionWithEmptyReplica-seed#[8471A478B8CBDACA]-worker) [
] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestLeaderElectionWithEmptyReplica_8471A478B8CBDACA-001
[junit4] 2> May 05, 2017 7:51:25 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=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=4, maxDocsPerChunk=1, blockSize=544),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=4, blockSize=544)),
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=vi-VN,
timezone=Asia/Aqtobe
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=207396440,total=530055168
[junit4] 2> NOTE: All tests run in this JVM:
[LeaderElectionContextKeyTest, HdfsSyncSliceTest, TestRawResponseWriter,
TestSolrCloudSnapshots, TestCustomStream, TestBinaryResponseWriter,
BlockCacheTest, TestStressLiveNodes, TestCloudSchemaless, DateRangeFieldTest,
TestSerializedLuceneMatchVersion, SuggestComponentTest, TestCryptoKeys,
TestFunctionQuery, SignatureUpdateProcessorFactoryTest,
DistributedSpellCheckComponentTest, CdcrReplicationHandlerTest,
TestChildDocTransformer, TestRandomCollapseQParserPlugin,
TestAtomicUpdateErrorCases, FastVectorHighlighterTest, AliasIntegrationTest,
TestFieldCacheWithThreads, TestOmitPositions, DeleteStatusTest,
HdfsUnloadDistributedZkTest, TestSQLHandler, SolrGraphiteReporterTest,
NumericFieldsTest, TestSchemaResource, AnalyticsQueryTest,
TestSubQueryTransformerDistrib, TlogReplayBufferedWhileIndexingTest,
TestDocumentBuilder, TestDocSet, LeaderInitiatedRecoveryOnCommitTest,
ConfigSetsAPITest, SolrRequestParserTest, TestUniqueKeyFieldResource,
BlockJoinFacetSimpleTest, BaseCdcrDistributedZkTest, TestNumericTerms64,
BigEndianAscendingWordSerializerTest, TestCloudJSONFacetJoinDomain,
TestRemoteStreaming, TestRestManager,
AddSchemaFieldsUpdateProcessorFactoryTest, TestDownShardTolerantSearch,
TestIBSimilarityFactory, TestCloudRecovery, JSONWriterTest, InfoHandlerTest,
HttpSolrCallGetCoreTest, BJQParserTest, TestRandomFaceting,
DisMaxRequestHandlerTest, ManagedSchemaRoundRobinCloudTest,
LeaderElectionIntegrationTest, TestSolrConfigHandlerConcurrent,
BasicAuthStandaloneTest, SparseHLLTest, TestStressUserVersions, TestRangeQuery,
TestComplexPhraseQParserPlugin, TestRealTimeGet, TestConfigSetProperties,
MergeStrategyTest, DocValuesNotIndexedTest, QueryElevationComponentTest,
TestInPlaceUpdatesStandalone, SpellCheckCollatorWithCollapseTest,
SSLMigrationTest, CacheHeaderTest, TestFoldingMultitermQuery,
PeerSyncWithIndexFingerprintCachingTest, TestFilteredDocIdSet,
DocumentAnalysisRequestHandlerTest, TestBackupRepositoryFactory,
DistributedQueryElevationComponentTest, PathHierarchyTokenizerFactoryTest,
ExitableDirectoryReaderTest, TestGraphMLResponseWriter, BadCopyFieldTest,
DeleteNodeTest, FacetPivotSmallTest, NodeMutatorTest,
ParsingFieldUpdateProcessorsTest, TestStressLucene, TestInfoStreamLogging,
UUIDUpdateProcessorFallbackTest, CoreAdminCreateDiscoverTest,
TestCrossCoreJoin, QueryEqualityTest, TestPKIAuthenticationPlugin,
AlternateDirectoryTest, TestPointFields, TestCSVLoader, UUIDFieldTest,
TestBadConfig, DateFieldTest, ReplicationFactorTest, BasicZkTest,
TestSolrCoreProperties, TestDistributedSearch, TestFieldCache,
OverseerCollectionConfigSetProcessorTest, ZkNodePropsTest, SolrCLIZkUtilsTest,
MBeansHandlerTest, TestConfigSetsAPI, TestAddFieldRealTimeGet,
CollectionReloadTest, BasicDistributedZkTest,
DistributedQueryComponentCustomSortTest, TestHighlightDedupGrouping,
ZkSolrClientTest, ZkCLITest, TestFaceting, TestHashPartitioner,
TestStressReorder, TestStressVersions, HardAutoCommitTest, TestCoreContainer,
SolrCoreTest, SolrCmdDistributorTest, BadIndexSchemaTest, TestFiltering,
SoftAutoCommitTest, FileBasedSpellCheckerTest, TermVectorComponentTest,
RequiredFieldsTest, TestSolrQueryParser, SolrPluginUtilsTest,
TestJmxIntegration, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest,
TestPartialUpdateDeduplication, PingRequestHandlerTest,
TestSolrDeletionPolicy2, SampleTest, MinimalSchemaTest, TestConfig,
OutputWriterTest, ExternalFileFieldSortTest, DirectSolrConnectionTest,
NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions,
TestCharFilters, TestCodecSupport, TestSweetSpotSimilarityFactory,
TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, TestFastWriter,
TimeZoneUtilsTest, ResourceLoaderTest, OpenExchangeRatesOrgProviderTest,
TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest,
CdcrVersionReplicationTest, ConcurrentDeleteAndCreateCollectionTest,
CustomCollectionTest, DeleteLastCustomShardedReplicaTest,
DistribJoinFromCollectionTest, ForceLeaderTest,
SimpleCollectionCreateDeleteTest, TestCollectionAPI,
TestLeaderElectionWithEmptyReplica]
[junit4] Completed [529/712 (2!)] on J0 in 12.57s, 1 test, 1 failure <<<
FAILURES!
[...truncated 44050 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
