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 2019/09/14 06:34:42 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-12.0.1) - Build # 325 -
Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/325/
Java: 64bit/jdk-12.0.1 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestContainerReqHandler.testPackageAPI
Error Message:
attempt: 9 Mismatch for value : '[requestHandler]' in response { "responseHeader":{ "status":0, "QTime":0}, "metadata":{"version":3}, "runtimeLib":[{ "name":"global", "url":"http://localhost:63119/jar3.jar", "sha256":"20e0bfaec71b2e93c4da9f2ed3745dda04dc3fc915b66cc0275863982e73b2a3", "znodeVersion":3}], "requestHandler":[{ "name":"bar", "znodeVersion":3, "class":"org.apache.solr.core.RuntimeLibReqHandler", "package":"global"}]}
Stack Trace:
java.lang.AssertionError: attempt: 9 Mismatch for value : '[requestHandler]' in response {
"responseHeader":{
"status":0,
"QTime":0},
"metadata":{"version":3},
"runtimeLib":[{
"name":"global",
"url":"http://localhost:63119/jar3.jar",
"sha256":"20e0bfaec71b2e93c4da9f2ed3745dda04dc3fc915b66cc0275863982e73b2a3",
"znodeVersion":3}],
"requestHandler":[{
"name":"bar",
"znodeVersion":3,
"class":"org.apache.solr.core.RuntimeLibReqHandler",
"package":"global"}]}
at __randomizedtesting.SeedInfo.seed([83D34C1E775CB7E7:F1DC5950DB07EDF7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.handler.TestContainerReqHandler.assertResponseValues(TestContainerReqHandler.java:121)
at org.apache.solr.handler.TestContainerReqHandler.testPackageAPI(TestContainerReqHandler.java:236)
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:567)
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:835)
Build Log:
[...truncated 13572 lines...]
[junit4] Suite: org.apache.solr.handler.TestContainerReqHandler
[junit4] 2> 664704 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 664705 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001/data-dir-58-001
[junit4] 2> 664706 WARN (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=42 numCloses=42
[junit4] 2> 664706 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 664706 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None") w/ MAC_OS_X supressed clientAuth
[junit4] 2> 664709 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPackageAPI
[junit4] 2> 664717 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 664721 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a457528{HTTP/1.1,[http/1.1]}{0.0.0.0:63119}
[junit4] 2> 664722 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.e.j.s.Server Started @664798ms
[junit4] 2> 664722 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001/tempDir-001
[junit4] 2> 664722 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 664723 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 664723 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 664827 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer start zk server on port:63120
[junit4] 2> 664828 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:63120
[junit4] 2> 664828 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63120
[junit4] 2> 664828 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63120
[junit4] 2> 664830 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664841 INFO (zkConnectionManagerCallback-3503-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664841 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664845 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664847 INFO (zkConnectionManagerCallback-3505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664847 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664848 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664850 INFO (zkConnectionManagerCallback-3507-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664850 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664957 WARN (jetty-launcher-3508-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 664958 WARN (jetty-launcher-3508-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 664958 WARN (jetty-launcher-3508-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 664958 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 664959 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 664959 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 664959 WARN (jetty-launcher-3508-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 664959 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 664959 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 664959 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 664962 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 664962 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 664962 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 664963 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 664963 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 664963 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 664963 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@794ab0c2{/solr,null,AVAILABLE}
[junit4] 2> 664964 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d3d6716{/solr,null,AVAILABLE}
[junit4] 2> 664964 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d966c2a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:63125}
[junit4] 2> 664964 INFO (jetty-launcher-3508-thread-4) [ ] o.e.j.s.Server Started @665041ms
[junit4] 2> 664964 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63125}
[junit4] 2> 664965 ERROR (jetty-launcher-3508-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@66041d64{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:63126}
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-2) [ ] o.e.j.s.Server Started @665042ms
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63126}
[junit4] 2> 664965 ERROR (jetty-launcher-3508-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 664965 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-14T04:56:27.841789Z
[junit4] 2> 664966 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 664966 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 664966 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 664966 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-14T04:56:27.842679Z
[junit4] 2> 664967 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 664967 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 664967 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 664967 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@23bd5845{/solr,null,AVAILABLE}
[junit4] 2> 664968 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69674c8a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:63127}
[junit4] 2> 664968 INFO (jetty-launcher-3508-thread-1) [ ] o.e.j.s.Server Started @665045ms
[junit4] 2> 664968 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63127}
[junit4] 2> 664968 ERROR (jetty-launcher-3508-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 664968 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-14T04:56:27.845170Z
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 664969 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 664970 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664971 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664972 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11193b44{/solr,null,AVAILABLE}
[junit4] 2> 664973 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d8fab3d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:63130}
[junit4] 2> 664973 INFO (jetty-launcher-3508-thread-3) [ ] o.e.j.s.Server Started @665050ms
[junit4] 2> 664974 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664975 INFO (zkConnectionManagerCallback-3512-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664975 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664973 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63130}
[junit4] 2> 664975 ERROR (jetty-launcher-3508-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 664975 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 664975 INFO (zkConnectionManagerCallback-3510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664976 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.3.0
[junit4] 2> 664976 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 664976 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 664976 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664976 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-09-14T04:56:27.852094Z
[junit4] 2> 664978 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 664979 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 664979 INFO (zkConnectionManagerCallback-3514-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664979 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664981 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 664981 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 664985 INFO (zkConnectionManagerCallback-3516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664985 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 664987 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 665257 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 665258 WARN (jetty-launcher-3508-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@9c4b211[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665258 WARN (jetty-launcher-3508-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@9c4b211[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665262 WARN (jetty-launcher-3508-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@56852e6f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665262 WARN (jetty-launcher-3508-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@56852e6f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665263 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63120/solr
[junit4] 2> 665267 INFO (zkConnectionManagerCallback-3524-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665267 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665267 INFO (jetty-launcher-3508-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665336 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 665337 WARN (jetty-launcher-3508-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1808de9c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665337 WARN (jetty-launcher-3508-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1808de9c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665338 WARN (jetty-launcher-3508-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2411452f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665338 WARN (jetty-launcher-3508-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2411452f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665341 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63120/solr
[junit4] 2> 665343 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665346 INFO (zkConnectionManagerCallback-3530-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665346 INFO (jetty-launcher-3508-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665402 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 665404 WARN (jetty-launcher-3508-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@52e69ce0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665404 WARN (jetty-launcher-3508-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@52e69ce0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665408 WARN (jetty-launcher-3508-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@794a459d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665408 WARN (jetty-launcher-3508-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@794a459d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665408 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63120/solr
[junit4] 2> 665409 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665410 INFO (zkConnectionManagerCallback-3537-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665410 INFO (jetty-launcher-3508-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665549 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 665550 WARN (jetty-launcher-3508-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@57af5c35[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665550 WARN (jetty-launcher-3508-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@57af5c35[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665553 WARN (jetty-launcher-3508-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39192cf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665553 WARN (jetty-launcher-3508-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39192cf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 665553 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63120/solr
[junit4] 2> 665554 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665557 INFO (zkConnectionManagerCallback-3544-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665557 INFO (jetty-launcher-3508-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665561 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665563 INFO (zkConnectionManagerCallback-3546-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665564 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665665 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665668 INFO (zkConnectionManagerCallback-3548-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665668 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665768 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665770 INFO (zkConnectionManagerCallback-3550-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665770 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 665873 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 665884 INFO (zkConnectionManagerCallback-3552-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665884 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 666081 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666082 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666083 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 666085 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666093 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63126_solr
[junit4] 2> 666095 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.Overseer Overseer (id=72119544138563598-127.0.0.1:63126_solr-n_0000000000) starting
[junit4] 2> 666106 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 666111 INFO (zkConnectionManagerCallback-3565-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666112 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 666113 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666115 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666116 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 666118 DEBUG (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666119 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63120/solr ready
[junit4] 2> 666120 INFO (OverseerStateUpdate-72119544138563598-127.0.0.1:63126_solr-n_0000000000) [n:127.0.0.1:63126_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:63126_solr
[junit4] 2> 666121 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63126_solr
[junit4] 2> 666124 DEBUG (OverseerStateUpdate-72119544138563598-127.0.0.1:63126_solr-n_0000000000) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 666125 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 666125 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 666126 DEBUG (OverseerStateUpdate-72119544138563598-127.0.0.1:63126_solr-n_0000000000) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666127 INFO (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 666127 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr]
[junit4] 2> 666127 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 666127 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr]
[junit4] 2> 666129 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 666150 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 666151 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666152 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666153 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666154 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 666154 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr]
[junit4] 2> 666155 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666155 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666157 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 666157 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr]
[junit4] 2> 666158 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666165 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63130_solr as DOWN
[junit4] 2> 666166 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 666166 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63130_solr
[junit4] 2> 666172 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63127_solr as DOWN
[junit4] 2> 666172 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63126.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666173 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 666173 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 666173 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 666174 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 666175 INFO (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666175 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666175 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666175 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666175 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666175 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666175 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666175 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666177 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 666177 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63127_solr
[junit4] 2> 666180 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 666182 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63126.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666182 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63126.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666182 INFO (zkConnectionManagerCallback-3571-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666183 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 666183 INFO (jetty-launcher-3508-thread-2) [n:127.0.0.1:63126_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001/tempDir-001/node2/.
[junit4] 2> 666183 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666184 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 666184 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 666184 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 666185 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 666186 INFO (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666186 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666187 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666187 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666187 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666187 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666187 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666187 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666188 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666192 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 666192 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 666192 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666194 INFO (zkConnectionManagerCallback-3576-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666194 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 666194 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666195 DEBUG (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666196 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666197 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63120/solr ready
[junit4] 2> 666197 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 666198 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 666198 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666199 DEBUG (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666200 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63120/solr ready
[junit4] 2> 666200 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 666203 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666211 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666214 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 666214 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666214 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666225 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63125_solr as DOWN
[junit4] 2> 666227 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 666227 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63125_solr
[junit4] 2> 666228 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666228 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666228 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666228 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666229 DEBUG (zkCallback-3575-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666229 INFO (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666229 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666229 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666229 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666229 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666229 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666229 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666229 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666230 INFO (zkCallback-3575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666230 DEBUG (zkCallback-3547-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666230 DEBUG (zkCallback-3575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666230 DEBUG (zkCallback-3570-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 666230 INFO (zkCallback-3547-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666230 DEBUG (zkCallback-3547-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666231 INFO (zkCallback-3570-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 666231 DEBUG (zkCallback-3570-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666233 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 666236 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 666236 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 666237 INFO (zkConnectionManagerCallback-3581-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666237 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 666238 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666248 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666259 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 666259 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666260 DEBUG (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666261 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63120/solr ready
[junit4] 2> 666264 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 666304 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63130.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666336 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63127.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666387 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 666391 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63127.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666391 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63127.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666393 INFO (jetty-launcher-3508-thread-1) [n:127.0.0.1:63127_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001/tempDir-001/node1/.
[junit4] 2> 666446 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63130.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666447 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63130.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666472 INFO (jetty-launcher-3508-thread-3) [n:127.0.0.1:63130_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001/tempDir-001/node3/.
[junit4] 2> 666509 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63125.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666527 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63125.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666528 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63125.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f98abbd
[junit4] 2> 666529 INFO (jetty-launcher-3508-thread-4) [n:127.0.0.1:63125_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001/tempDir-001/node4/.
[junit4] 2> 666609 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 666610 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 666613 INFO (zkConnectionManagerCallback-3589-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666613 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 666614 DEBUG (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 666615 DEBUG (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 666616 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 666616 DEBUG (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:63125_solr, 127.0.0.1:63126_solr, 127.0.0.1:63127_solr, 127.0.0.1:63130_solr]
[junit4] 2> 666617 DEBUG (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [0] total [0]
[junit4] 2> 666618 INFO (TEST-TestContainerReqHandler.testPackageAPI-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63120/solr ready
[junit4] 2> 666636 ERROR (qtp1738583566-8940) [n:127.0.0.1:63127_solr ] o.a.s.h.a.CollectionHandlerApi Error loading runtimelib
[junit4] 2> => org.apache.solr.common.SolrException: Invalid jar from http://localhost:63119/jar1.jar , expected sha256 hash : wrong-sha256 , actual : e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc
[junit4] 2> at org.apache.solr.core.RuntimeLib.init(RuntimeLib.java:112)
[junit4] 2> org.apache.solr.common.SolrException: Invalid jar from http://localhost:63119/jar1.jar , expected sha256 hash : wrong-sha256 , actual : e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc
[junit4] 2> at org.apache.solr.core.RuntimeLib.init(RuntimeLib.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi.addUpdatePackage(CollectionHandlerApi.java:230) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi.lambda$wrap$0(CollectionHandlerApi.java:119) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi$2.invoke(CollectionHandlerApi.java:317) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.BaseHandlerApiSupport$1.call(BaseHandlerApiSupport.java:100) ~[java/:?]
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:341) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:786) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:546) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:424) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:167) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1711) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1347) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1678) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1249) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:335) ~[jetty-rewrite-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:505) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:370) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:267) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:781) ~[jetty-util-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:917) ~[jetty-util-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 666636 ERROR (qtp1738583566-8940) [n:127.0.0.1:63127_solr ] o.a.s.h.a.CollectionHandlerApi Error in running command {
[junit4] 2> "add-package":{
[junit4] 2> "name":"global",
[junit4] 2> "url":"http://localhost:63119/jar1.jar",
[junit4] 2> "sha256":"wrong-sha256"},
[junit4] 2> "errors":["Invalid jar from http://localhost:63119/jar1.jar , expected sha256 hash : wrong-sha256 , actual : e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc"]} , current clusterprops.json : {}
[junit4] 2> 666636 INFO (qtp1738583566-8940) [n:127.0.0.1:63127_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster params={wt=javabin&version=2} status=400 QTime=10
[junit4] 2> 666653 ERROR (qtp1757675020-8941) [n:127.0.0.1:63126_solr ] o.a.s.c.BlobRepository Error loading resource http://localhost:63119/jar0.jar
[junit4] 2> => org.apache.solr.common.SolrException: no such resource available: foo, url : http://localhost:63119/jar0.jar
[junit4] 2> at org.apache.solr.core.BlobRepository.fetchFromUrl(BlobRepository.java:288)
[junit4] 2> org.apache.solr.common.SolrException: no such resource available: foo, url : http://localhost:63119/jar0.jar
[junit4] 2> at org.apache.solr.core.BlobRepository.fetchFromUrl(BlobRepository.java:288) ~[java/:?]
[junit4] 2> at org.apache.solr.core.RuntimeLib.init(RuntimeLib.java:108) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi.addUpdatePackage(CollectionHandlerApi.java:230) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi.lambda$wrap$0(CollectionHandlerApi.java:119) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi$2.invoke(CollectionHandlerApi.java:317) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.BaseHandlerApiSupport$1.call(BaseHandlerApiSupport.java:100) ~[java/:?]
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:341) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:786) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:546) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:424) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:167) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1711) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1347) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1678) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1249) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:335) ~[jetty-rewrite-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:505) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:370) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:267) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:781) ~[jetty-util-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:917) ~[jetty-util-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 666653 ERROR (qtp1757675020-8941) [n:127.0.0.1:63126_solr ] o.a.s.h.a.CollectionHandlerApi Error loading runtimelib
[junit4] 2> => org.apache.solr.common.SolrException: no such resource available: foo, url : http://localhost:63119/jar0.jar
[junit4] 2> at org.apache.solr.core.BlobRepository.fetchFromUrl(BlobRepository.java:288)
[junit4] 2> org.apache.solr.common.SolrException: no such resource available: foo, url : http://localhost:63119/jar0.jar
[junit4] 2> at org.apache.solr.core.BlobRepository.fetchFromUrl(BlobRepository.java:288) ~[java/:?]
[junit4] 2> at org.apache.solr.core.RuntimeLib.init(RuntimeLib.java:108) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi.addUpdatePackage(CollectionHandlerApi.java:230) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi.lambda$wrap$0(CollectionHandlerApi.java:119) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.CollectionHandlerApi$2.invoke(CollectionHandlerApi.java:317) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.admin.BaseHandlerApiSupport$1.call(BaseHandlerApiSupport.java:100) ~[java/:?]
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:341) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:786) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:546) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:424) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:167) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1711) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1347) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) ~[jetty-servlet-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1678) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1249) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:335) ~[jetty-rewrite-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:505) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:370) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:267) ~[jetty-server-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) ~[jetty-io-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:781) ~[jetty-util-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:917) ~[jetty-util-9.4.19.v20190610.jar:9.4.19.v20190610]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 666653 ERROR (qtp1757675020-8941) [n:127.0.0.1:63126_solr ] o.a.s.h.a.CollectionHandlerApi Error in running command {
[junit4] 2> "add-package":{
[junit4] 2> "name":"foo",
[junit4] 2> "url":"http://localhost:63119/jar0.jar",
[junit4] 2> "sha256":"e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc"},
[junit4] 2> "errors":["no such resource available: foo, url : http://localhost:63119/jar0.jar"]} , current clusterprops.json : {}
[junit4] 2> 666654 INFO (qtp1757675020-8941) [n:127.0.0.1:63126_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster params={wt=javabin&version=2} status=400 QTime=14
[junit4] 2> 666657 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 666660 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.c.PackageManager clusterprops.json changed , version -1
[junit4] 2> 666660 DEBUG (zkCallback-3575-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666660 DEBUG (zkCallback-3564-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666661 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666661 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 666661 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666661 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 666668 DEBUG (zkCallback-3570-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666668 DEBUG (zkCallback-3588-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666669 DEBUG (zkCallback-3547-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666669 DEBUG (zkCallback-3580-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666669 INFO (zkCallback-3547-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 666669 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}} to version 0
[junit4] 2> 666669 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 666670 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 666671 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 666671 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 666672 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 666675 INFO (zkCallback-3547-thread-1) [ ] o.a.s.c.CloudUtil Error fetching key names
[junit4] 2> 666734 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:63119/jar1.jar]
[junit4] 2> 666734 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.h.a.CollectionHandlerApi current version of clusterprops.json is 0 , trying to get every node to update
[junit4] 2> 666734 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:63119/jar1.jar]
[junit4] 2> 666734 DEBUG (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.h.a.CollectionHandlerApi The current clusterprops.json: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}}
[junit4] 2> 666735 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.h.a.CollectionHandlerApi Waiting up to 30 secs for 4 nodes to update clusterprops to be of version 0
[junit4] 2> 666737 INFO (solrHandlerExecutor-2841-thread-2-processing-n:127.0.0.1:63126_solr) [n:127.0.0.1:63126_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 666737 INFO (solrHandlerExecutor-2841-thread-1-processing-n:127.0.0.1:63126_solr) [n:127.0.0.1:63126_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 666738 INFO (solrHandlerExecutor-2841-thread-3-processing-n:127.0.0.1:63126_solr) [n:127.0.0.1:63126_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 666738 INFO (solrHandlerExecutor-2841-thread-4-processing-n:127.0.0.1:63126_solr) [n:127.0.0.1:63126_solr ] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 666741 INFO (zkCallback-3547-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:63119/jar1.jar]
[junit4] 2> 666741 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:63119/jar1.jar]
[junit4] 2> 666741 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.PackageManager Libraries changed. New memclassloader created with jars [http://localhost:63119/jar1.jar]
[junit4] 2> 666845 DEBUG (qtp1738583566-8955) [n:127.0.0.1:63127_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 666845 DEBUG (qtp1738583566-8955) [n:127.0.0.1:63127_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 666846 INFO (qtp1738583566-8955) [n:127.0.0.1:63127_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 666847 DEBUG (qtp1757675020-8950) [n:127.0.0.1:63126_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 666847 DEBUG (qtp1757675020-8950) [n:127.0.0.1:63126_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 666847 INFO (qtp1757675020-8950) [n:127.0.0.1:63126_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 666849 DEBUG (qtp1129421360-8944) [n:127.0.0.1:63125_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 666849 DEBUG (qtp1129421360-8944) [n:127.0.0.1:63125_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 666849 INFO (qtp1129421360-8944) [n:127.0.0.1:63125_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 666851 DEBUG (qtp1128815908-8959) [n:127.0.0.1:63130_solr ] o.a.s.c.PackageManager expected version : 0 , my version 0
[junit4] 2> 666851 DEBUG (qtp1128815908-8959) [n:127.0.0.1:63130_solr ] o.a.s.c.c.ZkStateReader Expected version of clusterprops.json is 0 , my version is 0
[junit4] 2> 666851 INFO (qtp1128815908-8959) [n:127.0.0.1:63130_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/ext params={znodeVersion=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 666853 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.h.a.CollectionHandlerApi Took 119.0ms to update the clusterprops to be of version 0 on 4 nodes
[junit4] 2> 666853 INFO (qtp1757675020-8947) [n:127.0.0.1:63126_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster params={wt=javabin&version=2} status=0 QTime=198
[junit4] 2> 666865 INFO (qtp1129421360-8957) [n:127.0.0.1:63125_solr ] o.a.s.c.PackageManager clusterprops.json changed , version 0
[junit4] 2> 666865 DEBUG (zkCallback-3545-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}, requestHandler={bar={name=bar, class=org.apache.solr.core.RuntimeLibReqHandler, package=global}}} to version 1
[junit4] 2> 666865 INFO (zkCallback-3545-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 1
[junit4] 2> 666865 DEBUG (zkCallback-3547-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}, requestHandler={bar={name=bar, class=org.apache.solr.core.RuntimeLibReqHandler, package=global}}} to version 1
[junit4] 2> 666865 INFO (zkCallback-3547-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 1
[junit4] 2> 666865 DEBUG (zkCallback-3570-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}, requestHandler={bar={name=bar, class=org.apache.solr.core.RuntimeLibReqHandler, package=global}}} to version 1
[junit4] 2> 666865 DEBUG (zkCallback-3549-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}, requestHandler={bar={name=bar, class=org.apache.solr.core.RuntimeLibReqHandler, package=global}}} to version 1
[junit4] 2> 666865 INFO (zkCallback-3549-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 1
[junit4] 2> 666866 DEBUG (zkCallback-3580-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}, requestHandler={bar={name=bar, class=org.apache.solr.core.RuntimeLibReqHandler, package=global}}} to version 1
[junit4] 2> 666866 DEBUG (zkCallback-3551-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {package={global={name=global, url=http://localhost:63119/jar1.jar, sha256=e1f9e23988c19619402f1040c9251556dcd6e02b9d3e3b966a129ea1be5c70fc}}, requestHandler={bar={name=bar, class=org.apache.solr.core.RuntimeLibReqHandler, package=global}}} to version 1
[junit4] 2> 666866 INFO (zkCallback-3551-thread-1) [ ] o.a.s.c.PackageManager clusterprops.json changed , version 1
[junit4] 2> 666866 DEBUG (zkCallback-3575-thread-1) [
[...truncated too long message...]
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 699572 DEBUG (zkCallback-4160-thread-3) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 699578 INFO (closeThreadPool-4209-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72119546018922507-127.0.0.1:63426_solr-n_0000000000) closing
[junit4] 2> 699674 INFO (zkCallback-4144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 699674 INFO (zkCallback-4160-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 699674 INFO (zkCallback-4151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 699674 DEBUG (zkCallback-4144-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63425_solr, 127.0.0.1:63426_solr, 127.0.0.1:63427_solr] -> [127.0.0.1:63425_solr]
[junit4] 2> 699674 DEBUG (zkCallback-4160-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63425_solr, 127.0.0.1:63426_solr, 127.0.0.1:63427_solr] -> [127.0.0.1:63425_solr]
[junit4] 2> 699674 DEBUG (zkCallback-4151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63425_solr, 127.0.0.1:63426_solr, 127.0.0.1:63427_solr] -> [127.0.0.1:63425_solr]
[junit4] 2> 699674 INFO (zkCallback-4167-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 699674 DEBUG (zkCallback-4167-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:63425_solr, 127.0.0.1:63426_solr, 127.0.0.1:63427_solr] -> [127.0.0.1:63425_solr]
[junit4] 2> 699675 INFO (jetty-closer-4195-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72119546018922507-127.0.0.1:63426_solr-n_0000000000) closing
[junit4] 2> 699763 INFO (jetty-closer-4195-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3fa8b2e4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 699764 INFO (jetty-closer-4195-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f67bbdb{/solr,null,UNAVAILABLE}
[junit4] 2> 699764 INFO (jetty-closer-4195-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 699893 INFO (jetty-closer-4195-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@209e240d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 699894 INFO (jetty-closer-4195-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@487717f{/solr,null,UNAVAILABLE}
[junit4] 2> 699894 INFO (jetty-closer-4195-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 699996 INFO (jetty-closer-4195-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@50c6b494{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 699997 INFO (jetty-closer-4195-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e904e7c{/solr,null,UNAVAILABLE}
[junit4] 2> 699998 INFO (jetty-closer-4195-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 700099 INFO (jetty-closer-4195-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2ebc4865{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 700100 INFO (jetty-closer-4195-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@42599a65{/solr,null,UNAVAILABLE}
[junit4] 2> 700100 INFO (jetty-closer-4195-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 700101 INFO (TEST-TestContainerReqHandler.testPluginFrompackage-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 700308 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> 10 /solr/aliases.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 6 /solr/collections/globalLoaderColl/state.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf
[junit4] 2> 3 /solr/collections/globalLoaderColl/terms/shard2
[junit4] 2> 2 /solr/collections/globalLoaderColl/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/collections/globalLoaderColl/state.json
[junit4] 2> 19 /solr/clusterprops.json
[junit4] 2> 10 /solr/aliases.json
[junit4] 2> 10 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/72119546018922507-127.0.0.1:63426_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/live_nodes
[junit4] 2> 29 /solr/collections
[junit4] 2> 21 /solr/overseer/queue
[junit4] 2> 9 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 701167 INFO (TEST-TestContainerReqHandler.testPluginFrompackage-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:63416
[junit4] 2> 701167 INFO (TEST-TestContainerReqHandler.testPluginFrompackage-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63416
[junit4] 2> 701167 INFO (TEST-TestContainerReqHandler.testPluginFrompackage-seed#[83D34C1E775CB7E7]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63416
[junit4] 2> 701168 INFO (TEST-TestContainerReqHandler.testPluginFrompackage-seed#[83D34C1E775CB7E7]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@47b446ec{HTTP/1.1,[http/1.1]}{0.0.0.0:0}
[junit4] 2> 701169 INFO (TEST-TestContainerReqHandler.testPluginFrompackage-seed#[83D34C1E775CB7E7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPluginFrompackage
[junit4] 2> 701169 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 701170 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:63416
[junit4] 2> 701170 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63416
[junit4] 2> 701170 INFO (SUITE-TestContainerReqHandler-seed#[83D34C1E775CB7E7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63416
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestContainerReqHandler_83D34C1E775CB7E7-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {_root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=FSTOrd50, desc_s=FSTOrd50}, docValues:{_version_=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1399, maxMBSortInHeap=7.516172226640213, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@27b54ae7), locale=no-NO, timezone=Etc/GMT-8
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=3,threads=1,free=78218672,total=300941312
[junit4] 2> NOTE: All tests run in this JVM: [TaggerTest, SplitByPrefixTest, DirectoryFactoryTest, HLLSerializationTest, TestHashPartitioner, NoCacheHeaderTest, FacetPivotSmallTest, DistribDocExpirationUpdateProcessorTest, LukeRequestHandlerTest, ReindexCollectionTest, CdcrUpdateLogTest, MultiDestinationAuditLoggerTest, MoveReplicaHDFSFailoverTest, CreateRoutedAliasTest, DistanceFunctionTest, TestBulkSchemaAPI, AddSchemaFieldsUpdateProcessorFactoryTest, TestCloudDeleteByQuery, CollectionStateFormat2Test, JvmMetricsTest, TestHighlightDedupGrouping, SolrRequestParserTest, ReplicationFactorTest, TestSizeLimitedDistributedMap, OverriddenZkACLAndCredentialsProvidersTest, HdfsChaosMonkeyNothingIsSafeTest, ZkControllerTest, PingRequestHandlerTest, TestPartialUpdateDeduplication, TestConfig, TestDynamicLoading, RollingRestartTest, RandomizedTaggerTest, TestSolrCoreParser, ClusterStateUpdateTest, TestDistribDocBasedVersion, DistributedQueryComponentCustomSortTest, SampleTest, SimplePostToolTest, CloudMLTQParserTest, TestPullReplicaErrorHandling, SchemaVersionSpecificBehaviorTest, SolrCmdDistributorTest, TestContainerReqHandler]
[junit4] Completed [108/876 (1!)] on J1 in 36.66s, 6 tests, 1 failure <<< FAILURES!
[...truncated 47345 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/325/consoleText
[repro] Revision: f56aacd0a1f4c75936ab6cec779066546a9439f9
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=TestContainerReqHandler -Dtests.method=testPackageAPI -Dtests.seed=83D34C1E775CB7E7 -Dtests.slow=true -Dtests.locale=no-NO -Dtests.timezone=Etc/GMT-8 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestContainerReqHandler
[repro] ant compile-test
[...truncated 3608 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestContainerReqHandler" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=83D34C1E775CB7E7 -Dtests.slow=true -Dtests.locale=no-NO -Dtests.timezone=Etc/GMT-8 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 97 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.handler.TestContainerReqHandler
[repro] Exiting with code 0
[...truncated 73 lines...]