Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20479/
Java: 32bit/jdk-9-ea+181 -client -XX:+UseSerialGC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test
Error Message:
This doc was supposed to have been deleted, but was: SolrDocument{id=0,
title_s=title0, id_i=0, inplace_updatable_float=1.0,
_version_=1578660790564552704, inplace_updatable_int_with_default=666,
inplace_updatable_float_with_default=42.0}
Stack Trace:
java.lang.AssertionError: This doc was supposed to have been deleted, but was:
SolrDocument{id=0, title_s=title0, id_i=0, inplace_updatable_float=1.0,
_version_=1578660790564552704, inplace_updatable_int_with_default=666,
inplace_updatable_float_with_default=42.0}
at
__randomizedtesting.SeedInfo.seed([2AE838B9B8D8DF24:A2BC07631624B2DC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at
org.apache.solr.update.TestInPlaceUpdatesDistrib.reorderedDBQsSimpleTest(TestInPlaceUpdatesDistrib.java:247)
at
org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpdatesDistrib.java:151)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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
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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12347 lines...]
[junit4] Suite: org.apache.solr.update.TestInPlaceUpdatesDistrib
[junit4] 2> 1364438 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/init-core-data-001
[junit4] 2> 1364439 WARN
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 1364439 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1364439 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1364440 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/
[junit4] 2> 1364440 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1364440 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1364456 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1364463 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=inplace-updates
[junit4] 2> 1364465 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 1364492 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364501 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364501 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364503 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1364515 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1364521 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=inplace-updates
[junit4] 2> 1364530 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 1364530 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 1364530 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry
'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364531 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1364531 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/init-core-data-001/]
[junit4] 2> 1364533 WARN (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1364553 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1364553 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1364553 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1364553 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1364554 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ce52d2[collection1] main]
[junit4] 2> 1364554 WARN (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 1364555 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1364555 INFO (searcherExecutor-4479-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1ce52d2[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1364555 INFO (coreLoadExecutor-4478-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1578660589602865152
[junit4] 2> 1364558 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1364560 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1364560 INFO (Thread-2723) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1364560 INFO (Thread-2723) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1364565 ERROR (Thread-2723) [ ] 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> 1364660 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkTestServer start zk server on port:33221
[junit4] 2> 1364665 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1364665 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema-inplace-updates.xml
to /configs/conf1/schema.xml
[junit4] 2> 1364666 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1364666 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1364667 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1364667 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1364667 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1364668 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1364668 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1364668 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1364669 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1364669 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly
asked otherwise
[junit4] 2> 1364751 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1364751 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4adce6{/_,null,AVAILABLE}
[junit4] 2> 1364751 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1863f11{HTTP/1.1,[http/1.1]}{127.0.0.1:46451}
[junit4] 2> 1364752 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server Started @1366089ms
[junit4] 2> 1364752 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/tempDir-001/control/data,
hostPort=46451,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/control-001/cores,
replicaType=NRT}
[junit4] 2> 1364752 ERROR
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1364752 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1364752 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1364752 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1364752 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-16T02:14:18.824440Z
[junit4] 2> 1364754 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1364754 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/control-001/solr.xml
[junit4] 2> 1364767 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1364770 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33221/solr
[junit4] 2> 1364801 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1364801 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46451__
[junit4] 2> 1364802 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.Overseer Overseer (id=98666286850572292-127.0.0.1:46451__-n_0000000000)
starting
[junit4] 2> 1364804 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46451__
[junit4] 2> 1364804 INFO (zkCallback-3085-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1364849 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364857 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364857 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1364859 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/control-001/cores
[junit4] 2> 1364877 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1364878 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33221/solr ready
[junit4] 2> 1364879 INFO (qtp941255-12587) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46451__&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1364881 INFO (OverseerThreadFactory-4489-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1364983 INFO (qtp941255-12589) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1364984 INFO (qtp941255-12589) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1365086 INFO (zkCallback-3085-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1365995 INFO (qtp941255-12589) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1366004 INFO (qtp941255-12589) [ ] o.a.s.s.IndexSchema
[control_collection_shard1_replica_n1] Schema name=inplace-updates
[junit4] 2> 1366006 INFO (qtp941255-12589) [ ] o.a.s.s.IndexSchema
Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 1366006 INFO (qtp941255-12589) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'control_collection_shard1_replica_n1' using configuration
from collection control_collection, trusted=true
[junit4] 2> 1366006 INFO (qtp941255-12589) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1366006 INFO (qtp941255-12589) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1366006 INFO (qtp941255-12589) [ ] o.a.s.c.SolrCore
[[control_collection_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1366011 WARN (qtp941255-12589) [ ] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 1366038 INFO (qtp941255-12589) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1366038 INFO (qtp941255-12589) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1366055 INFO (qtp941255-12589) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1366055 INFO (qtp941255-12589) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1366056 INFO (qtp941255-12589) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1546fd7[control_collection_shard1_replica_n1] main]
[junit4] 2> 1366057 INFO (qtp941255-12589) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1366057 INFO (qtp941255-12589) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1366057 INFO (qtp941255-12589) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1366058 INFO (searcherExecutor-4492-thread-1) [ ]
o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher
Searcher@1546fd7[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1366058 INFO (qtp941255-12589) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1578660591178874880
[junit4] 2> 1366061 INFO (qtp941255-12589) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1366061 INFO (qtp941255-12589) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1366061 INFO (qtp941255-12589) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:46451/_/control_collection_shard1_replica_n1/
[junit4] 2> 1366061 INFO (qtp941255-12589) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1366061 INFO (qtp941255-12589) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:46451/_/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1366061 INFO (qtp941255-12589) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1366062 INFO (qtp941255-12589) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:46451/_/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1366163 INFO (zkCallback-3085-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1366212 INFO (qtp941255-12589) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1366213 INFO (qtp941255-12589) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1229
[junit4] 2> 1366214 INFO (qtp941255-12587) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1366314 INFO (zkCallback-3085-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1366881 INFO
(OverseerCollectionConfigSetProcessor-98666286850572292-127.0.0.1:46451__-n_0000000000)
[ ] 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> 1367214 INFO (qtp941255-12587) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46451__&wt=javabin&version=2}
status=0 QTime=2335
[junit4] 2> 1367218 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1367219 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33221/solr ready
[junit4] 2> 1367219 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1367220 INFO (qtp941255-12589) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1367221 INFO (OverseerThreadFactory-4489-thread-2) [ ]
o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1367222 WARN (OverseerThreadFactory-4489-thread-2) [ ]
o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1)
without cores.
[junit4] 2> 1367425 INFO (qtp941255-12589) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1367425 INFO (qtp941255-12589) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2}
status=0 QTime=205
[junit4] 2> 1367521 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-1-001
of type TLOG
[junit4] 2> 1367521 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1367522 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1fe5aab{/_,null,AVAILABLE}
[junit4] 2> 1367522 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1ae2aa4{HTTP/1.1,[http/1.1]}{127.0.0.1:43567}
[junit4] 2> 1367523 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server Started @1368860ms
[junit4] 2> 1367523 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/tempDir-001/jetty1,
hostPort=43567,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-1-001/cores,
replicaType=TLOG}
[junit4] 2> 1367523 ERROR
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1367523 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1367523 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1367523 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1367523 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-16T02:14:21.595592Z
[junit4] 2> 1367525 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1367525 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-1-001/solr.xml
[junit4] 2> 1367526 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1367528 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33221/solr
[junit4] 2> 1367533 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1367533 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1367534 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43567__
[junit4] 2> 1367534 INFO (zkCallback-3092-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1367535 INFO (zkCallback-3097-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1367534 INFO (zkCallback-3085-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1367642 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1367650 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1367650 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1367651 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-1-001/cores
[junit4] 2> 1367688 INFO (qtp21004830-12628) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:43567__&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1367689 INFO (OverseerThreadFactory-4489-thread-3) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:43567__ for creating new replica
[junit4] 2> 1367689 INFO
(OverseerCollectionConfigSetProcessor-98666286850572292-127.0.0.1:46451__-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1367690 INFO (qtp21004830-12630) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t21&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 1367691 INFO (qtp21004830-12630) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1368705 INFO (qtp21004830-12630) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1368716 INFO (qtp21004830-12630) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_t21] Schema name=inplace-updates
[junit4] 2> 1368719 INFO (qtp21004830-12630) [ ] o.a.s.s.IndexSchema
Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 1368719 INFO (qtp21004830-12630) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t21' using configuration from
collection collection1, trusted=true
[junit4] 2> 1368720 INFO (qtp21004830-12630) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_t21' (registry
'solr.core.collection1.shard1.replica_t21') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1368720 INFO (qtp21004830-12630) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1368720 INFO (qtp21004830-12630) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_t21] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-1-001/cores/collection1_shard1_replica_t21],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-1-001/cores/collection1_shard1_replica_t21/data/]
[junit4] 2> 1368726 WARN (qtp21004830-12630) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1368765 INFO (qtp21004830-12630) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1368765 INFO (qtp21004830-12630) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1368765 INFO (qtp21004830-12630) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1368765 INFO (qtp21004830-12630) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1368766 INFO (qtp21004830-12630) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@9b1300[collection1_shard1_replica_t21] main]
[junit4] 2> 1368768 INFO (qtp21004830-12630) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1368768 INFO (qtp21004830-12630) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1368768 INFO (qtp21004830-12630) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1368769 INFO (searcherExecutor-4503-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_t21] Registered new searcher
Searcher@9b1300[collection1_shard1_replica_t21]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1368769 INFO (qtp21004830-12630) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1578660594021564416
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:43567/_/collection1_shard1_replica_t21/
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:43567/_/collection1_shard1_replica_t21/ has no replicas
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1368772 INFO (qtp21004830-12630) [ ] o.a.s.c.ZkController
collection1_shard1_replica_t21 stopping background replication from leader
[junit4] 2> 1368773 INFO (qtp21004830-12630) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43567/_/collection1_shard1_replica_t21/ shard1
[junit4] 2> 1368924 INFO (qtp21004830-12630) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1368925 INFO (qtp21004830-12630) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t21&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=1234
[junit4] 2> 1368927 INFO (qtp21004830-12628) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43567__&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
status=0 QTime=1238
[junit4] 2> 1369044 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-2-001
of type TLOG
[junit4] 2> 1369045 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1369046 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@a14ec1{/_,null,AVAILABLE}
[junit4] 2> 1369046 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@116f08a{HTTP/1.1,[http/1.1]}{127.0.0.1:46695}
[junit4] 2> 1369046 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server Started @1370384ms
[junit4] 2> 1369046 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/tempDir-001/jetty2,
hostPort=46695,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-2-001/cores,
replicaType=TLOG}
[junit4] 2> 1369047 ERROR
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1369047 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1369047 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1369047 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1369047 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-16T02:14:23.119429Z
[junit4] 2> 1369049 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1369049 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-2-001/solr.xml
[junit4] 2> 1369051 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1369053 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33221/solr
[junit4] 2> 1369056 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1369057 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1369058 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46695__
[junit4] 2> 1369058 INFO (zkCallback-3092-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369059 INFO (zkCallback-3103-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369059 INFO (zkCallback-3085-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369061 INFO (zkCallback-3097-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1369167 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1369176 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1369176 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1369177 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-2-001/cores
[junit4] 2> 1369206 INFO (qtp21004830-12629) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:46695__&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1369207 INFO
(OverseerCollectionConfigSetProcessor-98666286850572292-127.0.0.1:46451__-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1369207 INFO (OverseerThreadFactory-4489-thread-4) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:46695__ for creating new replica
[junit4] 2> 1369208 INFO (qtp21507384-12658) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t23&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 1369208 INFO (qtp21507384-12658) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1370215 INFO (qtp21507384-12658) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1370223 INFO (qtp21507384-12658) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_t23] Schema name=inplace-updates
[junit4] 2> 1370224 INFO (qtp21507384-12658) [ ] o.a.s.s.IndexSchema
Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 1370224 INFO (qtp21507384-12658) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t23' using configuration from
collection collection1, trusted=true
[junit4] 2> 1370225 INFO (qtp21507384-12658) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_t23' (registry
'solr.core.collection1.shard1.replica_t23') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1370225 INFO (qtp21507384-12658) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1370225 INFO (qtp21507384-12658) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_t23] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-2-001/cores/collection1_shard1_replica_t23],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-2-001/cores/collection1_shard1_replica_t23/data/]
[junit4] 2> 1370229 WARN (qtp21507384-12658) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1370272 INFO (qtp21507384-12658) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1370272 INFO (qtp21507384-12658) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1370273 INFO (qtp21507384-12658) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1370273 INFO (qtp21507384-12658) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1370274 INFO (qtp21507384-12658) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@119e8b5[collection1_shard1_replica_t23] main]
[junit4] 2> 1370275 INFO (qtp21507384-12658) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1370275 INFO (qtp21507384-12658) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1370276 INFO (qtp21507384-12658) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1370277 INFO (searcherExecutor-4514-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_t23] Registered new searcher
Searcher@119e8b5[collection1_shard1_replica_t23]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370277 INFO (qtp21507384-12658) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1578660595602817024
[junit4] 2> 1370279 INFO (qtp21507384-12658) [ ] o.a.s.c.ZkController
Core needs to recover:collection1_shard1_replica_t23
[junit4] 2> 1370279 INFO (updateExecutor-3100-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1370279 INFO (qtp21507384-12658) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t23&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=1071
[junit4] 2> 1370279 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1370280 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1370280 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.ZkController collection1_shard1_replica_t23 stopping background
replication from leader
[junit4] 2> 1370280 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1_shard1_replica_t23]
[junit4] 2> 1370280 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1370280 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core
[collection1_shard1_replica_t23] as recovering, leader is
[http://127.0.0.1:43567/_/collection1_shard1_replica_t21/] and I am
[http://127.0.0.1:46695/_/collection1_shard1_replica_t23/]
[junit4] 2> 1370281 INFO (qtp21004830-12629) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:46695__&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
status=0 QTime=1074
[junit4] 2> 1370281 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:43567/_]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard1_replica_t21&nodeName=127.0.0.1:46695__&coreNodeName=core_node24&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1370281 INFO (qtp21004830-12630) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node24, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 1370282 INFO (qtp21004830-12630) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_t21,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:46695__,
coreNodeName=core_node24, onlyIfActiveCheckResult=false, nodeProps:
core_node24:{"core":"collection1_shard1_replica_t23","base_url":"http://127.0.0.1:46695/_","node_name":"127.0.0.1:46695__","state":"down","type":"TLOG"}
[junit4] 2> 1370366 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-3-001
of type TLOG
[junit4] 2> 1370367 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1370367 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@aa37ce{/_,null,AVAILABLE}
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@8f525a{HTTP/1.1,[http/1.1]}{127.0.0.1:38031}
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.e.j.s.Server Started @1371706ms
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/tempDir-001/jetty3,
hostPort=38031,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-3-001/cores,
replicaType=TLOG}
[junit4] 2> 1370368 ERROR
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1370368 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-16T02:14:24.440828Z
[junit4] 2> 1370370 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1370370 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-3-001/solr.xml
[junit4] 2> 1370372 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1370374 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33221/solr
[junit4] 2> 1370380 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1370380 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1370381 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38031__
[junit4] 2> 1370382 INFO (zkCallback-3092-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1370382 INFO (zkCallback-3085-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1370382 INFO (zkCallback-3103-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1370382 INFO (zkCallback-3097-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1370382 INFO (zkCallback-3110-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1370508 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1370516 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1370516 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1370517 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-3-001/cores
[junit4] 2> 1370553 INFO (qtp21004830-12624) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:38031__&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1370554 INFO
(OverseerCollectionConfigSetProcessor-98666286850572292-127.0.0.1:46451__-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1370554 INFO (OverseerThreadFactory-4489-thread-5) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:38031__ for creating new replica
[junit4] 2> 1370555 INFO (qtp15008317-12686) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t25&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 1370556 INFO (qtp15008317-12686) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1371282 INFO (qtp21004830-12630) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_t21,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:46695__,
coreNodeName=core_node24, onlyIfActiveCheckResult=false, nodeProps:
core_node24:{"core":"collection1_shard1_replica_t23","base_url":"http://127.0.0.1:46695/_","node_name":"127.0.0.1:46695__","state":"recovering","type":"TLOG"}
[junit4] 2> 1371282 INFO (qtp21004830-12630) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node24, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1371282 INFO (qtp21004830-12630) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:46695__&onlyIfLeaderActive=true&core=collection1_shard1_replica_t21&coreNodeName=core_node24&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 1371568 INFO (qtp15008317-12686) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1371580 INFO (qtp15008317-12686) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_t25] Schema name=inplace-updates
[junit4] 2> 1371583 INFO (qtp15008317-12686) [ ] o.a.s.s.IndexSchema
Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 1371583 INFO (qtp15008317-12686) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t25' using configuration from
collection collection1, trusted=true
[junit4] 2> 1371584 INFO (qtp15008317-12686) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_t25' (registry
'solr.core.collection1.shard1.replica_t25') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1662395
[junit4] 2> 1371584 INFO (qtp15008317-12686) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1371584 INFO (qtp15008317-12686) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_t25] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-3-001/cores/collection1_shard1_replica_t25],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001/shard-3-001/cores/collection1_shard1_replica_t25/data/]
[junit4] 2> 1371590 WARN (qtp15008317-12686) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1371621 INFO (qtp15008317-12686) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1371621 INFO (qtp15008317-12686) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1371622 INFO (qtp15008317-12686) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1371622 INFO (qtp15008317-12686) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1371623 INFO (qtp15008317-12686) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1727d00[collection1_shard1_replica_t25] main]
[junit4] 2> 1371624 INFO (qtp15008317-12686) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1371624 INFO (qtp15008317-12686) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1371624 INFO (qtp15008317-12686) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1371625 INFO (searcherExecutor-4525-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_t25] Registered new searcher
Searcher@1727d00[collection1_shard1_replica_t25]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1371625 INFO (qtp15008317-12686) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1578660597016297472
[junit4] 2> 1371627 INFO (qtp15008317-12686) [ ] o.a.s.c.ZkController
Core needs to recover:collection1_shard1_replica_t25
[junit4] 2> 1371627 INFO (updateExecutor-3107-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1371627 INFO (qtp15008317-12686) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t25&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=1071
[junit4] 2> 1371627 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1371627 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1371627 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.ZkController collection1_shard1_replica_t25 stopping background
replication from leader
[junit4] 2> 1371627 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1_shard1_replica_t25]
[junit4] 2> 1371627 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1371627 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core
[collection1_shard1_replica_t25] as recovering, leader is
[http://127.0.0.1:43567/_/collection1_shard1_replica_t21/] and I am
[http://127.0.0.1:38031/_/collection1_shard1_replica_t25/]
[junit4] 2> 1371628 INFO (qtp21004830-12624) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:38031__&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
status=0 QTime=1075
[junit4] 2> 1371629 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:43567/_]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard1_replica_t21&nodeName=127.0.0.1:38031__&coreNodeName=core_node26&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1371629 INFO (qtp21004830-12628) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node26, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 1371629 INFO (qtp21004830-12628) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_t21,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:38031__,
coreNodeName=core_node26, onlyIfActiveCheckResult=false, nodeProps:
core_node26:{"core":"collection1_shard1_replica_t25","base_url":"http://127.0.0.1:38031/_","node_name":"127.0.0.1:38031__","state":"down","type":"TLOG"}
[junit4] 2> 1371630 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1371630 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):330
[junit4] 1> -
[junit4] 1> replica:core_node22 rstate:active live:true
[junit4] 1> replica:core_node24 rstate:recovering live:true
[junit4] 1> replica:core_node26 rstate:down live:true
[junit4] 2> 1371782 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting Replication Recovery.
[junit4] 2> 1371782 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to replicate from
[http://127.0.0.1:43567/_/collection1_shard1_replica_t21/].
[junit4] 2> 1371784 INFO (qtp21004830-12624) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1578660597183021056,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1371785 INFO (qtp21004830-12624) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1371785 INFO (qtp21004830-12624) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1371785 INFO (qtp21004830-12624) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update
params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2}{commit=}
0 1
[junit4] 2> 1371787 INFO (qtp21004830-12626) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t21] webapp=/_ path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replication Recovery was successful.
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.ZkController collection1_shard1_replica_t23 starting background
replication from leader
[junit4] 2> 1371787 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll
interval: 00:00:03
[junit4] 2> 1371788 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 1371788 INFO (indexFetcher-4531-thread-1) [ ]
o.a.s.h.IndexFetcher Updated masterUrl to
http://127.0.0.1:43567/_/collection1_shard1_replica_t21/
[junit4] 2> 1371789 INFO (qtp21004830-12624) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t21] webapp=/_ path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1371789 INFO (indexFetcher-4531-thread-1) [ ]
o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1371789 INFO (indexFetcher-4531-thread-1) [ ]
o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1371789 INFO (indexFetcher-4531-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1371789 INFO (indexFetcher-4531-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1371789 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Updating version bucket highest from index after
successful recovery.
[junit4] 2> 1371789 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1578660597188263936
[junit4] 2> 1371792 INFO (recoveryExecutor-3101-thread-1) [ ]
o.a.s.c.RecoveryStrategy Finished recovery process, successful=[true]
[junit4] 2> 1372556 INFO
(OverseerCollectionConfigSetProcessor-98666286850572292-127.0.0.1:46451__-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1372629 INFO (qtp21004830-12628) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_t21,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:38031__,
coreNodeName=core_node26, onlyIfActiveCheckResult=false, nodeProps:
core_node26:{"core":"collection1_shard1_replica_t25","base_url":"http://127.0.0.1:38031/_","node_name":"127.0.0.1:38031__","state":"recovering","type":"TLOG"}
[junit4] 2> 1372630 INFO (qtp21004830-12628) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node26, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1372630 INFO (qtp21004830-12628) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:38031__&onlyIfLeaderActive=true&core=collection1_shard1_replica_t21&coreNodeName=core_node26&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 1> -
[junit4] 1> replica:core_node22 rstate:active live:true
[junit4] 1> replica:core_node24 rstate:active live:true
[junit4] 1> replica:core_node26 rstate:recovering live:true
[junit4] 2> 1373130 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting Replication Recovery.
[junit4] 2> 1373130 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to replicate from
[http://127.0.0.1:43567/_/collection1_shard1_replica_t21/].
[junit4] 2> 1373132 INFO (qtp21004830-12628) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1578660598596501504,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1373132 INFO (qtp21004830-12628) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1373132 INFO (qtp21004830-12628) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1373132 INFO (qtp21004830-12628) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update
params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2}{commit=}
0 0
[junit4] 2> 1373133 INFO (qtp21004830-12626) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t21] webapp=/_ path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1373133 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1373133 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1373133 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1373133 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1373134 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replication Recovery was successful.
[junit4] 2> 1373134 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1373134 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.ZkController collection1_shard1_replica_t25 starting background
replication from leader
[junit4] 2> 1373134 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll
interval: 00:00:03
[junit4] 2> 1373134 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 1373134 INFO (indexFetcher-4533-thread-1) [ ]
o.a.s.h.IndexFetcher Updated masterUrl to
http://127.0.0.1:43567/_/collection1_shard1_replica_t21/
[junit4] 2> 1373135 INFO (qtp21004830-12630) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t21] webapp=/_ path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1373135 INFO (indexFetcher-4533-thread-1) [ ]
o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1373135 INFO (indexFetcher-4533-thread-1) [ ]
o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1373135 INFO (indexFetcher-4533-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1373135 INFO (indexFetcher-4533-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1373135 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Updating version bucket highest from index after
successful recovery.
[junit4] 2> 1373135 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1578660598599647232
[junit4] 2> 1373137 INFO (recoveryExecutor-3108-thread-1) [ ]
o.a.s.c.RecoveryStrategy Finished recovery process, successful=[true]
[junit4] 1> -
[junit4] 1> replica:core_node22 rstate:active live:true
[junit4] 1> replica:core_node24 rstate:active live:true
[junit4] 1> replica:core_node26 rstate:active live:true
[junit4] 1> no one is recoverying
[junit4] 2> 1373630 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 1373632 INFO (qtp21004830-12629) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t21] webapp=/_
path=/schema/fields/inplace_updatable_int params={wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1373633 INFO (qtp21507384-12660) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t23] webapp=/_
path=/schema/fields/inplace_updatable_float params={wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1373633 INFO (qtp21004830-12628) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_t21] webapp=/_ path=/schema/fields/_version_
params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1373634 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.u.TestInPlaceUpdatesDistrib Trying num docs = 45
[junit4] 2> 1373639 INFO (qtp21507384-12658) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[0
(1578660599123935232)]} 0 1
[junit4] 2> 1373639 INFO (qtp15008317-12692) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[0
(1578660599123935232)]} 0 1
[junit4] 2> 1373639 INFO (qtp21004830-12630) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[0
(1578660599123935232)]} 0 4
[junit4] 2> 1373641 INFO (qtp21507384-12654) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[1
(1578660599129178112)]} 0 0
[junit4] 2> 1373641 INFO (qtp15008317-12693) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[1
(1578660599129178112)]} 0 0
[junit4] 2> 1373642 INFO (qtp21004830-12629) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[1
(1578660599129178112)]} 0 1
[junit4] 2> 1373643 INFO (qtp21507384-12660) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-1
(1578660599131275264)]} 0 0
[junit4] 2> 1373643 INFO (qtp15008317-12686) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-1
(1578660599131275264)]} 0 0
[junit4] 2> 1373643 INFO (qtp21004830-12628) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[-1
(1578660599131275264)]} 0 1
[junit4] 2> 1373645 INFO (qtp21507384-12655) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-2
(1578660599133372416)]} 0 0
[junit4] 2> 1373645 INFO (qtp15008317-12687) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-2
(1578660599133372416)]} 0 0
[junit4] 2> 1373646 INFO (qtp21004830-12626) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[-2
(1578660599133372416)]} 0 1
[junit4] 2> 1373647 INFO (qtp21507384-12655) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[2
(1578660599135469568)]} 0 0
[junit4] 2> 1373647 INFO (qtp15008317-12692) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[2
(1578660599135469568)]} 0 0
[junit4] 2> 1373647 INFO (qtp21004830-12630) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[2
(1578660599135469568)]} 0 1
[junit4] 2> 1373649 INFO (qtp21507384-12655) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-3
(1578660599137566720)]} 0 0
[junit4] 2> 1373649 INFO (qtp15008317-12693) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-3
(1578660599137566720)]} 0 0
[junit4] 2> 1373649 INFO (qtp21004830-12629) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[-3
(1578660599137566720)]} 0 1
[junit4] 2> 1373650 INFO (qtp21507384-12660) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-4
(1578660599139663872)]} 0 0
[junit4] 2> 1373651 INFO (qtp15008317-12686) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-4
(1578660599139663872)]} 0 0
[junit4] 2> 1373651 INFO (qtp21004830-12628) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[-4
(1578660599139663872)]} 0 1
[junit4] 2> 1373652 INFO (qtp21507384-12658) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[3
(1578660599140712448)]} 0 0
[junit4] 2> 1373653 INFO (qtp15008317-12687) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[3
(1578660599140712448)]} 0 0
[junit4] 2> 1373653 INFO (qtp21004830-12626) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[3
(1578660599140712448)]} 0 1
[junit4] 2> 1373654 INFO (qtp21507384-12654) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-5
(1578660599142809600)]} 0 0
[junit4] 2> 1373654 INFO (qtp15008317-12692) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-5
(1578660599142809600)]} 0 0
[junit4] 2> 1373654 INFO (qtp21004830-12630) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[-5
(1578660599142809600)]} 0 1
[junit4] 2> 1373655 INFO (qtp21507384-12655) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-6
(1578660599144906752)]} 0 0
[junit4] 2> 1373655 INFO (qtp15008317-12693) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t25] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[-6
(1578660599144906752)]} 0 0
[junit4] 2> 1373655 INFO (qtp21004830-12629) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t21] webapp=/_
path=/update params={versions=true&wt=javabin&version=2}{add=[-6
(1578660599144906752)]} 0 0
[junit4] 2> 1373656 INFO (qtp21507384-12660) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t23] webapp=/_
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:43567/_/collection1_shard1_replica_t21/&wt=javabin&version=2}{add=[4
(1578660599145955328)]} 0 0
[junit4] 2> 1373657 INFO (qtp15008317-12686) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replic
[...truncated too long message...]
D8DF24]) [ ] 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> 1567889 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[2AE838B9B8D8DF24]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:33221 33221
[junit4] 2> 1576976 INFO (Thread-2723) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:33221 33221
[junit4] 2> 1576977 WARN (Thread-2723) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestInPlaceUpdatesDistrib -Dtests.method=test
-Dtests.seed=2AE838B9B8D8DF24 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=sl-SI -Dtests.timezone=America/Dawson_Creek -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 212s J1 | TestInPlaceUpdatesDistrib.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: This doc was supposed
to have been deleted, but was: SolrDocument{id=0, title_s=title0, id_i=0,
inplace_updatable_float=1.0, _version_=1578660790564552704,
inplace_updatable_int_with_default=666,
inplace_updatable_float_with_default=42.0}
[junit4] > at
__randomizedtesting.SeedInfo.seed([2AE838B9B8D8DF24:A2BC07631624B2DC]:0)
[junit4] > at
org.apache.solr.update.TestInPlaceUpdatesDistrib.reorderedDBQsSimpleTest(TestInPlaceUpdatesDistrib.java:247)
[junit4] > at
org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpdatesDistrib.java:151)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1576980 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1576980 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=3976618
[junit4] 2> 1576980 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1576980 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@9746c1: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@f52b07
[junit4] 2> 1576981 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1576982 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@16b937a: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@15c4c82
[junit4] 2> 1576982 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1576983 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1b98fc7: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@122ccf6
[junit4] 2> 1576983 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[2AE838B9B8D8DF24]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1576983 INFO (coreCloseExecutor-4609-thread-1) [ ]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@12453ad
[junit4] 2> 1576983 INFO (coreCloseExecutor-4609-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=19157933
[junit4] 2> 1576983 INFO (coreCloseExecutor-4609-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1299a49: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@15a1e28
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.TestInPlaceUpdatesDistrib_2AE838B9B8D8DF24-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{title_s=FST50, id=PostingsFormat(name=MockRandom),
id_field_copy_that_does_not_support_in_place_update_s=FST50},
docValues:{inplace_updatable_float=DocValuesFormat(name=Memory),
id_i=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Memory),
id=DocValuesFormat(name=Lucene70),
inplace_updatable_int_with_default=DocValuesFormat(name=Memory),
inplace_updatable_int=DocValuesFormat(name=Asserting),
inplace_updatable_float_with_default=DocValuesFormat(name=Asserting)},
maxPointsInLeafNode=779, maxMBSortInHeap=6.82163548226575,
sim=RandomSimilarity(queryNorm=true): {}, locale=sl-SI,
timezone=America/Dawson_Creek
[junit4] 2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 9
(32-bit)/cpus=8,threads=1,free=86388680,total=422920192
[junit4] 2> NOTE: All tests run in this JVM: [SampleTest,
TestHashQParserPlugin, HdfsRecoverLeaseTest, TestInfoStreamLogging,
BasicFunctionalityTest, TestSha256AuthenticationProvider,
TestCloudManagedSchema, TestMultiValuedNumericRangeQuery,
ConfigureRecoveryStrategyTest, DeleteReplicaTest, TestLRUCache,
TestReloadDeadlock, ConnectionManagerTest, BlockJoinFacetDistribTest,
HighlighterTest, CoreAdminHandlerTest, DistributedSuggestComponentTest,
BadComponentTest, UpdateRequestProcessorFactoryTest, TestTrieFacet,
TestSmileRequest, TestSchemaNameResource, AnalysisAfterCoreReloadTest,
BitVectorTest, HdfsUnloadDistributedZkTest, TestReplicationHandlerBackup,
IndexSchemaRuntimeFieldTest, ClassificationUpdateProcessorTest,
SystemInfoHandlerTest, V2StandaloneTest, SolrGraphiteReporterTest,
SolrCmdDistributorTest, TestStressCloudBlindAtomicUpdates,
TestAnalyzeInfixSuggestions, ReplicaListTransformerTest, DocValuesMultiTest,
TestCollectionsAPIViaSolrCloudCluster, WrapperMergePolicyFactoryTest,
TestUnifiedSolrHighlighter, TestCloudSearcherWarming, TestCustomStream,
TestPostingsSolrHighlighter, SubstringBytesRefFilterTest, ZkControllerTest,
TestConfigsApi, SuggestComponentContextFilterQueryTest, PrimUtilsTest,
BlobRepositoryCloudTest, TestDocBasedVersionConstraints,
TestManagedSchemaThreadSafety, TestRestManager,
TestDynamicFieldCollectionResource, DirectSolrSpellCheckerTest,
TestTrackingShardHandlerFactory, TestSystemIdResolver, TestHashPartitioner,
TestCoreDiscovery, CurrencyFieldTypeTest, ZkFailoverTest,
TestComplexPhraseLeadingWildcard, TestAtomicUpdateErrorCases, TestSQLHandler,
TestBackupRepositoryFactory, BasicAuthIntegrationTest,
DistributedFacetPivotWhiteBoxTest, SharedFSAutoReplicaFailoverUtilsTest,
ExitableDirectoryReaderTest, TestNoOpRegenerator, TestLegacyFieldReuse,
SliceStateTest, ZkCLITest, MoveReplicaTest, TestCustomDocTransformer,
HdfsChaosMonkeySafeLeaderTest, TestHdfsCloudBackupRestore,
StatelessScriptUpdateProcessorFactoryTest, RemoteQueryErrorTest,
TestFieldCacheVsDocValues, ClusterStateUpdateTest, TestConfigSetProperties,
SolrCloudExampleTest, TestCloudPivotFacet, TestRequestForwarding, SOLR749Test,
TestSchemaVersionResource, TestAuthorizationFramework, TestSolrCLIRunExample,
TestDFISimilarityFactory, DistributedMLTComponentTest, DistributedQueueTest,
TestNumericTerms32, CloudMLTQParserTest, TestSolrConfigHandler,
NoCacheHeaderTest, DistributedExpandComponentTest,
OverseerModifyCollectionTest, TestValueSourceCache, ResourceLoaderTest,
SolrSlf4jReporterTest, CollectionsAPIDistributedZkTest,
TestRandomRequestDistribution, SuggesterTSTTest, HdfsSyncSliceTest,
TestTlogReplica, CollectionsAPISolrJTest, TestStressRecovery,
DistributedIntervalFacetingTest, TestManagedStopFilterFactory,
TestCloudDeleteByQuery, TestHalfAndHalfDocValues,
TestCursorMarkWithoutUniqueKey, ResponseHeaderTest, JsonLoaderTest,
TestNumericTokenStream, TestFuzzyAnalyzedSuggestions, TestChildDocTransformer,
TestInPlaceUpdatesDistrib]
[junit4] Completed [353/732 (1!)] on J1 in 212.58s, 1 test, 1 failure <<<
FAILURES!
[...truncated 40367 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
