You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/06/02 16:24:36 UTC
[JENKINS] Lucene-Solr-repro-Java11 - Build # 1134 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-repro-Java11/1134/
[...truncated 29 lines...]
[repro] Jenkins log URL: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/2211/consoleText
[repro] Revision: e841d7625cc9cf495e611972b488390bcc8458ea
[repro] Ant options: -Dtests.multiplier=2 -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
[repro] Repro line: ant test -Dtestcase=RollingRestartTest -Dtests.method=test -Dtests.seed=D9290F182E495D84 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=fr-BL -Dtests.timezone=America/Antigua -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=PackageManagerCLITest -Dtests.method=testPackageManager -Dtests.seed=D9290F182E495D84 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=yo-BJ -Dtests.timezone=Asia/Riyadh -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] git rev-parse --abbrev-ref HEAD
[repro] git rev-parse HEAD
[repro] Initial local git branch/revision: db9cd0cebb8c73d51bdd68a30c9c229c4745cb52
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] git fetch
[...truncated 7 lines...]
[repro] git checkout e841d7625cc9cf495e611972b488390bcc8458ea
[...truncated 2 lines...]
[repro] git merge --ff-only
[...truncated 1 lines...]
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] PackageManagerCLITest
[repro] RollingRestartTest
[repro] ant compile-test
[...truncated 3125 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.PackageManagerCLITest|*.RollingRestartTest" -Dtests.showOutput=onerror -Dtests.multiplier=2 -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.seed=D9290F182E495D84 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=yo-BJ -Dtests.timezone=Asia/Riyadh -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 771 lines...]
[junit4] 2> ERROR: Error from server at https://127.0.0.1:44713: java.util.ConcurrentModificationException
[junit4] 2>
[junit4] 2> 14723 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPackageManager
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=PackageManagerCLITest -Dtests.method=testPackageManager -Dtests.seed=D9290F182E495D84 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=yo-BJ -Dtests.timezone=Asia/Riyadh -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 10.1s J0 | PackageManagerCLITest.testPackageManager <<<
[junit4] > Throwable #1: java.lang.AssertionError: Non-zero status returned for: [-solrUrl, https://127.0.0.1:44713/solr, install, question-answer] expected:<0> but was:<1>
[junit4] > at __randomizedtesting.SeedInfo.seed([D9290F182E495D84:DA8344762CA7822F]:0)
[junit4] > at org.apache.solr.cloud.PackageManagerCLITest.run(PackageManagerCLITest.java:156)
[junit4] > at org.apache.solr.cloud.PackageManagerCLITest.testPackageManager(PackageManagerCLITest.java:111)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> 14746 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@af508dd{HTTP/1.1, (http/1.1)}{0.0.0.0:0}
[junit4] 2> 14917 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1726882809
[junit4] 2> 14917 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44713_solr
[junit4] 2> 14920 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 14920 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:44713_solr as DOWN
[junit4] 2> 14936 INFO (zkCallback-20-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/abc/state.json] for collection [abc] has occurred - updating... (live nodes size: [1])
[junit4] 2> 14936 INFO (zkCallback-20-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/abc/state.json] for collection [abc] has occurred - updating... (live nodes size: [1])
[junit4] 2> 14936 INFO (zkCallback-20-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/abc/state.json] for collection [abc] has occurred - updating... (live nodes size: [1])
[junit4] 2> 14937 INFO (zkCallback-20-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 14937 INFO (zkCallback-20-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 14937 INFO (zkCallback-20-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 14971 INFO (coreCloseExecutor-104-thread-1) [n:127.0.0.1:44713_solr ] o.a.s.c.SolrCore [abc_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@71d70403
[junit4] 2> 14971 INFO (coreCloseExecutor-104-thread-1) [n:127.0.0.1:44713_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.abc.shard1.replica_n1 tag=SolrCore@71d70403
[junit4] 2> 14971 INFO (coreCloseExecutor-104-thread-1) [n:127.0.0.1:44713_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48aed9c9: rootName = solr_44713, domain = solr.core.abc.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.abc.shard1.replica_n1/com.codahale.metrics.MetricRegistry@6e6b10ec
[junit4] 2> 14979 INFO (coreCloseExecutor-104-thread-2) [n:127.0.0.1:44713_solr ] o.a.s.c.SolrCore [def_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@7681357
[junit4] 2> 14982 INFO (coreCloseExecutor-104-thread-2) [n:127.0.0.1:44713_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.def.shard1.replica_n1 tag=SolrCore@7681357
[junit4] 2> 14982 INFO (coreCloseExecutor-104-thread-2) [n:127.0.0.1:44713_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2b77c5dd: rootName = solr_44713, domain = solr.core.def.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.def.shard1.replica_n1/com.codahale.metrics.MetricRegistry@73578c40
[junit4] 2> 14993 INFO (coreCloseExecutor-104-thread-2) [n:127.0.0.1:44713_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.def.shard1.leader tag=SolrCore@7681357
[junit4] 2> 14995 INFO (coreCloseExecutor-104-thread-2) [n:127.0.0.1:44713_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 14994 INFO (coreCloseExecutor-104-thread-1) [n:127.0.0.1:44713_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.abc.shard1.leader tag=SolrCore@71d70403
[junit4] 2> 14996 INFO (coreCloseExecutor-104-thread-1) [n:127.0.0.1:44713_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 15037 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 15037 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1969f820: rootName = solr_44713, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@17f30dd7
[junit4] 2> 15043 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 15043 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55ec2667: rootName = solr_44713, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@b6e1a74
[junit4] 2> 15045 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 15045 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3ba89f69: rootName = solr_44713, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@652df2af
[junit4] 2> 15045 INFO (jetty-closer-100-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 15078 INFO (closeThreadPool-106-thread-2) [ ] o.a.s.c.Overseer Overseer (id=73862164190724100-127.0.0.1:44713_solr-n_0000000000) closing
[junit4] 2> 15078 INFO (OverseerStateUpdate-73862164190724100-127.0.0.1:44713_solr-n_0000000000) [n:127.0.0.1:44713_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44713_solr
[junit4] 2> 15078 INFO (OverseerAutoScalingTriggerThread-73862164190724100-127.0.0.1:44713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 15424 INFO (closeThreadPool-106-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73862164190724100-127.0.0.1:44713_solr-n_0000000000) closing
[junit4] 2> 15525 INFO (jetty-closer-100-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73862164190724100-127.0.0.1:44713_solr-n_0000000000) closing
[junit4] 2> 24409 INFO (jetty-closer-100-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@13736b7e{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 24411 INFO (jetty-closer-100-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@39d66b0b{/solr,null,UNAVAILABLE}
[junit4] 2> 24411 INFO (jetty-closer-100-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 24413 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 24769 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/configs/conf1
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/collections/def/terms/shard1
[junit4] 2> 2 /solr/collections/abc/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/abc/state.json
[junit4] 2> 12 /solr/collections/def/state.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/overseer/queue
[junit4] 2> 8 /solr/overseer/collection-queue-work
[junit4] 2> 6 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 24775 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:33511
[junit4] 2> 24775 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33511
[junit4] 2> 24775 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33511
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-001
[junit4] 2> Jun 02, 2020 4:06:24 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=145, maxMBSortInHeap=7.916147358468808, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@ecf092f), locale=yo-BJ, timezone=Asia/Riyadh
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=4,threads=2,free=160864768,total=264241152
[junit4] 2> NOTE: All tests run in this JVM: [PackageManagerCLITest]
[junit4] Completed [1/10 (1!)] on J0 in 24.67s, 1 test, 1 failure <<< FAILURES!
[junit4]
[junit4] Suite: org.apache.solr.cloud.PackageManagerCLITest
[junit4] 2> 25776 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/server/solr/configsets/_default/conf'
[junit4] 2> 25828 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/data-dir-2-001
[junit4] 2> 25828 WARN (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 25829 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 25830 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 25831 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 25832 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001
[junit4] 2> 25832 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 25848 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 25848 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 26336 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 35973
[junit4] 2> 26336 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:35973
[junit4] 2> 26336 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:35973
[junit4] 2> 26336 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 35973
[junit4] 2> 26391 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 26435 INFO (zkConnectionManagerCallback-109-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 26435 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 26508 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 26526 INFO (zkConnectionManagerCallback-111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 26526 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 26536 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 26568 INFO (zkConnectionManagerCallback-113-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 26568 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 26692 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 26692 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 26692 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 27156 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 27156 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 27157 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 27157 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d721e7f{/solr,null,AVAILABLE}
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3bf0c41c{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:43297}
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.e.j.s.Server Started @27198ms
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43297}
[junit4] 2> 27163 ERROR (jetty-launcher-114-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr
[junit4] 2> 27163 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-02T16:06:26.459633Z
[junit4] 2> 27179 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 27183 INFO (zkConnectionManagerCallback-116-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 27183 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 27184 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 27298 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 27316 WARN (jetty-launcher-114-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3241d5d3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 27316 WARN (jetty-launcher-114-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3241d5d3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 27346 WARN (jetty-launcher-114-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@64595baa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 27346 WARN (jetty-launcher-114-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@64595baa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 27347 INFO (jetty-launcher-114-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35973/solr
[junit4] 2> 27360 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 27361 INFO (zkConnectionManagerCallback-127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 27361 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 27424 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43297_solr
[junit4] 2> 27425 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.Overseer Overseer (id=73862165707489284-127.0.0.1:43297_solr-n_0000000000) starting
[junit4] 2> 27428 INFO (OverseerStateUpdate-73862165707489284-127.0.0.1:43297_solr-n_0000000000) [n:127.0.0.1:43297_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43297_solr
[junit4] 2> 27431 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43297_solr
[junit4] 2> 27432 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 27433 WARN (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 27440 INFO (zkCallback-126-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 27456 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 27481 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43297.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1609fe82
[junit4] 2> 27487 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43297.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1609fe82
[junit4] 2> 27488 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43297.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1609fe82
[junit4] 2> 27489 INFO (jetty-launcher-114-thread-1) [n:127.0.0.1:43297_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/.
[junit4] 2> 27507 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 27509 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 27518 INFO (zkConnectionManagerCallback-144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 27518 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 27778 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 27779 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35973/solr ready
[junit4] 2> 27784 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 27788 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37a5699e{HTTP/1.1, (http/1.1)}{0.0.0.0:38071}
[junit4] 2> 27788 INFO (SUITE-PackageManagerCLITest-seed#[D9290F182E495D84]-worker) [ ] o.e.j.s.Server Started @27823ms
[junit4] 2> 27806 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPackageManager
[junit4] 2> 27806 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 27873 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=2
[junit4] 2> 27882 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 27882 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 27883 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 27895 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 27900 INFO (zkConnectionManagerCallback-146-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 1> [32mInstalled packages:
[junit4] 1> -----[0m
[junit4] 2> 27900 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 27901 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 2> 27901 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Getting packages from packages.json...
[junit4] 2> 27901 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Got packages: []
[junit4] 2> 28002 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: list-installed
[junit4] 2> 28003 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 28047 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=2
[junit4] 2> 28054 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 28054 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 28056 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 28058 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 28075 INFO (zkConnectionManagerCallback-148-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 28075 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 28076 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 2> 28076 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.RepositoryManager []
[junit4] 2> 28132 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=1
[junit4] 2> 28155 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/fullstory.der params={sync=true} status=0 QTime=2
[junit4] 1> [32mAdded repository: fullstory[0m
[junit4] 2> 28399 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: add-repo
[junit4] 2> 28399 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 28441 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=2
[junit4] 2> 28446 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 28447 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 28448 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 28454 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 28455 INFO (zkConnectionManagerCallback-150-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 28456 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 28456 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 1> [32mAvailable packages:
[junit4] 1> -----[0m
[junit4] 1> [32mquestion-answer A natural language question answering plugin[0m
[junit4] 1> [32m Version: 1.0.0[0m
[junit4] 1> [32m Version: 1.1.0[0m
[junit4] 2> 28565 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: list-available
[junit4] 2> 28566 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 28610 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=1
[junit4] 2> 28616 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 28618 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=2
[junit4] 2> 28619 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 28621 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 28623 INFO (zkConnectionManagerCallback-152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 28623 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 28623 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 2> 28633 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Getting packages from packages.json...
[junit4] 2> 28634 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Got packages: []
[junit4] 1> [32mPosting manifest...[0m
[junit4] 2> 28705 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /package/question-answer/1.0.0/manifest.json
[junit4] 2> 28705 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore Unable to download file : /package/question-answer/1.0.0/manifest.json
[junit4] 2> 28711 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore persisted a file /package/question-answer/1.0.0/manifest.json and metadata. sizes 809 144
[junit4] 2> 28714 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/package/question-answer/1.0.0/manifest.json params={wt=javabin&version=2} status=0 QTime=10
[junit4] 1> [32mPosting artifacts...[0m
[junit4] 2> 28719 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore fullstory.der does not exist locally, downloading..
[junit4] 2> 28720 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /package/question-answer/1.0.0/question-answer-request-handler-1.0.jar.tmp
[junit4] 2> 28720 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore Unable to download file : /package/question-answer/1.0.0/question-answer-request-handler-1.0.jar.tmp
[junit4] 2> 28731 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore persisted a file /package/question-answer/1.0.0/question-answer-request-handler-1.0.jar.tmp and metadata. sizes 5652 246
[junit4] 2> 28734 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/package/question-answer/1.0.0/question-answer-request-handler-1.0.jar.tmp params={sig=C9UWKkucmY3UNzqn0VLneVMe9kCbJjw7Urc76vGenoRwp32xvNn5ZIGZ7G34xZP7cVjqn/ltDlLWBZ/C3eAtuw%3D%3D&wt=javabin&version=2} status=0 QTime=16
[junit4] 1> [32mExecuting Package API to register this package...[0m
[junit4] 2> 28738 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore fullstory.der does not exist locally, downloading..
[junit4] 2> 28738 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore fullstory.der does not exist locally, downloading..
[junit4] 2> 28740 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.p.PackageLoader /packages.json updated to version 1
[junit4] 2> 28740 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.p.PackageLoader A new package: question-answer introduced
[junit4] 2> 28740 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.p.PackageLoader A new version: 1.0.0 added for package: question-answer with artifacts [/package/question-answer/1.0.0/question-answer-request-handler-1.0.jar.tmp]
[junit4] 2> 28741 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore fullstory.der does not exist locally, downloading..
[junit4] 2> 28741 INFO (zkCallback-126-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 28741 INFO (zkCallback-126-thread-1) [ ] o.a.s.p.PackageLoader A new package: question-answer introduced
[junit4] 2> 28743 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.f.DistribPackageStore fullstory.der does not exist locally, downloading..
[junit4] 1> [32mResponse: {"responseHeader":{
[junit4] 1> "status":0,
[junit4] 1> "QTime":8}}[0m
[junit4] 1> [32mquestion-answer installed.[0m
[junit4] 2> 28744 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/filestore/package/question-answer/1.0.0]
[junit4] 2> 28744 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.p.PackageLoader version: 1.0.0 is the new latest in package: question-answer
[junit4] 2> 28744 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 28846 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: install
[junit4] 2> 28846 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 28884 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=1
[junit4] 2> 28891 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 28892 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 28893 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 28895 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 28899 INFO (zkConnectionManagerCallback-154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 28899 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 28900 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 1> [32mInstalled packages:
[junit4] 1> -----[0m
[junit4] 2> 28900 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Getting packages from packages.json...
[junit4] 2> 28937 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/package/question-answer/1.0.0/manifest.json params={} status=0 QTime=0
[junit4] 1> [32m{
[junit4] 1> "name":"question-answer",
[junit4] 2> 28939 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Got packages: [{
[junit4] 2> "name":"question-answer",
[junit4] 2> "version":"1.0.0"}]
[junit4] 1> "version":"1.0.0"}[0m
[junit4] 2> 29041 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: list-installed
[junit4] 2> 29077 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=abc&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 29080 INFO (OverseerThreadFactory-134-thread-1-processing-n:127.0.0.1:43297_solr) [n:127.0.0.1:43297_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection abc
[junit4] 2> 29195 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 29196 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 29198 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 29203 INFO (OverseerStateUpdate-73862165707489284-127.0.0.1:43297_solr-n_0000000000) [n:127.0.0.1:43297_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43297/solr",
[junit4] 2> "node_name":"127.0.0.1:43297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 29411 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr x:abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=abc_shard1_replica_n1&action=CREATE&numShards=1&collection=abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 29424 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 29439 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 29442 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 29442 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'abc_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 29443 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43297.solr.core.abc.shard1.replica_n1' (registry 'solr.core.abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1609fe82
[junit4] 2> 29443 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/./abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/./abc_shard1_replica_n1/data/]
[junit4] 2> 29504 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 29504 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 29506 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 29506 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 29508 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 29509 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 29509 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 29509 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668403941925191680
[junit4] 2> 29512 INFO (searcherExecutor-156-thread-1-processing-n:127.0.0.1:43297_solr x:abc_shard1_replica_n1 c:abc s:shard1 r:core_node2) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.SolrCore [abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 29514 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 29515 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/abc/leaders/shard1
[junit4] 2> 29518 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 29518 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 29518 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:43297/solr/abc_shard1_replica_n1/
[junit4] 2> 29519 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 29519 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:43297/solr/abc_shard1_replica_n1/ has no replicas
[junit4] 2> 29519 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/abc/leaders/shard1/leader after winning as /collections/abc/leader_elect/shard1/election/73862165707489284-core_node2-n_0000000000
[junit4] 2> 29521 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:43297/solr/abc_shard1_replica_n1/ shard1
[junit4] 2> 29623 INFO (zkCallback-126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/abc/state.json] for collection [abc] has occurred - updating... (live nodes size: [1])
[junit4] 2> 29624 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 29626 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=abc_shard1_replica_n1&action=CREATE&numShards=1&collection=abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=215
[junit4] 2> 29628 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 29726 INFO (zkCallback-126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/abc/state.json] for collection [abc] has occurred - updating... (live nodes size: [1])
[junit4] 2> 29726 INFO (zkCallback-126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/abc/state.json] for collection [abc] has occurred - updating... (live nodes size: [1])
[junit4] 2> 29727 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=abc&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=650
[junit4] 2> 29729 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf2&name=def&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 29732 INFO (OverseerThreadFactory-134-thread-2-processing-n:127.0.0.1:43297_solr) [n:127.0.0.1:43297_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection def
[junit4] 2> 29732 INFO (OverseerCollectionConfigSetProcessor-73862165707489284-127.0.0.1:43297_solr-n_0000000000) [n:127.0.0.1:43297_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 29939 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.abc.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=1
[junit4] 2> 29942 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 29945 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.abc.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 29947 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 29954 INFO (OverseerStateUpdate-73862165707489284-127.0.0.1:43297_solr-n_0000000000) [n:127.0.0.1:43297_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"def",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"def_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43297/solr",
[junit4] 2> "node_name":"127.0.0.1:43297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 30157 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr x:def_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf2&newCollection=true&name=def_shard1_replica_n1&action=CREATE&numShards=1&collection=def&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 30168 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 30177 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 30188 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 30188 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'def_shard1_replica_n1' using configuration from configset conf2, trusted=true
[junit4] 2> 30188 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43297.solr.core.def.shard1.replica_n1' (registry 'solr.core.def.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1609fe82
[junit4] 2> 30189 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.SolrCore [[def_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/./def_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/./def_shard1_replica_n1/data/]
[junit4] 2> 30246 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 30246 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 30248 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 30248 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 30250 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 30251 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 30251 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 30251 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668403942703235072
[junit4] 2> 30254 INFO (searcherExecutor-162-thread-1-processing-n:127.0.0.1:43297_solr x:def_shard1_replica_n1 c:def s:shard1 r:core_node2) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.SolrCore [def_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 30257 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/def/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 30257 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/def/leaders/shard1
[junit4] 2> 30259 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 30259 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 30259 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:43297/solr/def_shard1_replica_n1/
[junit4] 2> 30260 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 30260 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:43297/solr/def_shard1_replica_n1/ has no replicas
[junit4] 2> 30260 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/def/leaders/shard1/leader after winning as /collections/def/leader_elect/shard1/election/73862165707489284-core_node2-n_0000000000
[junit4] 2> 30261 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:43297/solr/def_shard1_replica_n1/ shard1
[junit4] 2> 30262 INFO (zkCallback-126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30262 INFO (zkCallback-126-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30364 INFO (zkCallback-126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30364 INFO (zkCallback-126-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30364 INFO (zkCallback-126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30366 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:def s:shard1 r:core_node2 x:def_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 30371 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf2&newCollection=true&name=def_shard1_replica_n1&action=CREATE&numShards=1&collection=def&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=214
[junit4] 2> 30373 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 30468 INFO (zkCallback-126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30468 INFO (zkCallback-126-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30468 INFO (zkCallback-126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30468 INFO (zkCallback-126-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/def/state.json] for collection [def] has occurred - updating... (live nodes size: [1])
[junit4] 2> 30469 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf2&name=def&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=740
[junit4] 2> 30470 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 30482 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=1
[junit4] 2> 30487 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 30488 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 30490 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 30491 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30494 INFO (zkConnectionManagerCallback-168-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30494 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30494 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 1> [32mPackage question-answer not deployed on any collection.[0m
[junit4] 2> 30666 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: list-deployed
[junit4] 2> 30666 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 30723 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=2
[junit4] 2> 30730 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 30735 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=5
[junit4] 2> 30740 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 30746 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30748 INFO (zkConnectionManagerCallback-170-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30748 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30748 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 2> 30749 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Getting packages from packages.json...
[junit4] 2> 30787 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/package/question-answer/1.0.0/manifest.json params={} status=0 QTime=0
[junit4] 2> 30789 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Got packages: [{
[junit4] 2> "name":"question-answer",
[junit4] 2> "version":"1.0.0"}]
[junit4] 2> 30822 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property params to be of version 0 for collection abc
[junit4] 2> 30824 INFO (Thread-50) [ ] o.a.s.c.SolrCore config update listener called for core abc_shard1_replica_n1
[junit4] 2> 30825 INFO (solrHandlerExecutor-171-thread-1-processing-n:127.0.0.1:43297_solr x:abc_shard1_replica_n1 c:abc s:shard1 r:core_node2) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 30826 INFO (Thread-50) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 30827 INFO (Thread-50) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 30935 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler I already have the expected version 0 of params
[junit4] 2> 30935 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 30935 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.S.Request [abc_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={params=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 30937 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Took 114.0ms to set the property params to be of version 0 for collection abc
[junit4] 2> 30939 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ZkController Persisted config data to node /configs/conf1/params.json
[junit4] 2> 30939 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property params to be of version 1 for collection abc
[junit4] 2> 30939 INFO (Thread-51) [ ] o.a.s.c.SolrCore config update listener called for core abc_shard1_replica_n1
[junit4] 2> 30940 INFO (Thread-51) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams conf resource params.json loaded . version : 1
[junit4] 2> 30940 INFO (Thread-51) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams request params refreshed to version 1
[junit4] 2> 30941 INFO (solrHandlerExecutor-173-thread-1-processing-n:127.0.0.1:43297_solr x:abc_shard1_replica_n1 c:abc s:shard1 r:core_node2) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 31078 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler I already have the expected version 1 of params
[junit4] 2> 31078 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 31078 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.S.Request [abc_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={params=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 31079 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Took 140.0ms to set the property params to be of version 1 for collection abc
[junit4] 1> [32mExecuting {"add-requesthandler":{"name":"/mypath2","class":"question-answer:fullstory.QARequestHandler"}} for path:/api/collections/abc/config[0m
[junit4] 1> Putting patterns into the list from the init()
[junit4] 2> 31085 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property overlay to be of version 0 for collection abc
[junit4] 2> 31085 INFO (Thread-52) [ ] o.a.s.c.SolrCore config update listener called for core abc_shard1_replica_n1
[junit4] 2> 31086 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrCore core reload abc_shard1_replica_n1
[junit4] 2> 31086 INFO (solrHandlerExecutor-175-thread-1-processing-n:127.0.0.1:43297_solr x:abc_shard1_replica_n1 c:abc s:shard1 r:core_node2) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 31091 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams conf resource params.json loaded . version : 1
[junit4] 2> 31091 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams request params refreshed to version 1
[junit4] 2> 31092 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 31101 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 31103 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 31103 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'abc_shard1_replica_n1' using configuration from configset conf1
[junit4] 2> 31131 INFO (Thread-52) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43297.solr.core.abc.shard1.replica_n1' (registry 'solr.core.abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1609fe82
[junit4] 2> 31131 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrCore [[abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/./abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp/solr.cloud.PackageManagerCLITest_D9290F182E495D84-002/tempDir-001/node1/./abc_shard1_replica_n1/data/]
[junit4] 2> 31178 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.p.PackagePluginHolder loading plugin: requestHandler -> /mypath2 using package question-answer:1.0.0
[junit4] 1> Putting patterns into the list from the init()
[junit4] 2> 31179 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 31180 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 31192 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 31192 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 31193 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 31193 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668403943690993664
[junit4] 2> 31197 INFO (Thread-52) [n:127.0.0.1:43297_solr ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 31198 INFO (searcherExecutor-177-thread-1-processing-n:127.0.0.1:43297_solr) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrCore [abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 31201 INFO (searcherExecutor-177-thread-1-processing-n:127.0.0.1:43297_solr) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrCore [abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 31227 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler I already have the expected version 0 of config
[junit4] 2> 31227 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 31227 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.S.Request [abc_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 31228 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Took 143.0ms to set the property overlay to be of version 0 for collection abc
[junit4] 2> 31229 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.c.SolrCore [abc_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@4230f493
[junit4] 2> 31229 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.abc.shard1.replica_n1 tag=SolrCore@4230f493
[junit4] 2> 31229 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ce530d2: rootName = solr_43297, domain = solr.core.abc.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.abc.shard1.replica_n1/com.codahale.metrics.MetricRegistry@4c554306
[junit4] 2> 31241 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.abc.shard1.leader tag=SolrCore@4230f493
[junit4] 2> 31244 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.ZkController Persisted config data to node /configs/conf1/params.json
[junit4] 2> 31245 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property params to be of version 2 for collection abc
[junit4] 2> 31245 INFO (Thread-53) [ ] o.a.s.c.SolrCore config update listener called for core abc_shard1_replica_n1
[junit4] 2> 31246 INFO (solrHandlerExecutor-182-thread-1-processing-n:127.0.0.1:43297_solr x:abc_shard1_replica_n1 c:abc s:shard1 r:core_node2) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 31246 INFO (Thread-53) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams conf resource params.json loaded . version : 2
[junit4] 2> 31246 INFO (Thread-53) [n:127.0.0.1:43297_solr ] o.a.s.c.RequestParams request params refreshed to version 2
[junit4] 2> 31390 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler I already have the expected version 2 of params
[junit4] 2> 31390 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 31390 INFO (qtp1170929111-254) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.S.Request [abc_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={params=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 31392 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.h.SolrConfigHandler Took 147.0ms to set the property params to be of version 2 for collection abc
[junit4] 1> [32mExecuting https://127.0.0.1:43297/api/collections/abc/config/requestHandler?componentName=/mypath2&meta=true for collection:abc[0m
[junit4] 1> [32m{
[junit4] 1> "responseHeader":{
[junit4] 1> "status":0,
[junit4] 1> "QTime":0},
[junit4] 1> "config":{"requestHandler":{"/mypath2":{
[junit4] 1> "name":"/mypath2",
[junit4] 1> "class":"question-answer:fullstory.QARequestHandler",
[junit4] 1> "_packageinfo_":{
[junit4] 1> "package":"question-answer",
[junit4] 1> "version":"1.0.0",
[junit4] 1> "files":["/package/question-answer/1.0.0/question-answer-request-handler-1.0.jar.tmp"],
[junit4] 1> "manifest":"/package/question-answer/1.0.0/manifest.json",
[junit4] 1> "manifestSHA512":"a91ab5a2c5abd53f0f72c256592c2be8b667cecb8226ac054aeed4d28aac9d743311442f2d58539bb83663a19bd1efb310aaadfd77bea458f3d475161721a114"}}}}}
[junit4] 1> [0m
[junit4] 1> [32mActual: 1.0.0, expected: 1.0.0[0m
[junit4] 1> [32mDeployed on [abc] and verified package: question-answer, version: 1.0.0[0m
[junit4] 1> [32mDeployment successful[0m
[junit4] 2> 31542 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: deploy
[junit4] 2> 31578 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.S.Request [abc_shard1_replica_n1] webapp=/solr path=/config/params params={meta=true} status=0 QTime=0
[junit4] 2> 31588 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr c:abc s:shard1 r:core_node2 x:abc_shard1_replica_n1 ] o.a.s.c.S.Request [abc_shard1_replica_n1] webapp=/solr path=/config/requestHandler params={meta=true&componentName=/mypath2} status=0 QTime=0
[junit4] 2> 31589 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 31601 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=1
[junit4] 2> 31606 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 31607 INFO (qtp1170929111-251) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 31608 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 31609 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 31610 INFO (zkConnectionManagerCallback-185-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31610 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 31611 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 1> [32mCollections on which package question-answer was deployed:[0m
[junit4] 1> [32m abc(question-answer:1.0.0)[0m
[junit4] 2> 31734 INFO (OverseerCollectionConfigSetProcessor-73862165707489284-127.0.0.1:43297_solr-n_0000000000) [n:127.0.0.1:43297_solr ] 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> 31758 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: list-deployed
[junit4] 2> 31758 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 31801 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=2
[junit4] 2> 31809 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 31811 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=2
[junit4] 2> 31812 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 31816 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 31817 INFO (zkConnectionManagerCallback-187-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31818 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 31818 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 2> 31856 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Getting packages from packages.json...
[junit4] 2> 31867 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/package/question-answer/1.0.0/manifest.json params={} status=0 QTime=0
[junit4] 1> [32mPackages deployed on abc:[0m
[junit4] 1> [32m {
[junit4] 1> "name":"question-answer",
[junit4] 1> "version":"1.0.0"}[0m
[junit4] 2> 31870 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Got packages: [{
[junit4] 2> "name":"question-answer",
[junit4] 2> "version":"1.0.0"}]
[junit4] 2> 31973 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Finished: list-deployed
[junit4] 2> 31973 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.PackageManagerCLITest Testing auto-update to latest installed
[junit4] 2> 31973 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool Solr url:https://127.0.0.1:43297/solr, solr base url: https://127.0.0.1:43297
[junit4] 2> 32013 INFO (qtp1170929111-253) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=1
[junit4] 2> 32019 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 32021 INFO (qtp1170929111-255) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 32022 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.u.PackageTool ZK: 127.0.0.1:35973/solr
[junit4] 2> 32024 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 32026 INFO (zkConnectionManagerCallback-189-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 32026 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 32026 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Done initializing a zkClient instance...
[junit4] 2> 32027 INFO (TEST-PackageManagerCLITest.testPackageManager-seed#[D9290F182E495D84]) [ ] o.a.s.p.PackageManager Getting packages from packages.json...
[junit4] 2> 32070 INFO (qtp1170929111-252) [n:127.0.0.1:43297_solr ] o.a.s.s.HttpSolrCall [adm
[...truncated too long message...]
-Java11/solr/build/solr-core/classes/test/org/apache/hadoop/package-info.class
[copy] Copying 1 file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/classes/test
common-solr.compile-test:
compile-test:
BUILD SUCCESSFUL
Total time: 58 seconds
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.PackageManagerCLITest" -Dtests.showOutput=onerror -Dtests.multiplier=2 -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.seed=D9290F182E495D84 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=yo-BJ -Dtests.timezone=Asia/Riyadh -Dtests.asserts=true -Dtests.file.encoding=UTF-8
Buildfile: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/core/build.xml
-clover.disable:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/lucene/top-level-ivy-settings.xml
install-junit4-taskdef:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.17 in public
[ivy:cachepath] :: resolution report :: resolve 14ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-init-totals:
-test:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: D9290F182E495D84
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/temp
[junit4] <JUnit4> says jolly good day! Master seed: D9290F182E495D84
[junit4] Executing 5 suites with 3 JVMs.
[junit4]
[junit4] Started J0 PID(29641@localhost).
[junit4] Started J2 PID(29642@localhost).
[junit4] Started J1 PID(29643@localhost).
[junit4] Suite: org.apache.solr.cloud.PackageManagerCLITest
[junit4] OK 8.51s J1 | PackageManagerCLITest.testPackageManager
[junit4] Completed [1/5] on J1 in 23.10s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.PackageManagerCLITest
[junit4] OK 8.69s J2 | PackageManagerCLITest.testPackageManager
[junit4] Completed [2/5] on J2 in 23.13s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.PackageManagerCLITest
[junit4] OK 8.73s J0 | PackageManagerCLITest.testPackageManager
[junit4] Completed [3/5] on J0 in 23.03s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.PackageManagerCLITest
[junit4] OK 6.05s J1 | PackageManagerCLITest.testPackageManager
[junit4] Completed [4/5] on J1 in 16.71s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.PackageManagerCLITest
[junit4] OK 6.02s J0 | PackageManagerCLITest.testPackageManager
[junit4] Completed [5/5] on J0 in 16.70s, 1 test
[junit4]
[junit4] JVM J0: 0.68 .. 41.38 = 40.70s
[junit4] JVM J1: 0.72 .. 41.37 = 40.66s
[junit4] JVM J2: 0.69 .. 24.34 = 23.65s
[junit4] Execution time total: 41 seconds
[junit4] Tests summary: 5 suites, 5 tests
[junit4] Could not remove temporary path: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J0/temp])
[junit4] Could not remove temporary path: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J2 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-repro-Java11/solr/build/solr-core/test/J2/temp])
[echo] 5 slowest tests:
[junit4:tophints] 490.51s | org.apache.solr.cloud.RollingRestartTest
[junit4:tophints] 19.68s | org.apache.solr.cloud.PackageManagerCLITest
-check-totals:
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.codehaus.groovy.reflection.CachedClass (file:/home/jenkins/.ivy2/cache/org.codehaus.groovy/groovy-all/jars/groovy-all-2.4.17.jar) to method java.lang.Object.finalize()
WARNING: Please consider reporting this to the maintainers of org.codehaus.groovy.reflection.CachedClass
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
test-nocompile:
BUILD SUCCESSFUL
Total time: 43 seconds
[repro] Failures original seeds at the tip of master:
[repro] 0/5 failed: org.apache.solr.cloud.PackageManagerCLITest
[repro] git checkout db9cd0cebb8c73d51bdd68a30c9c229c4745cb52
Note: checking out 'db9cd0cebb8c73d51bdd68a30c9c229c4745cb52'.
You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by performing another checkout.
If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -b with the checkout command again. Example:
git checkout -b <new-branch-name>
HEAD is now at db9cd0cebb8 SOLR-14526: fix or suppress warnings in apache/solr/core
[repro] Exiting with code 256
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2805)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2684)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2665)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3052)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:957)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
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:1840)
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:3054)
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:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
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:2663)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
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:1840)
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)
[JENKINS] Lucene-Solr-repro-Java11 - Build # 1135 - Failure
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-repro-Java11/1135/
[...truncated 29 lines...]
[repro] Jenkins log URL: https://builds.apache.org/job/Lucene-Solr-SmokeRelease-master/1706/consoleText
[...truncated 48 lines...]
raise RuntimeError('ERROR: fetching %s : %s' % (url, e))
RuntimeError: ERROR: fetching https://builds.apache.org/job/Lucene-Solr-SmokeRelease-master/1706/consoleText : <urlopen error [SSL: CERTIFICATE_VERIFY_FAILED] certificate verify failed (_ssl.c:645)>
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any