Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1855/
2 tests failed.
FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([F855A53E8FDDDA62]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.update.TestInPlaceUpdatesDistrib
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([F855A53E8FDDDA62]:0)
Build Log:
[...truncated 15928 lines...]
[junit4] Suite: org.apache.solr.update.TestInPlaceUpdatesDistrib
[junit4] 2> 4267322 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/init-core-data-001
[junit4] 2> 4267322 WARN
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=81 numCloses=81
[junit4] 2> 4267323 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 4267324 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-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> 4267324 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/d_upd/y
[junit4] 2> 4267324 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 4267324 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 4267324 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4267344 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4267361 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=inplace-updates
[junit4] 2> 4267365 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 4267475 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4267501 WARN
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@6df13681[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4267525 WARN
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@26e44555[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4267548 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 4267548 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 4267573 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4267589 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4267590 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4267592 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4267614 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4267631 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=inplace-updates
[junit4] 2> 4267634 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with
uniqueid field id
[junit4] 2> 4267634 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 4267635 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4267635 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/init-core-data-001/]
[junit4] 2> 4267642 WARN (coreLoadExecutor-15198-thread-1) [
x:collection1] 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> 4267711 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 4267711 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 4267713 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4267713 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4267714 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@655d364d[collection1] main]
[junit4] 2> 4267715 WARN (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 4267715 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4267716 INFO (coreLoadExecutor-15198-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1634480045531594752
[junit4] 2> 4267719 INFO
(SUITE-TestInPlaceUpdatesDistrib-seed#[F855A53E8FDDDA62]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 4267739 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4267750 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4267750 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4267751 INFO
(searcherExecutor-15199-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@655d364d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4267850 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer start zk server on port:46693
[junit4] 2> 4267850 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46693
[junit4] 2> 4267850 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 46693
[junit4] 2> 4267856 INFO (zkConnectionManagerCallback-22573-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4267859 INFO (zkConnectionManagerCallback-22575-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4267860 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4267862 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema-inplace-updates.xml
to /configs/conf1/schema.xml
[junit4] 2> 4267864 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4267865 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4267866 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4267868 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4267869 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4267870 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4267871 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4267873 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4267874 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkTestServer put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4267876 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 4268056 WARN
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4268057 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4268057 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4268057 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 4268058 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4268058 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4268058 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4268059 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@745f4f14{/d_upd/y,null,AVAILABLE}
[junit4] 2> 4268060 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@2d60136a{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:42715}
[junit4] 2> 4268060 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.s.Server Started @4268119ms
[junit4] 2> 4268060 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/d_upd/y,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/tempDir-001/control/data,
hostPort=42715,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/control-001/cores}
[junit4] 2> 4268060 ERROR
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4268060 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4268061 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
9.0.0
[junit4] 2> 4268061 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4268061 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4268061 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-25T05:20:40.447182Z
[junit4] 2> 4268063 INFO (zkConnectionManagerCallback-22577-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4268064 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4268064 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/control-001/solr.xml
[junit4] 2> 4268067 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4268067 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4268069 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4268540 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4268541 WARN
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@7864e6d4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4268544 WARN
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@4baecc99[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4268546 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46693/solr
[junit4] 2> 4268547 INFO (zkConnectionManagerCallback-22584-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4268550 INFO (zkConnectionManagerCallback-22586-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4268648 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader
127.0.0.1:42715_d_upd%2Fy
[junit4] 2> 4268649 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.Overseer Overseer
(id=73361523819937796-127.0.0.1:42715_d_upd%2Fy-n_0000000000) starting
[junit4] 2> 4268656 INFO (zkConnectionManagerCallback-22593-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4268659 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46693/solr ready
[junit4] 2> 4268660 INFO
(OverseerStateUpdate-73361523819937796-127.0.0.1:42715_d_upd%2Fy-n_0000000000)
[n:127.0.0.1:42715_d_upd%2Fy ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:42715_d_upd%2Fy
[junit4] 2> 4268661 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42715_d_upd%2Fy
[junit4] 2> 4268662 INFO
(OverseerStateUpdate-73361523819937796-127.0.0.1:42715_d_upd%2Fy-n_0000000000)
[n:127.0.0.1:42715_d_upd%2Fy ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 4268663 INFO (zkCallback-22592-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4268682 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 4268717 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4268741 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4268741 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4268744 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/control-001/cores
[junit4] 2> 4268794 INFO (zkConnectionManagerCallback-22599-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4268796 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4268797 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46693/solr ready
[junit4] 2> 4268800 INFO (qtp93907865-94140) [n:127.0.0.1:42715_d_upd%2Fy
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42715_d_upd%252Fy&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4268803 INFO (OverseerThreadFactory-15213-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 4268913 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
x:control_collection_shard1_replica_n1] 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> 4268913 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
x:control_collection_shard1_replica_n1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4269928 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4269947 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema
name=inplace-updates
[junit4] 2> 4269951 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 4269951 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from collection control_collection, trusted=true
[junit4] 2> 4269952 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
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@38437ddc
[junit4] 2> 4269952 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 4269957 WARN (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
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> 4270024 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 4270024 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4270026 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4270026 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4270028 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5b85deeb[control_collection_shard1_replica_n1] main]
[junit4] 2> 4270029 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4270030 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4270031 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4270031 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1634480047959048192
[junit4] 2> 4270036 INFO
(searcherExecutor-15218-thread-1-processing-n:127.0.0.1:42715_d_upd%2Fy
x:control_collection_shard1_replica_n1 c:control_collection s:shard1)
[n:127.0.0.1:42715_d_upd%2Fy c:control_collection s:shard1
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher
Searcher@5b85deeb[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4270047 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/control_collection/terms/shard1 to Terms{values={core_node2=0},
version=0}
[junit4] 2> 4270047 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/control_collection/leaders/shard1
[junit4] 2> 4270053 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4270053 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4270053 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:42715/d_upd/y/control_collection_shard1_replica_n1/
[junit4] 2> 4270054 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4270054 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:42715/d_upd/y/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 4270054 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/control_collection/leaders/shard1/leader after winning as
/collections/control_collection/leader_elect/shard1/election/73361523819937796-core_node2-n_0000000000
[junit4] 2> 4270057 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:42715/d_upd/y/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 4270159 INFO (zkCallback-22585-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> 4270160 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4270163 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
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=1250
[junit4] 2> 4270178 INFO (zkCallback-22585-thread-2) [ ]
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> 4270187 INFO (qtp93907865-94140) [n:127.0.0.1:42715_d_upd%2Fy
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at
most 45 seconds. Check all shard replicas
[junit4] 2> 4270284 INFO (zkCallback-22585-thread-2) [ ]
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> 4270284 INFO (zkCallback-22585-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> 4270285 INFO (zkCallback-22585-thread-3) [ ]
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> 4270285 INFO (qtp93907865-94140) [n:127.0.0.1:42715_d_upd%2Fy
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42715_d_upd%252Fy&wt=javabin&version=2}
status=0 QTime=1485
[junit4] 2> 4270294 INFO (zkConnectionManagerCallback-22605-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4270296 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4270297 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46693/solr ready
[junit4] 2> 4270297 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 4270299 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4270303 INFO
(OverseerCollectionConfigSetProcessor-73361523819937796-127.0.0.1:42715_d_upd%2Fy-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> 4270303 INFO (OverseerThreadFactory-15213-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 4270509 WARN (OverseerThreadFactory-15213-thread-2) [ ]
o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection
(collection1) without cores.
[junit4] 2> 4270511 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at
most 45 seconds. Check all shard replicas
[junit4] 2> 4270512 INFO (qtp93907865-94142) [n:127.0.0.1:42715_d_upd%2Fy
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=2&wt=javabin&version=2}
status=0 QTime=213
[junit4] 2> 4270512 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances
pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 4270685 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-1-001
of type NRT
[junit4] 2> 4270685 WARN (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4270686 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4270686 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4270686 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 4270687 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4270688 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4270688 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4270688 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@48f00a9f{/d_upd/y,null,AVAILABLE}
[junit4] 2> 4270688 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3ab66e26{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:39567}
[junit4] 2> 4270688 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.Server Started @4270747ms
[junit4] 2> 4270688 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/d_upd/y,
solrconfig=solrconfig.xml,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/tempDir-001/jetty1,
hostPort=39567,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-1-001/cores,
replicaType=NRT}
[junit4] 2> 4270689 ERROR (closeThreadPool-22606-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4270689 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4270689 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
9.0.0
[junit4] 2> 4270689 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4270689 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4270689 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-25T05:20:43.075443Z
[junit4] 2> 4270692 INFO (zkConnectionManagerCallback-22608-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4270693 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4270693 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-1-001/solr.xml
[junit4] 2> 4270696 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4270696 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4270698 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4270769 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4270779 WARN (closeThreadPool-22606-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@2b02e89a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4270782 WARN (closeThreadPool-22606-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@e4772e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4270784 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46693/solr
[junit4] 2> 4270785 INFO (zkConnectionManagerCallback-22615-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4270794 INFO (zkConnectionManagerCallback-22617-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4270801 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 4270806 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.ZkController Publish
node=127.0.0.1:39567_d_upd%2Fy as DOWN
[junit4] 2> 4270807 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 4 transient cores
[junit4] 2> 4270808 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39567_d_upd%2Fy
[junit4] 2> 4270810 INFO (zkCallback-22592-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4270810 INFO (zkCallback-22585-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4270815 INFO (zkCallback-22604-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4270817 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4270817 INFO (zkConnectionManagerCallback-22624-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4270818 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (2)
[junit4] 2> 4270820 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.s.i.ZkClientClusterStateProvider
Cluster at 127.0.0.1:46693/solr ready
[junit4] 2> 4270846 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 4270897 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-2-001
of type NRT
[junit4] 2> 4270907 WARN (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4270907 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4270907 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4270907 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 4270926 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271056 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4271056 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4271056 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4271057 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6faaa699{/d_upd/y,null,AVAILABLE}
[junit4] 2> 4271057 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@56155c40{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:40680}
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.e.j.s.Server Started @4271116ms
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/d_upd/y,
solrconfig=solrconfig.xml,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/tempDir-001/jetty2,
hostPort=40680,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-2-001/cores,
replicaType=NRT}
[junit4] 2> 4271058 ERROR (closeThreadPool-22606-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
9.0.0
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4271058 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-25T05:20:43.444718Z
[junit4] 2> 4271061 INFO (zkConnectionManagerCallback-22627-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271062 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4271062 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-2-001/solr.xml
[junit4] 2> 4271066 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4271066 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4271067 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4271073 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271074 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271077 INFO (closeThreadPool-22606-thread-1)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-1-001/cores
[junit4] 2> 4271149 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4271150 WARN (closeThreadPool-22606-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@6a668f36[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4271155 WARN (closeThreadPool-22606-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@4c51c8be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4271156 INFO (closeThreadPool-22606-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46693/solr
[junit4] 2> 4271158 INFO (zkConnectionManagerCallback-22634-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271178 INFO (zkConnectionManagerCallback-22636-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271183 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (2)
[junit4] 2> 4271189 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.ZkController Publish
node=127.0.0.1:40680_d_upd%2Fy as DOWN
[junit4] 2> 4271190 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 4 transient cores
[junit4] 2> 4271190 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40680_d_upd%2Fy
[junit4] 2> 4271191 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4271191 INFO (zkCallback-22604-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4271192 INFO (zkCallback-22592-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4271192 INFO (zkCallback-22585-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4271192 INFO (zkCallback-22623-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4271195 INFO (zkConnectionManagerCallback-22643-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271195 INFO (zkCallback-22635-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4271197 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (3)
[junit4] 2> 4271199 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.s.i.ZkClientClusterStateProvider
Cluster at 127.0.0.1:46693/solr ready
[junit4] 2> 4271220 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 4271253 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271275 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271276 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271277 INFO
(TEST-TestInPlaceUpdatesDistrib.test-seed#[F855A53E8FDDDA62]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-3-001
of type NRT
[junit4] 2> 4271278 WARN (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4271278 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4271278 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4271278 INFO (closeThreadPool-22606-thread-2)
[n:127.0.0.1:40680_d_upd%2Fy ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-2-001/cores
[junit4] 2> 4271278 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 4271280 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4271280 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4271280 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4271280 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@c9454f4{/d_upd/y,null,AVAILABLE}
[junit4] 2> 4271281 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@68eee243{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:45171}
[junit4] 2> 4271281 INFO (closeThreadPool-22606-thread-1) [ ]
o.e.j.s.Server Started @4271340ms
[junit4] 2> 4271281 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/d_upd/y,
solrconfig=solrconfig.xml,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/tempDir-001/jetty3,
hostPort=45171,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-3-001/cores,
replicaType=NRT}
[junit4] 2> 4271281 ERROR (closeThreadPool-22606-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4271281 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4271281 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
9.0.0
[junit4] 2> 4271281 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4271282 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4271282 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-25T05:20:43.668017Z
[junit4] 2> 4271284 INFO (zkConnectionManagerCallback-22646-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271285 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4271285 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-3-001/solr.xml
[junit4] 2> 4271288 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4271288 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4271290 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4271491 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4271492 WARN (closeThreadPool-22606-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@d2263d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4271495 WARN (closeThreadPool-22606-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@665aefc4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4271497 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46693/solr
[junit4] 2> 4271499 INFO (zkConnectionManagerCallback-22653-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271501 INFO (zkConnectionManagerCallback-22655-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271507 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4271512 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:45171_d_upd%2Fy as DOWN
[junit4] 2> 4271513 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4271513 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45171_d_upd%2Fy
[junit4] 2> 4271515 INFO (zkCallback-22623-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271515 INFO (zkCallback-22585-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271515 INFO (zkCallback-22604-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271515 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271515 INFO (zkCallback-22592-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271515 INFO (zkCallback-22635-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271515 INFO (zkCallback-22642-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271516 INFO (zkCallback-22654-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4271518 INFO (zkConnectionManagerCallback-22662-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4271519 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4271521 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46693/solr ready
[junit4] 2> 4271556 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 4271594 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271626 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271627 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4271668 INFO (closeThreadPool-22606-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-3-001/cores
[junit4] 2> 4271763 INFO (qtp693706616-94200)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.h.a.CollectionsHandler Invoked
Collection Action :addreplica with params
node=127.0.0.1:39567_d_upd%252Fy&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4271763 INFO (qtp693706616-94201)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.h.a.CollectionsHandler Invoked
Collection Action :addreplica with params
node=127.0.0.1:40680_d_upd%252Fy&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4271764 INFO (qtp693706616-94198)
[n:127.0.0.1:39567_d_upd%2Fy ] o.a.s.h.a.CollectionsHandler Invoked
Collection Action :addreplica with params
node=127.0.0.1:45171_d_upd%252Fy&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4271775 INFO
(OverseerCollectionConfigSetProcessor-73361523819937796-127.0.0.1:42715_d_upd%2Fy-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> 4271777 INFO (OverseerThreadFactory-15213-thread-3) [
c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified
127.0.0.1:39567_d_upd%2Fy for creating new replica of shard shard1 for
collection collection1
[junit4] 2> 4271780 INFO (OverseerThreadFactory-15213-thread-3) [
c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica
command.
[junit4] 2> 4271783 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy x:collection1_shard1_replica_n1]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4272799 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
9.0.0
[junit4] 2> 4272818 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema
[collection1_shard1_replica_n1] Schema name=inplace-updates
[junit4] 2> 4272821 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
inplace-updates/1.6 with uniqueid field id
[junit4] 2> 4272822 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore
'collection1_shard1_replica_n1' using configuration from collection
collection1, trusted=true
[junit4] 2> 4272822 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4272822 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[[collection1_shard1_replica_n1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-1-001/cores/collection1_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-1-001/cores/collection1_shard1_replica_n1/data/]
[junit4] 2> 4272829 WARN (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] 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> 4272908 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4272908 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 4272910 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4272910 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4272913 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@4c0a1651[collection1_shard1_replica_n1] main]
[junit4] 2> 4272915 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4272915 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4272916 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000ms.
[junit4] 2> 4272916 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version
in index or recent updates, using new clock 1634480050984189952
[junit4] 2> 4272922 INFO
(searcherExecutor-15250-thread-1-processing-n:127.0.0.1:39567_d_upd%2Fy
x:collection1_shard1_replica_n1 c:collection1 s:shard1)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] Registered new searcher
Searcher@4c0a1651[collection1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4272926 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=0},
version=0}
[junit4] 2> 4272927 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make
sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 4272934 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 4272934 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be
the new leader - try and sync
[junit4] 2> 4272934 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:39567/d_upd/y/collection1_shard1_replica_n1/
[junit4] 2> 4272934 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 4272935 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:39567/d_upd/y/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 4272935 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase
Creating leader registration node
/collections/collection1/leaders/shard1/leader after winning as
/collections/collection1/leader_elect/shard1/election/73361523819937802-core_node2-n_0000000000
[junit4] 2> 4272937 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: http://127.0.0.1:39567/d_upd/y/collection1_shard1_replica_n1/ shard1
[junit4] 2> 4273039 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273040 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 4273043 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1259
[junit4] 2> 4273043 INFO (zkCallback-22616-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273046 INFO (qtp693706616-94200)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/collections
params={node=127.0.0.1:39567_d_upd%252Fy&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=1283
[junit4] 2> 4273143 INFO (zkCallback-22616-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273143 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273145 INFO (zkCallback-22616-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273795 INFO
(OverseerCollectionConfigSetProcessor-73361523819937796-127.0.0.1:42715_d_upd%2Fy-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> 4273796 INFO (OverseerThreadFactory-15213-thread-4) [
c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified
127.0.0.1:40680_d_upd%2Fy for creating new replica of shard shard1 for
collection collection1
[junit4] 2> 4273797 INFO (OverseerThreadFactory-15213-thread-4) [
c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica
command.
[junit4] 2> 4273823 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy x:collection1_shard1_replica_n3]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n3&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4273928 INFO (zkCallback-22616-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273928 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4273928 INFO (zkCallback-22616-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4274835 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion:
9.0.0
[junit4] 2> 4274853 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.s.IndexSchema
[collection1_shard1_replica_n3] Schema name=inplace-updates
[junit4] 2> 4274856 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema
inplace-updates/1.6 with uniqueid field id
[junit4] 2> 4274856 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.CoreContainer Creating SolrCore
'collection1_shard1_replica_n3' using configuration from collection
collection1, trusted=true
[junit4] 2> 4274857 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_n3' (registry
'solr.core.collection1.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@38437ddc
[junit4] 2> 4274857 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.SolrCore
[[collection1_shard1_replica_n3] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-2-001/cores/collection1_shard1_replica_n3],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_F855A53E8FDDDA62-001/shard-2-001/cores/collection1_shard1_replica_n3/data/]
[junit4] 2> 4274863 WARN (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] 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> 4274926 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4274926 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 4274928 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4274928 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4274930 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.s.SolrIndexSearcher Opening
[Searcher@3b4ea1e9[collection1_shard1_replica_n3] main]
[junit4] 2> 4274932 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4274932 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4274933 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000ms.
[junit4] 2> 4274933 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max version
in index or recent updates, using new clock 1634480053099167744
[junit4] 2> 4274937 INFO
(searcherExecutor-15255-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.SolrCore
[collection1_shard1_replica_n3] Registered new searcher
Searcher@3b4ea1e9[collection1_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4274940 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=0,
core_node4=0}, version=1}
[junit4] 2> 4274940 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make
sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 4274943 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.c.ZkController Core needs to
recover:collection1_shard1_replica_n3
[junit4] 2> 4274943 INFO
(updateExecutor-22630-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 4274944 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 4274944 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy startupVersions is
empty
[junit4] 2> 4274945 INFO (qtp1864938593-94231)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1
x:collection1_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n3&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1122
[junit4] 2> 4274946 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica_n1] o.a.s.c.S.Request
[collection1_shard1_replica_n1] webapp=/d_upd/y path=/admin/ping
params={wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 4274946 INFO (qtp693706616-94199)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica_n1] o.a.s.c.S.Request
[collection1_shard1_replica_n1] webapp=/d_upd/y path=/admin/ping
params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 4274947 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Begin buffering
updates. core=[collection1_shard1_replica_n3]
[junit4] 2> 4274947 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 4274947 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Publishing state of
core [collection1_shard1_replica_n3] as recovering, leader is
[http://127.0.0.1:39567/d_upd/y/collection1_shard1_replica_n1/] and I am
[http://127.0.0.1:40680/d_upd/y/collection1_shard1_replica_n3/]
[junit4] 2> 4274949 INFO (qtp693706616-94201)
[n:127.0.0.1:39567_d_upd%2Fy c:collection1 ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/collections
params={node=127.0.0.1:40680_d_upd%252Fy&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=3185
[junit4] 2> 4274950 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_upd%2Fy c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:39567/d_upd/y]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard1_replica_n1&nodeName=127.0.0.1:40680_d_upd%252Fy&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 4274951 INFO (qtp693706616-94200)
[n:127.0.0.1:39567_d_upd%2Fy x:collection1_shard1_replica_n1]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 4274951 INFO (qtp693706616-94200)
[n:127.0.0.1:39567_d_upd%2Fy x:collection1_shard1_replica_n1]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_n1,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:40680_d_upd%2Fy,
coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"collection1_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:40680/d_upd/y";,
[junit4] 2> "node_name":"127.0.0.1:40680_d_upd%2Fy",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 4275051 INFO (zkCallback-22616-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4275051 INFO (zkCallback-22616-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4275051 INFO (zkCallback-22635-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4275051 INFO (zkCallback-22616-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4275051 INFO (watches-22618-thread-2) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_n1,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:40680_d_upd%2Fy,
coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"collection1_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:40680/d_upd/y";,
[junit4] 2> "node_name":"127.0.0.1:40680_d_upd%2Fy",
[junit4] 2> "state":"recovering",
[junit4] 2> "type":"NRT"}
[junit4] 2> 4275051 INFO (qtp693706616-94200)
[n:127.0.0.1:39567_d_upd%2Fy x:collection1_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:40680_d_upd%252Fy&onlyIfLeaderActive=true&core=collection1_shard1_replica_n1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=101
[junit4] 2> 4275058 INFO (zkCallback-22635-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4275059 INFO (zkCallback-22635-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 4275552 INFO
(recoveryExecutor-22632-thread-1-processing-n:127.0.0.1:40680_d_upd%2Fy
x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4)
[n:127.0.0.1:40680_d_u
[...truncated too long message...]
:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1329539178
[copy] Copying 240 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1329539178
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1329539178
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: #;working@lucene1-us-west
[ivy:cachepath] confs: [default]
[ivy:cachepath] found
org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.54 in public
[ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public
[ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 53ms :: artifacts dl 9ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 9 | 0 | 0 | 0 || 9 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 432 minutes 22 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at
hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
matches found within 10000
at hudson.FilePath.act(FilePath.java:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
