You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/03/18 21:04:47 UTC
[JENKINS-Experimental-GC] Lucene-Solr-8.5-Linux
(64bit/jdk-15-ea+14) - Build # 832 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-Linux/832/
Java: 64bit/jdk-15-ea+14 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
1 tests failed.
FAILED: org.apache.solr.pkg.TestPackages.testPluginLoading
Error Message:
req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1932/0x00007f39c55c2cb0@44839741 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , { "responseHeader":{ "status":0, "QTime":0}, "config":{"queryResponseWriter":{"json1":{ "name":"json1", "class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
Stack Trace:
java.lang.AssertionError: req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1932/0x00007f39c55c2cb0@44839741 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , {
"responseHeader":{
"status":0,
"QTime":0},
"config":{"queryResponseWriter":{"json1":{
"name":"json1",
"class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
at __randomizedtesting.SeedInfo.seed([44B1AB9774BBCAD0:92374B6A97A50D4A]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:242)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:213)
at org.apache.solr.pkg.TestPackages.verifyCmponent(TestPackages.java:415)
at org.apache.solr.pkg.TestPackages.testPluginLoading(TestPackages.java:153)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 15812 lines...]
[junit4] Suite: org.apache.solr.pkg.TestPackages
[junit4] 2> 1179942 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1179942 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1179943 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/data-dir-94-001
[junit4] 2> 1179943 WARN (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=351 numCloses=351
[junit4] 2> 1179943 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1179944 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1179946 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPluginLoading
[junit4] 2> 1179947 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001
[junit4] 2> 1179947 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1179947 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1179947 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1180047 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer start zk server on port:32785
[junit4] 2> 1180048 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:32785
[junit4] 2> 1180048 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:32785
[junit4] 2> 1180048 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 32785
[junit4] 2> 1180049 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180051 INFO (zkConnectionManagerCallback-13661-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180051 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180053 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180053 INFO (zkConnectionManagerCallback-13663-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180053 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180054 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180054 INFO (zkConnectionManagerCallback-13665-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180054 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180159 WARN (jetty-launcher-13666-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1180159 WARN (jetty-launcher-13666-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1180159 WARN (jetty-launcher-13666-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1180159 WARN (jetty-launcher-13666-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+14-561
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+14-561
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+14-561
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1180160 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+14-561
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2cb0562d{/solr,null,AVAILABLE}
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e5c2ea1{/solr,null,AVAILABLE}
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1180174 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@593f8712{/solr,null,AVAILABLE}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5acf3503{/solr,null,AVAILABLE}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47627371{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40197}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.e.j.s.Server Started @1180427ms
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@62bc5638{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46283}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.e.j.s.Server Started @1180427ms
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40197}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46283}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a64e783{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42703}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.e.j.s.Server Started @1180427ms
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42703}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7b01e69a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38939}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-4) [ ] o.e.j.s.Server Started @1180427ms
[junit4] 2> 1180175 ERROR (jetty-launcher-13666-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1180175 ERROR (jetty-launcher-13666-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1180175 ERROR (jetty-launcher-13666-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38939}
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T20:51:10.378886Z
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T20:51:10.378886Z
[junit4] 2> 1180175 ERROR (jetty-launcher-13666-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1180175 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1180176 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T20:51:10.378896Z
[junit4] 2> 1180176 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.5.0
[junit4] 2> 1180176 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1180176 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1180176 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T20:51:10.379040Z
[junit4] 2> 1180177 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180177 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180185 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180187 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180188 INFO (zkConnectionManagerCallback-13671-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180188 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180189 INFO (zkConnectionManagerCallback-13674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180189 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180189 INFO (zkConnectionManagerCallback-13670-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180189 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180189 INFO (zkConnectionManagerCallback-13672-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180189 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180197 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1180197 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1180197 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1180197 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1180430 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1180431 WARN (jetty-launcher-13666-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@66df1734[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180431 WARN (jetty-launcher-13666-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@66df1734[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180433 WARN (jetty-launcher-13666-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@416670f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180433 WARN (jetty-launcher-13666-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@416670f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180434 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32785/solr
[junit4] 2> 1180437 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180437 INFO (zkConnectionManagerCallback-13682-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180437 INFO (jetty-launcher-13666-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180483 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1180483 WARN (jetty-launcher-13666-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@55e05f36[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180483 WARN (jetty-launcher-13666-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@55e05f36[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180485 WARN (jetty-launcher-13666-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2c0f92f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180485 WARN (jetty-launcher-13666-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2c0f92f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180485 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32785/solr
[junit4] 2> 1180486 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180487 INFO (zkConnectionManagerCallback-13688-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180487 INFO (jetty-launcher-13666-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180625 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1180626 WARN (jetty-launcher-13666-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@620a2d2f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180626 WARN (jetty-launcher-13666-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@620a2d2f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180626 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1180627 WARN (jetty-launcher-13666-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43058d21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180627 WARN (jetty-launcher-13666-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43058d21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180628 WARN (jetty-launcher-13666-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@659bd13a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180628 WARN (jetty-launcher-13666-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@659bd13a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180628 WARN (jetty-launcher-13666-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3abd7237[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180628 WARN (jetty-launcher-13666-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3abd7237[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1180629 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32785/solr
[junit4] 2> 1180629 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32785/solr
[junit4] 2> 1180630 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180634 INFO (zkConnectionManagerCallback-13700-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180634 INFO (jetty-launcher-13666-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180634 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180634 INFO (zkConnectionManagerCallback-13702-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180634 INFO (jetty-launcher-13666-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180705 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180706 INFO (zkConnectionManagerCallback-13704-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180706 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180741 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42703_solr
[junit4] 2> 1180741 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.c.Overseer Overseer (id=72070021001969675-127.0.0.1:42703_solr-n_0000000000) starting
[junit4] 2> 1180743 INFO (OverseerStateUpdate-72070021001969675-127.0.0.1:42703_solr-n_0000000000) [n:127.0.0.1:42703_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42703_solr
[junit4] 2> 1180744 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42703_solr
[junit4] 2> 1180744 INFO (OverseerStateUpdate-72070021001969675-127.0.0.1:42703_solr-n_0000000000) [n:127.0.0.1:42703_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1180745 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1180746 WARN (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_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> 1180756 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1180769 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42703.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180773 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42703.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180773 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42703.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180774 INFO (jetty-launcher-13666-thread-1) [n:127.0.0.1:42703_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node2/.
[junit4] 2> 1180805 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180806 INFO (zkConnectionManagerCallback-13710-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180806 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180808 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1180810 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46283_solr as DOWN
[junit4] 2> 1180811 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1180811 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46283_solr
[junit4] 2> 1180811 INFO (zkCallback-13703-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1180812 INFO (zkCallback-13709-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1180812 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1180812 WARN (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_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> 1180821 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1180832 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46283.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180836 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46283.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180836 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46283.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180837 INFO (jetty-launcher-13666-thread-3) [n:127.0.0.1:46283_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node3/.
[junit4] 2> 1180905 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1180906 INFO (zkConnectionManagerCallback-13716-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1180906 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1180908 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1180910 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38939_solr as DOWN
[junit4] 2> 1180910 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1180910 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38939_solr
[junit4] 2> 1180910 INFO (zkCallback-13703-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1180910 INFO (zkCallback-13709-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1180911 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1180911 WARN (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_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> 1180913 INFO (zkCallback-13715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1180920 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1180932 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38939.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180937 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38939.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180937 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38939.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1180938 INFO (jetty-launcher-13666-thread-4) [n:127.0.0.1:38939_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node4/.
[junit4] 2> 1181005 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1181007 INFO (zkConnectionManagerCallback-13722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1181007 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1181014 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1181016 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40197_solr as DOWN
[junit4] 2> 1181016 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1181016 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40197_solr
[junit4] 2> 1181017 INFO (zkCallback-13703-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1181017 INFO (zkCallback-13721-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1181017 INFO (zkCallback-13715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1181017 INFO (zkCallback-13709-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1181018 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1181018 WARN (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_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> 1181029 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1181040 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40197.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1181045 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40197.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1181045 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40197.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1181046 INFO (jetty-launcher-13666-thread-2) [n:127.0.0.1:40197_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node1/.
[junit4] 2> 1181068 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1181068 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1181069 INFO (zkConnectionManagerCallback-13731-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1181069 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1181069 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1181070 INFO (TEST-TestPackages.testPluginLoading-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32785/solr ready
[junit4] 2> 1181084 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:38939_solr} status=0 QTime=0
[junit4] 2> 1181084 INFO (updateExecutor-13684-thread-1-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:38939_solr
[junit4] 2> 1181085 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:38939_solr} status=0 QTime=0
[junit4] 2> 1181085 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:38939_solr
[junit4] 2> 1181086 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 1181086 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:38939_solr} status=0 QTime=0
[junit4] 2> 1181086 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:38939_solr
[junit4] 2> 1181086 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 1181086 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 1181087 INFO (qtp1975916114-31338) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={sync=true} status=0 QTime=8
[junit4] 2> 1181087 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 1> sync resp: http://127.0.0.1:38939/api/node/files/_trusted_/keys/pub_key512.der?sync=true ,is: null
[junit4] 2> 1181087 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 1181087 INFO (qtp1975916114-31338) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 1181088 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 1181088 INFO (updateExecutor-13684-thread-1-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 1181088 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 1181088 INFO (updateExecutor-13684-thread-1-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 1181088 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 1181088 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 1181090 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 1181091 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181093 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181094 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181098 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181099 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/runtimelibs.jar
[junit4] 2> 1181100 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181101 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181103 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181104 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/runtimelibs.jar
[junit4] 2> 1181105 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1181106 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:42703_solr} status=0 QTime=0
[junit4] 2> 1181106 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:42703_solr
[junit4] 2> 1181106 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:42703_solr} status=0 QTime=0
[junit4] 2> 1181107 INFO (updateExecutor-13695-thread-2-processing-n:127.0.0.1:38939_solr) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:42703_solr
[junit4] 2> 1181107 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:42703_solr} status=0 QTime=0
[junit4] 2> 1181107 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 1181107 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:42703_solr
[junit4] 2> 1181107 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/runtimelibs.jar params={sig=L3q/qIGs4NaF6JiO0ZkMUFa88j0OmYc%2BI6O7BOdNuMct/xoZ4h73aZHZGc0%2BnmI1f/U3bOlMPINlSOM6LK3JpQ%3D%3D&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 1181107 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 1181107 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 1181108 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 1181108 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1181108 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 1181109 INFO (updateExecutor-13695-thread-2-processing-n:127.0.0.1:38939_solr) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1181109 INFO (updateExecutor-13695-thread-2-processing-n:127.0.0.1:38939_solr) [n:127.0.0.1:38939_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 1181110 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 1181110 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 1181110 INFO (qtp1688409447-31362) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181111 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1181111 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 1181113 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181115 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181117 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181119 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181120 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/testurpv1.jar
[junit4] 2> 1181121 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181121 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181122 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181122 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/testurpv1.jar
[junit4] 2> 1181123 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1181124 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:38939_solr} status=0 QTime=0
[junit4] 2> 1181124 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:38939_solr
[junit4] 2> 1181124 INFO (qtp1688409447-31362) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:38939_solr} status=0 QTime=0
[junit4] 2> 1181124 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 1181124 INFO (updateExecutor-13684-thread-2-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:38939_solr
[junit4] 2> 1181125 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:38939_solr
[junit4] 2> 1181125 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:38939_solr} status=0 QTime=0
[junit4] 2> 1181125 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/testurpv1.jar params={sig=h6UmMzuPqu4hQFGLBMJh/6kDSEXpJlgLsQDXx0KuxXWkV5giilRP57K3towiJRh2J%2BrqihqIghNCi3YgzgUnWQ%3D%3D&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1181126 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 1181126 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 1181126 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 1181127 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1181127 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 1181127 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181127 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 1181128 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1181128 INFO (updateExecutor-13692-thread-1-processing-n:127.0.0.1:40197_solr) [n:127.0.0.1:40197_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 1181130 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 1181130 INFO (updateExecutor-13684-thread-2-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1181130 INFO (updateExecutor-13684-thread-2-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 1181230 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181232 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181234 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181236 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181239 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181240 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/expressible.jar
[junit4] 2> 1181241 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181241 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181242 INFO (qtp1975916114-31338) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181242 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/expressible.jar
[junit4] 2> 1181243 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1181244 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:40197_solr} status=0 QTime=0
[junit4] 2> 1181244 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:40197_solr
[junit4] 2> 1181244 INFO (qtp1975916114-31343) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:40197_solr} status=0 QTime=0
[junit4] 2> 1181244 INFO (updateExecutor-13695-thread-3-processing-n:127.0.0.1:38939_solr) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:40197_solr
[junit4] 2> 1181244 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 1181244 INFO (qtp1688409447-31340) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:40197_solr} status=0 QTime=0
[junit4] 2> 1181245 INFO (updateExecutor-13684-thread-2-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:40197_solr
[junit4] 2> 1181245 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 1181245 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/expressible.jar params={sig=ZOT11arAiPmPZYOHzqodiNnxO9pRyRozWZEBX8XGjU1/HJptFnZK%2BDI7eXnUtbNaMcbXE2Ze8hh4M/eGyhY8BQ%3D%3D&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1181245 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 1181245 INFO (qtp1235588149-31375) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 1181245 INFO (qtp1235588149-31348) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 1181245 INFO (qtp1235588149-31359) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 1181246 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1181246 INFO (updateExecutor-13678-thread-1-processing-n:127.0.0.1:46283_solr) [n:127.0.0.1:46283_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 1181246 INFO (updateExecutor-13695-thread-3-processing-n:127.0.0.1:38939_solr) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1181246 INFO (updateExecutor-13695-thread-3-processing-n:127.0.0.1:38939_solr) [n:127.0.0.1:38939_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 1181246 INFO (updateExecutor-13684-thread-2-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1181246 INFO (updateExecutor-13684-thread-2-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 1181247 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181249 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181250 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181252 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181257 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181257 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181258 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181259 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181260 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181260 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181263 DEBUG (zkCallback-13721-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1181263 DEBUG (zkCallback-13703-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1181263 DEBUG (zkCallback-13715-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1181263 DEBUG (zkCallback-13709-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1181263 INFO (qtp1235588149-31375) [n:127.0.0.1:40197_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181263 INFO (qtp1688409447-31362) [n:127.0.0.1:42703_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181264 INFO (qtp1820046791-31344) [n:127.0.0.1:46283_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1181264 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.p.PackageLoader /packages.json updated to version 1
[junit4] 2> 1181264 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1181265 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1181266 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181267 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181268 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181268 INFO (zkCallback-13715-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1181268 INFO (zkCallback-13709-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1181268 INFO (zkCallback-13703-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1181268 INFO (zkCallback-13709-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1181268 INFO (zkCallback-13715-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1181268 INFO (zkCallback-13703-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1181268 INFO (zkCallback-13703-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1181268 INFO (zkCallback-13709-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1181268 INFO (zkCallback-13721-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1181268 INFO (zkCallback-13721-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1181268 INFO (zkCallback-13721-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1181269 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181269 INFO (zkCallback-13703-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181269 INFO (zkCallback-13709-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181269 INFO (zkCallback-13721-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181271 INFO (zkCallback-13703-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181271 INFO (zkCallback-13709-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181271 INFO (zkCallback-13721-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181271 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181272 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181272 INFO (zkCallback-13703-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181272 INFO (zkCallback-13721-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181272 INFO (zkCallback-13709-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181272 INFO (zkCallback-13703-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181273 INFO (zkCallback-13709-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181273 INFO (zkCallback-13721-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181273 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 1181273 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 1181273 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 1181273 INFO (zkCallback-13703-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181274 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1181274 INFO (zkCallback-13703-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181274 INFO (qtp1975916114-31351) [n:127.0.0.1:38939_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=21
[junit4] 2> 1181274 INFO (zkCallback-13709-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181274 INFO (zkCallback-13721-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181275 INFO (zkCallback-13721-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181275 INFO (zkCallback-13709-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1181275 INFO (qtp1688409447-31354) [n:127.0.0.1:42703_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=testPluginLoadingColl&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1181275 INFO (zkCallback-13703-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13703-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13703-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13703-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1181275 INFO (zkCallback-13709-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13721-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13721-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13709-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13721-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13709-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 1181275 INFO (zkCallback-13721-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1181275 INFO (zkCallback-13709-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1181276 INFO (OverseerThreadFactory-5561-thread-1-processing-n:127.0.0.1:42703_solr) [n:127.0.0.1:42703_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testPluginLoadingColl
[junit4] 2> 1181380 INFO (OverseerStateUpdate-72070021001969675-127.0.0.1:42703_solr-n_0000000000) [n:127.0.0.1:42703_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46283/solr",
[junit4] 2> "node_name":"127.0.0.1:46283_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1181381 INFO (OverseerStateUpdate-72070021001969675-127.0.0.1:42703_solr-n_0000000000) [n:127.0.0.1:42703_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40197/solr",
[junit4] 2> "node_name":"127.0.0.1:40197_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1181382 INFO (OverseerStateUpdate-72070021001969675-127.0.0.1:42703_solr-n_0000000000) [n:127.0.0.1:42703_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38939/solr",
[junit4] 2> "node_name":"127.0.0.1:38939_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1181382 INFO (OverseerStateUpdate-72070021001969675-127.0.0.1:42703_solr-n_0000000000) [n:127.0.0.1:42703_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42703/solr",
[junit4] 2> "node_name":"127.0.0.1:42703_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1181591 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n4&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1181591 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n6&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1181591 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n1&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1181591 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n2&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1181592 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1181602 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1181606 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1181607 INFO (qtp1975916114-31347) [n:127.0.0.1:38939_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1181607 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1181624 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1181626 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1181626 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 1181627 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46283.solr.core.testPluginLoadingColl.shard1.replica_n1' (registry 'solr.core.testPluginLoadingColl.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1181627 INFO (qtp1820046791-31356) [n:127.0.0.1:46283_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node3/testPluginLoadingColl_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node3/./testPluginLoadingColl_shard1_replica_n1/data/]
[junit4] 2> 1181628 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1181629 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1181629 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 1181630 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40197.solr.core.testPluginLoadingColl.shard1.replica_n2' (registry 'solr.core.testPluginLoadingColl.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1181630 INFO (qtp1235588149-31374) [n:127.0.0.1:40197_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node1/testPluginLoadingColl_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node1/./testPluginLoadingColl_shard1_replica_n2/data/]
[junit4] 2> 1181635 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard2_replica_n6] Schema name=minimal
[junit4] 2> 1181637 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1181637 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 1181637 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42703.solr.core.testPluginLoadingColl.shard2.replica_n6' (registry 'solr.core.testPluginLoadingColl.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@350576d9
[junit4] 2> 1181637 INFO (qtp1688409447-31361) [n:127.0.0.1:42703_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001/tempDir-001/node2/testPluginLoadingColl_shard2_replica_n6], dataDir=[/home/jenki
[...truncated too long message...]
er (id=72070021756616714-127.0.0.1:44349_solr-n_0000000000) closing
[junit4] 2> 1193138 INFO (zkCallback-13826-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1193139 INFO (jetty-closer-13837-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1193139 INFO (jetty-closer-13837-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d841120: rootName = solr_38247, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7f54a43d
[junit4] 2> 1193141 INFO (jetty-closer-13837-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1193141 INFO (jetty-closer-13837-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75d6ecd9: rootName = solr_38247, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@22afe83d
[junit4] 2> 1193141 INFO (jetty-closer-13837-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1193531 INFO (jetty-closer-13837-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72070021756616714-127.0.0.1:44349_solr-n_0000000000) closing
[junit4] 2> 1193532 INFO (jetty-closer-13837-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1f60c2b3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1193532 INFO (jetty-closer-13837-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@66b81ed2{/solr,null,UNAVAILABLE}
[junit4] 2> 1193532 INFO (jetty-closer-13837-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1202693 INFO (jetty-closer-13837-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@19c79e31{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1202693 INFO (jetty-closer-13837-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@28af7d8b{/solr,null,UNAVAILABLE}
[junit4] 2> 1202693 INFO (jetty-closer-13837-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1202819 INFO (jetty-closer-13837-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4731d4a1{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1202819 INFO (jetty-closer-13837-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4f918057{/solr,null,UNAVAILABLE}
[junit4] 2> 1202819 INFO (jetty-closer-13837-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1202858 INFO (jetty-closer-13837-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@384a0ed4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1202859 INFO (jetty-closer-13837-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@21ab7cbf{/solr,null,UNAVAILABLE}
[junit4] 2> 1202859 INFO (jetty-closer-13837-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1202864 INFO (TEST-TestPackages.testAPI-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1203074 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> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/packages.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/72070021756616714-127.0.0.1:44349_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/live_nodes
[junit4] 2> 10 /solr/overseer/queue
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 1203075 INFO (TEST-TestPackages.testAPI-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41703
[junit4] 2> 1203075 INFO (TEST-TestPackages.testAPI-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41703
[junit4] 2> 1203075 INFO (TEST-TestPackages.testAPI-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41703
[junit4] 2> 1203077 INFO (TEST-TestPackages.testAPI-seed#[44B1AB9774BBCAD0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAPI
[junit4] 2> 1203078 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1203078 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41703
[junit4] 2> 1203078 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41703
[junit4] 2> 1203078 INFO (SUITE-TestPackages-seed#[44B1AB9774BBCAD0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41703
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J4/temp/solr.pkg.TestPackages_44B1AB9774BBCAD0-001
[junit4] 2> Mar 18, 2020 8:51:33 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(Lucene84): {}, docValues:{}, maxPointsInLeafNode=601, maxMBSortInHeap=5.992323804954292, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@559b3ce4), locale=kam, timezone=America/Virgin
[junit4] 2> NOTE: Linux 5.3.0-42-generic amd64/Oracle Corporation 15-ea (64-bit)/cpus=16,threads=1,free=312475648,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [JvmMetricsTest, TestRandomFaceting, HighlighterTest, BadCopyFieldTest, SuggesterFSTTest, BlockCacheTest, TestXmlQParser, TestRawResponseWriter, TestCloudInspectUtil, ClusterStateUpdateTest, DistributedQueryElevationComponentTest, TestFunctionQuery, TestXIncludeConfig, CdcrOpsAndBoundariesTest, SplitHandlerTest, TestCloudJSONFacetSKG, TestDynamicURP, SchemaApiFailureTest, VersionInfoTest, TestDistributedSearch, TestSkipOverseerOperations, ResponseHeaderTest, IndexSizeEstimatorTest, DeleteReplicaTest, TestBM25SimilarityFactory, SystemLogListenerTest, TestFastLRUCache, SignatureUpdateProcessorFactoryTest, FullHLLTest, DistributedFacetPivotSmallAdvancedTest, TestHdfsBackupRestoreCore, TestPayloadCheckQParserPlugin, TestLegacyTerms, MergeStrategyTest, TestCoreDiscovery, SplitShardTest, V2ApiIntegrationTest, HdfsAutoAddReplicasIntegrationTest, TestSolr4Spatial, TestExceedMaxTermLength, TestSchemaManager, CollectionsAPIDistributedZkTest, ConnectionManagerTest, TestJettySolrRunner, SolrInfoBeanTest, TestTrackingShardHandlerFactory, TestSolrCloudWithHadoopAuthPlugin, TestWaitForStateWithJettyShutdowns, ChaosMonkeySafeLeaderWithPullReplicasTest, TestCoreAdminApis, TestDocTermOrdsUninvertLimit, LeaderElectionTest, TestSolrQueryParser, TestMaxScoreQueryParser, DistributedQueueTest, MetricTriggerTest, TestHashQParserPlugin, TestStressLiveNodes, AuditLoggerIntegrationTest, TestRandomCollapseQParserPlugin, TestDeleteCollectionOnDownNodes, TestRequestStatusCollectionAPI, NestedAtomicUpdateTest, DistanceUnitsTest, TestSimTriggerIntegration, TestCloudSearcherWarming, TestRestManager, TestSQLHandler, TestFieldResource, SplitByPrefixTest, HdfsDirectoryFactoryTest, TestSchemaVersionResource, TestFaceting, ShardsWhitelistTest, HdfsRecoveryZkTest, TestSimComputePlanAction, TestSimExecutePlanAction, TestSimExtremeIndexing, TestSimGenericDistributedQueue, ZkStateReaderTest, ZkStateWriterTest, BlobRepositoryCloudTest, QueryResultKeyTest, TestCodecSupport, TestInfoStreamLogging, TestJmxIntegration, TestNRTOpen, TestQuerySenderListener, TestShardHandlerFactory, TestSolrConfigHandler, XsltUpdateRequestHandlerTest, CoreAdminCreateDiscoverTest, CoreAdminHandlerTest, PropertiesRequestHandlerTest, CloudReplicaSourceTest, CustomTermsComponentTest, FacetPivotSmallTest, InfixSuggestersTest, StatsComponentTest, TestPackages]
[junit4] Completed [716/900 (1!)] on J4 in 23.77s, 2 tests, 1 failure <<< FAILURES!
[...truncated 45366 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-Linux/832/consoleText
[repro] Revision: 1050e459027c35d15d4d1ea91a6c14cf2052594f
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line: ant test -Dtestcase=TestPackages -Dtests.method=testPluginLoading -Dtests.seed=44B1AB9774BBCAD0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=kam -Dtests.timezone=America/Virgin -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestPackages
[repro] ant compile-test
[...truncated 3423 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestPackages" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=44B1AB9774BBCAD0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=kam -Dtests.timezone=America/Virgin -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.pkg.TestPackages
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS-Experimental-GC] Lucene-Solr-8.5-Linux (64bit/jdk-14) -
Build # 833 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-Linux/833/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
1 tests failed.
FAILED: org.apache.solr.pkg.TestPackages.testPluginLoading
Error Message:
req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1663/0x00007f7ba12d84b0@139098c3 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , { "responseHeader":{ "status":0, "QTime":0}, "config":{"queryResponseWriter":{"json1":{ "name":"json1", "class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
Stack Trace:
java.lang.AssertionError: req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1663/0x00007f7ba12d84b0@139098c3 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , {
"responseHeader":{
"status":0,
"QTime":0},
"config":{"queryResponseWriter":{"json1":{
"name":"json1",
"class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
at __randomizedtesting.SeedInfo.seed([5FFE380A25F667CF:8978D8F7C6E8A055]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:242)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:213)
at org.apache.solr.pkg.TestPackages.verifyCmponent(TestPackages.java:415)
at org.apache.solr.pkg.TestPackages.testPluginLoading(TestPackages.java:153)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 15789 lines...]
[junit4] Suite: org.apache.solr.pkg.TestPackages
[junit4] 2> 1139240 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1139240 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1139241 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/data-dir-95-001
[junit4] 2> 1139241 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1139241 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1139243 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPluginLoading
[junit4] 2> 1139244 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001
[junit4] 2> 1139244 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1139244 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1139245 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1139344 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer start zk server on port:38093
[junit4] 2> 1139345 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38093
[junit4] 2> 1139345 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38093
[junit4] 2> 1139345 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38093
[junit4] 2> 1139346 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139350 INFO (zkConnectionManagerCallback-9465-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139350 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139353 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139358 INFO (zkConnectionManagerCallback-9467-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139358 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139360 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139361 INFO (zkConnectionManagerCallback-9469-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139361 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139478 WARN (jetty-launcher-9470-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1139478 WARN (jetty-launcher-9470-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1139478 WARN (jetty-launcher-9470-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1139478 WARN (jetty-launcher-9470-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14+36-1461
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14+36-1461
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14+36-1461
[junit4] 2> 1139478 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 14+36-1461
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1139484 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4b326556{/solr,null,AVAILABLE}
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ce87127{/solr,null,AVAILABLE}
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@65cc9e4f{/solr,null,AVAILABLE}
[junit4] 2> 1139485 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c33a19a{/solr,null,AVAILABLE}
[junit4] 2> 1139486 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4c5270bf{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:41199}
[junit4] 2> 1139486 INFO (jetty-launcher-9470-thread-4) [ ] o.e.j.s.Server Started @1139781ms
[junit4] 2> 1139486 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41199}
[junit4] 2> 1139486 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@226cd58c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:46261}
[junit4] 2> 1139486 INFO (jetty-launcher-9470-thread-2) [ ] o.e.j.s.Server Started @1139781ms
[junit4] 2> 1139486 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46261}
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@66a7bf71{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:40965}
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.e.j.s.Server Started @1139781ms
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40965}
[junit4] 2> 1139487 ERROR (jetty-launcher-9470-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1139487 ERROR (jetty-launcher-9470-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1139487 ERROR (jetty-launcher-9470-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5dcaa1cb{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:37221}
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.e.j.s.Server Started @1139782ms
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37221}
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T23:48:32.789275Z
[junit4] 2> 1139487 ERROR (jetty-launcher-9470-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T23:48:32.789340Z
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T23:48:32.789437Z
[junit4] 2> 1139487 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-18T23:48:32.789437Z
[junit4] 2> 1139488 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139488 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139488 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139488 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139490 INFO (zkConnectionManagerCallback-9477-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139490 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139492 INFO (zkConnectionManagerCallback-9475-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139492 INFO (zkConnectionManagerCallback-9478-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139492 INFO (zkConnectionManagerCallback-9476-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1139492 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1139666 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1139667 WARN (jetty-launcher-9470-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5119ceb2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139667 WARN (jetty-launcher-9470-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5119ceb2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139669 WARN (jetty-launcher-9470-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51d7eab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139669 WARN (jetty-launcher-9470-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51d7eab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139670 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38093/solr
[junit4] 2> 1139671 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139672 INFO (zkConnectionManagerCallback-9485-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139672 INFO (jetty-launcher-9470-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139778 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1139779 WARN (jetty-launcher-9470-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@65f14075[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139779 WARN (jetty-launcher-9470-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@65f14075[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139783 WARN (jetty-launcher-9470-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6ec68a3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139783 WARN (jetty-launcher-9470-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6ec68a3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139784 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38093/solr
[junit4] 2> 1139784 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139785 INFO (zkConnectionManagerCallback-9492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139786 INFO (jetty-launcher-9470-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139872 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1139873 WARN (jetty-launcher-9470-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@37034af5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139873 WARN (jetty-launcher-9470-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@37034af5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139878 WARN (jetty-launcher-9470-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3385814b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139878 WARN (jetty-launcher-9470-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3385814b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139878 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38093/solr
[junit4] 2> 1139879 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139882 INFO (zkConnectionManagerCallback-9499-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139882 INFO (jetty-launcher-9470-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1139984 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1139985 WARN (jetty-launcher-9470-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3c54ff63[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139985 WARN (jetty-launcher-9470-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3c54ff63[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139987 WARN (jetty-launcher-9470-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1f2c7632[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139987 WARN (jetty-launcher-9470-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1f2c7632[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1139988 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38093/solr
[junit4] 2> 1139989 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1139990 INFO (zkConnectionManagerCallback-9506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1139990 INFO (jetty-launcher-9470-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1140000 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1140002 INFO (zkConnectionManagerCallback-9508-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1140002 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1140056 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46261_solr
[junit4] 2> 1140057 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.c.Overseer Overseer (id=72070718462165003-127.0.0.1:46261_solr-n_0000000000) starting
[junit4] 2> 1140059 INFO (OverseerStateUpdate-72070718462165003-127.0.0.1:46261_solr-n_0000000000) [n:127.0.0.1:46261_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46261_solr
[junit4] 2> 1140068 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46261_solr
[junit4] 2> 1140071 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1140071 WARN (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_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> 1140075 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1140093 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1140102 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1140111 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46261.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140119 INFO (zkConnectionManagerCallback-9514-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1140119 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1140124 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46261.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140124 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46261.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140125 INFO (jetty-launcher-9470-thread-2) [n:127.0.0.1:46261_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node2/.
[junit4] 2> 1140127 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1140135 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37221_solr as DOWN
[junit4] 2> 1140136 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1140136 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37221_solr
[junit4] 2> 1140146 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1140149 INFO (zkCallback-9513-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1140150 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1140150 WARN (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_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> 1140163 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1140177 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37221.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140201 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1140203 INFO (zkConnectionManagerCallback-9520-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1140203 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1140205 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1140206 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37221.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140206 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37221.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140207 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40965_solr as DOWN
[junit4] 2> 1140207 INFO (jetty-launcher-9470-thread-1) [n:127.0.0.1:37221_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node1/.
[junit4] 2> 1140208 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1140208 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40965_solr
[junit4] 2> 1140208 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1140208 INFO (zkCallback-9513-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1140208 INFO (zkCallback-9519-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1140209 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1140209 WARN (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_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> 1140219 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1140233 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40965.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140238 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40965.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140238 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40965.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140239 INFO (jetty-launcher-9470-thread-3) [n:127.0.0.1:40965_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node3/.
[junit4] 2> 1140314 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1140322 INFO (zkConnectionManagerCallback-9526-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1140322 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1140325 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1140327 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41199_solr as DOWN
[junit4] 2> 1140328 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1140328 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41199_solr
[junit4] 2> 1140329 INFO (zkCallback-9513-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1140329 INFO (zkCallback-9519-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1140329 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1140330 INFO (zkCallback-9525-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1140330 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1140330 WARN (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_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> 1140346 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1140362 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41199.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140380 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41199.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140380 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41199.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1140381 INFO (jetty-launcher-9470-thread-4) [n:127.0.0.1:41199_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node4/.
[junit4] 2> 1140406 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1140406 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1140407 INFO (zkConnectionManagerCallback-9535-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1140407 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1140408 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1140408 INFO (TEST-TestPackages.testPluginLoading-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38093/solr ready
[junit4] 2> 1140451 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140451 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:40965_solr
[junit4] 2> 1140460 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 1140462 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 1140462 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140462 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:40965_solr
[junit4] 2> 1140464 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 1140464 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 1140471 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 1140472 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 1140473 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 1140473 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 1140478 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:40965_solr} status=0 QTime=3
[junit4] 2> 1140478 INFO (updateExecutor-9502-thread-1-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:40965_solr
[junit4] 2> 1140479 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={sync=true} status=0 QTime=48
[junit4] 1> sync resp: https://127.0.0.1:40965/api/node/files/_trusted_/keys/pub_key512.der?sync=true ,is: null
[junit4] 2> 1140486 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 1140491 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 1140493 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 1140494 INFO (updateExecutor-9502-thread-1-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 1140494 INFO (updateExecutor-9502-thread-1-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 1140503 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140552 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140564 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140576 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140577 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/runtimelibs.jar
[junit4] 2> 1140577 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140578 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140579 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140579 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/runtimelibs.jar
[junit4] 2> 1140580 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1140581 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140581 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:40965_solr
[junit4] 2> 1140581 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140581 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:40965_solr
[junit4] 2> 1140582 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 1140582 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 1140582 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 1140582 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 1140584 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1140584 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 1140584 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1140584 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 1140584 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140584 INFO (updateExecutor-9502-thread-1-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:40965_solr
[junit4] 2> 1140585 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/runtimelibs.jar params={sig=L3q/qIGs4NaF6JiO0ZkMUFa88j0OmYc%2BI6O7BOdNuMct/xoZ4h73aZHZGc0%2BnmI1f/U3bOlMPINlSOM6LK3JpQ%3D%3D&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 1140585 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 1140585 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 1140587 INFO (updateExecutor-9502-thread-1-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 1140587 INFO (updateExecutor-9502-thread-1-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 1140595 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140606 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140616 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140631 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140642 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140643 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/testurpv1.jar
[junit4] 2> 1140650 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140651 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140662 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140662 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/testurpv1.jar
[junit4] 2> 1140662 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1140665 INFO (qtp680836860-26222) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:41199_solr} status=0 QTime=0
[junit4] 2> 1140665 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:41199_solr
[junit4] 2> 1140665 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:41199_solr} status=0 QTime=0
[junit4] 2> 1140665 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:41199_solr
[junit4] 2> 1140666 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:41199_solr} status=0 QTime=0
[junit4] 2> 1140666 INFO (updateExecutor-9481-thread-3-processing-n:127.0.0.1:40965_solr) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:41199_solr
[junit4] 2> 1140666 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/testurpv1.jar params={sig=h6UmMzuPqu4hQFGLBMJh/6kDSEXpJlgLsQDXx0KuxXWkV5giilRP57K3towiJRh2J%2BrqihqIghNCi3YgzgUnWQ%3D%3D&wt=javabin&version=2} status=0 QTime=25
[junit4] 2> 1140666 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 1140667 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 1140668 INFO (updateExecutor-9481-thread-3-processing-n:127.0.0.1:40965_solr) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1140668 INFO (updateExecutor-9481-thread-3-processing-n:127.0.0.1:40965_solr) [n:127.0.0.1:40965_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 1140671 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 1140672 INFO (qtp1185553359-26228) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 1140673 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1140673 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 1140673 INFO (qtp680836860-26222) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140674 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 1140675 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 1140675 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 1140675 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 1140681 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140692 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140703 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140706 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140707 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/expressible.jar
[junit4] 2> 1140707 INFO (qtp1185553359-26228) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140708 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140708 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140710 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/expressible.jar
[junit4] 2> 1140713 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1140716 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140716 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:40965_solr
[junit4] 2> 1140717 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140717 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 1140717 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:40965_solr
[junit4] 2> 1140717 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 1140717 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 1140717 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:40965_solr} status=0 QTime=0
[junit4] 2> 1140718 INFO (updateExecutor-9502-thread-2-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:40965_solr
[junit4] 2> 1140718 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/expressible.jar params={sig=ZOT11arAiPmPZYOHzqodiNnxO9pRyRozWZEBX8XGjU1/HJptFnZK%2BDI7eXnUtbNaMcbXE2Ze8hh4M/eGyhY8BQ%3D%3D&wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 1140718 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 1140718 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 1140718 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1140718 INFO (updateExecutor-9488-thread-1-processing-n:127.0.0.1:37221_solr) [n:127.0.0.1:37221_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 1140718 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1140718 INFO (updateExecutor-9495-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 1140719 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 1140720 INFO (updateExecutor-9502-thread-2-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 1140720 INFO (updateExecutor-9502-thread-2-processing-n:127.0.0.1:41199_solr) [n:127.0.0.1:41199_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 1140733 INFO (qtp680836860-26222) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140744 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140752 INFO (qtp1573471486-26201) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140761 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140773 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140774 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140775 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140776 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140777 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140778 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140780 DEBUG (zkCallback-9525-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1140780 DEBUG (zkCallback-9513-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1140780 DEBUG (zkCallback-9507-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1140780 DEBUG (zkCallback-9519-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 1140788 INFO (zkCallback-9507-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1140788 INFO (zkCallback-9513-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1140788 INFO (zkCallback-9513-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1140788 INFO (zkCallback-9507-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1140788 INFO (zkCallback-9525-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1140788 INFO (zkCallback-9525-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1140788 INFO (zkCallback-9519-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1140788 INFO (zkCallback-9519-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1140789 INFO (zkCallback-9519-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1140789 INFO (zkCallback-9513-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1140789 INFO (zkCallback-9525-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1140789 INFO (zkCallback-9507-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 1140789 INFO (qtp1462946491-26204) [n:127.0.0.1:37221_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140790 INFO (qtp1185553359-26211) [n:127.0.0.1:41199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140790 INFO (qtp1573471486-26208) [n:127.0.0.1:40965_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1140791 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 1140791 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 1140791 INFO (zkCallback-9519-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140791 INFO (zkCallback-9525-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140791 INFO (zkCallback-9513-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140791 INFO (zkCallback-9507-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140792 INFO (zkCallback-9519-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140792 INFO (zkCallback-9525-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140792 INFO (zkCallback-9513-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140792 INFO (zkCallback-9507-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9519-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9525-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9513-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9507-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9525-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9519-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140793 INFO (zkCallback-9513-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140794 INFO (zkCallback-9507-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140794 INFO (zkCallback-9525-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140794 INFO (zkCallback-9519-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140795 INFO (zkCallback-9513-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140795 INFO (zkCallback-9525-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140795 INFO (zkCallback-9519-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140795 INFO (zkCallback-9507-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140795 INFO (zkCallback-9513-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140796 INFO (zkCallback-9525-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9525-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9513-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9525-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9513-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9507-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 1140796 INFO (zkCallback-9513-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9519-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9519-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9519-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 1140796 INFO (zkCallback-9519-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1140796 INFO (zkCallback-9525-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1140796 INFO (zkCallback-9513-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1140797 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 1140797 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 1140797 INFO (zkCallback-9507-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 1140797 INFO (zkCallback-9507-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 1140797 INFO (qtp680836860-26206) [n:127.0.0.1:46261_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=28
[junit4] 2> 1140798 INFO (qtp680836860-26222) [n:127.0.0.1:46261_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=testPluginLoadingColl&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1140800 INFO (OverseerThreadFactory-5904-thread-1-processing-n:127.0.0.1:46261_solr) [n:127.0.0.1:46261_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testPluginLoadingColl
[junit4] 2> 1140910 INFO (OverseerStateUpdate-72070718462165003-127.0.0.1:46261_solr-n_0000000000) [n:127.0.0.1:46261_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46261/solr",
[junit4] 2> "node_name":"127.0.0.1:46261_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1140915 INFO (OverseerStateUpdate-72070718462165003-127.0.0.1:46261_solr-n_0000000000) [n:127.0.0.1:46261_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41199/solr",
[junit4] 2> "node_name":"127.0.0.1:41199_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1140917 INFO (OverseerStateUpdate-72070718462165003-127.0.0.1:46261_solr-n_0000000000) [n:127.0.0.1:46261_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40965/solr",
[junit4] 2> "node_name":"127.0.0.1:40965_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1140917 INFO (OverseerStateUpdate-72070718462165003-127.0.0.1:46261_solr-n_0000000000) [n:127.0.0.1:46261_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:37221/solr",
[junit4] 2> "node_name":"127.0.0.1:37221_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1141124 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n2&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1141126 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n6&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1141126 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n4&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1141131 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n1&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1141131 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1141146 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1141147 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1141147 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1141158 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1141159 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard2_replica_n6] Schema name=minimal
[junit4] 2> 1141159 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1141159 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1141162 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1141162 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1141162 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1141162 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 1141162 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 1141163 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 1141163 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46261.solr.core.testPluginLoadingColl.shard1.replica_n1' (registry 'solr.core.testPluginLoadingColl.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1141163 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41199.solr.core.testPluginLoadingColl.shard1.replica_n2' (registry 'solr.core.testPluginLoadingColl.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1141163 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37221.solr.core.testPluginLoadingColl.shard2.replica_n6' (registry 'solr.core.testPluginLoadingColl.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3711fb8d
[junit4] 2> 1141163 INFO (qtp1185553359-26213) [n:127.0.0.1:41199_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node4/testPluginLoadingColl_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node4/./testPluginLoadingColl_shard1_replica_n2/data/]
[junit4] 2> 1141163 INFO (qtp680836860-26209) [n:127.0.0.1:46261_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node2/testPluginLoadingColl_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node2/./testPluginLoadingColl_shard1_replica_n1/data/]
[junit4] 2> 1141163 INFO (qtp1462946491-26202) [n:127.0.0.1:37221_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node1/testPluginLoadingColl_shard2_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001/tempDir-001/node1/./testPluginLoadingColl_shard2_replica_n6/data/]
[junit4] 2> 1141168 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard2_replica_n4] Schema name=minimal
[junit4] 2> 1141169 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1141169 INFO (qtp1573471486-26199) [n:127.0.0.1:40965_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n4'
[...truncated too long message...]
com.codahale.metrics.MetricRegistry@2d176cd1
[junit4] 2> 1153554 INFO (jetty-closer-9641-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1153554 INFO (closeThreadPool-9657-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72070719229788171-127.0.0.1:40685_solr-n_0000000000) closing
[junit4] 2> 1153555 INFO (OverseerStateUpdate-72070719229788171-127.0.0.1:40685_solr-n_0000000000) [n:127.0.0.1:40685_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:40685_solr
[junit4] 2> 1153555 INFO (OverseerAutoScalingTriggerThread-72070719229788171-127.0.0.1:40685_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1153555 INFO (zkCallback-9609-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1153555 INFO (zkCallback-9630-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1153555 INFO (closeThreadPool-9657-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72070719229788171-127.0.0.1:40685_solr-n_0000000000) closing
[junit4] 2> 1153656 INFO (jetty-closer-9641-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@55debe8{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1153656 INFO (jetty-closer-9641-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5dc8d13{/solr,null,UNAVAILABLE}
[junit4] 2> 1153656 INFO (jetty-closer-9641-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1153755 INFO (jetty-closer-9641-thread-3) [ ] o.a.s.c.Overseer Overseer (id=72070719229788171-127.0.0.1:40685_solr-n_0000000000) closing
[junit4] 2> 1162645 INFO (jetty-closer-9641-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@aa41838{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1162645 INFO (jetty-closer-9641-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3fa2119e{/solr,null,UNAVAILABLE}
[junit4] 2> 1162645 INFO (jetty-closer-9641-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1162989 INFO (jetty-closer-9641-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@69d4e768{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1162989 INFO (jetty-closer-9641-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2b7f3557{/solr,null,UNAVAILABLE}
[junit4] 2> 1162989 INFO (jetty-closer-9641-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1163176 INFO (jetty-closer-9641-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@55316fe6{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1163176 INFO (jetty-closer-9641-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40ad5b1c{/solr,null,UNAVAILABLE}
[junit4] 2> 1163176 INFO (jetty-closer-9641-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1163180 INFO (TEST-TestPackages.testAPI-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1163390 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> 5 /solr/aliases.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/packages.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/72070719229788172-127.0.0.1:41673_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/live_nodes
[junit4] 2> 10 /solr/overseer/queue
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 1163392 INFO (TEST-TestPackages.testAPI-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:39897
[junit4] 2> 1163392 INFO (TEST-TestPackages.testAPI-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39897
[junit4] 2> 1163392 INFO (TEST-TestPackages.testAPI-seed#[5FFE380A25F667CF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39897
[junit4] 2> 1163393 INFO (TEST-TestPackages.testAPI-seed#[5FFE380A25F667CF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAPI
[junit4] 2> 1163394 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1163394 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:39897
[junit4] 2> 1163394 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39897
[junit4] 2> 1163394 INFO (SUITE-TestPackages-seed#[5FFE380A25F667CF]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39897
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.5-Linux/solr/build/solr-core/test/J5/temp/solr.pkg.TestPackages_5FFE380A25F667CF-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=200, maxMBSortInHeap=7.318037696234919, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6f50886e), locale=uk, timezone=America/Nipigon
[junit4] 2> NOTE: Linux 5.3.0-42-generic amd64/Oracle Corporation 14 (64-bit)/cpus=16,threads=1,free=383778816,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestSort, SolrCmdDistributorTest, TestSimUtils, TestPolicyCloud, ProtectedTermFilterFactoryTest, V2StandaloneTest, LeaderFailoverAfterPartitionTest, TestReplicationHandler, MultiSolrCloudTestCaseTest, ParsingFieldUpdateProcessorsTest, SolrJmxReporterCloudTest, TestSolr4Spatial2, OverseerTaskQueueTest, ConcurrentDeleteAndCreateCollectionTest, TestSimLargeCluster, HttpPartitionOnCommitTest, TestNestedUpdateProcessor, DistributedIntervalFacetingTest, MetricsConfigTest, TestIndexSearcher, TestMinHashQParser, TestStressVersions, TestPayloadScoreQParserPlugin, XMLAtomicUpdateMultivalueTest, PhrasesIdentificationComponentTest, IndexSchemaRuntimeFieldTest, TestLegacyFieldCache, DocumentAnalysisRequestHandlerTest, TestScoreJoinQPScore, HighlighterWithoutStoredIdTest, TermsComponentTest, HdfsBasicDistributedZkTest, TimeRoutedAliasUpdateProcessorTest, TestSubQueryTransformer, TestGeoJSONResponseWriter, TestFileDictionaryLookup, TestSimComputePlanAction, TestSchemalessBufferedUpdates, ConnectionReuseTest, TestImplicitCoreProperties, BasicDistributedZkTest, TestDocumentBuilder, TestLegacyBM25SimilarityFactory, HLLSerializationTest, TestSimpleTrackingShardHandler, SuggesterTSTTest, TestRawTransformer, RuleEngineTest, SpatialFilterTest, TestManagedSchemaAPI, TestFieldSortValues, TestSolrConfigHandler, DeleteShardTest, HttpTriggerListenerTest, CoreAdminHandlerTest, PeerSyncWithBufferUpdatesTest, TestReloadDeadlock, BlockDirectoryTest, RollingRestartTest, TestBulkSchemaAPI, DistributedDebugComponentTest, TestRangeQuery, TestWithCollection, TestClusterProperties, SpellCheckCollatorWithCollapseTest, TestClassicSimilarityFactory, TestConfigOverlay, AnalyticsMergeStrategyTest, TestPKIAuthenticationPlugin, MetricsHistoryWithAuthIntegrationTest, TestSerializedLuceneMatchVersion, TestMultiValuedNumericRangeQuery, RecoveryZkTest, SSLMigrationTest, SaslZkACLProviderTest, ShardRoutingCustomTest, SolrCloudExampleTest, TestAuthenticationFramework, TestCloudInspectUtil, TestConfigSetsAPI, TestDistributedMap, TestDownShardTolerantSearch, TestDynamicFieldNamesIndexCorrectly, TestOnReconnectListenerSupport, TestRandomFlRTGCloud, TestStressLiveNodes, LoggingHandlerTest, MetricsHandlerTest, MetricsHistoryHandlerTest, SegmentsInfoRequestHandlerTest, SystemInfoHandlerTest, TestApiFramework, TestCollectionAPIs, ZookeeperStatusHandlerTest, DistributedFacetPivotLongTailTest, DistributedMLTComponentTest, DistributedSpellCheckComponentTest, SpellCheckComponentTest, TestExpandComponent, TestHttpShardHandlerFactory, TestTrackingShardHandlerFactory, SolrJmxReporterTest, SolrRrdBackendFactoryTest, TestPackages]
[junit4] Completed [730/900 (1!)] on J5 in 24.29s, 2 tests, 1 failure <<< FAILURES!
[...truncated 45387 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.5-Linux/833/consoleText
[repro] Revision: 1050e459027c35d15d4d1ea91a6c14cf2052594f
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line: ant test -Dtestcase=TestPackages -Dtests.method=testPluginLoading -Dtests.seed=5FFE380A25F667CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=uk -Dtests.timezone=America/Nipigon -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestPackages
[repro] ant compile-test
[...truncated 3423 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestPackages" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=5FFE380A25F667CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=uk -Dtests.timezone=America/Nipigon -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.pkg.TestPackages
[repro] Exiting with code 0
[...truncated 43 lines...]