You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/01/23 20:45:46 UTC
[JENKINS-Experimental-GC] Lucene-Solr-8.x-Linux (64bit/jdk-12.0.2)
- Build # 1882 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/1882/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
1 tests failed.
FAILED: org.apache.solr.pkg.TestPackages.testPluginLoading
Error Message:
req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1314/0x00007fcd777504b0@157abb78 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , { "responseHeader":{ "status":0, "QTime":0}, "config":{"queryResponseWriter":{"json1":{ "name":"json1", "class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
Stack Trace:
java.lang.AssertionError: req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1314/0x00007fcd777504b0@157abb78 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , {
"responseHeader":{
"status":0,
"QTime":0},
"config":{"queryResponseWriter":{"json1":{
"name":"json1",
"class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
at __randomizedtesting.SeedInfo.seed([FCB8C97E5CFF839C:2A3E2983BFE14406]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:242)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:213)
at org.apache.solr.pkg.TestPackages.verifyCmponent(TestPackages.java:415)
at org.apache.solr.pkg.TestPackages.testPluginLoading(TestPackages.java:153)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java: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 14932 lines...]
[junit4] Suite: org.apache.solr.pkg.TestPackages
[junit4] 2> 637380 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 637381 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/data-dir-70-001
[junit4] 2> 637381 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 637382 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 637384 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPluginLoading
[junit4] 2> 637385 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001
[junit4] 2> 637385 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 637385 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 637385 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 637485 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer start zk server on port:46231
[junit4] 2> 637485 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46231
[junit4] 2> 637485 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46231
[junit4] 2> 637485 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46231
[junit4] 2> 637487 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637487 INFO (zkConnectionManagerCallback-2327-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637487 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637489 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637490 INFO (zkConnectionManagerCallback-2329-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637490 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637490 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637490 INFO (zkConnectionManagerCallback-2331-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637490 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637594 WARN (jetty-launcher-2332-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 637594 WARN (jetty-launcher-2332-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
[junit4] 2> 637594 WARN (jetty-launcher-2332-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
[junit4] 2> 637594 WARN (jetty-launcher-2332-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 637594 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 12.0.2+10
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7225c352{/solr,null,AVAILABLE}
[junit4] 2> 637608 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@758232c1{/solr,null,AVAILABLE}
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2d2f00bb{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41651}
[junit4] 2> 637613 ERROR (jetty-launcher-2332-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 637613 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T20:14:37.883725Z
[junit4] 2> 637615 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7f7b24bb{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33711}
[junit4] 2> 637616 ERROR (jetty-launcher-2332-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 637616 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T20:14:37.886293Z
[junit4] 2> 637620 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637620 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637620 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 637620 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 637620 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 637626 INFO (zkConnectionManagerCallback-2336-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637626 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637626 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1513bc8d{/solr,null,AVAILABLE}
[junit4] 2> 637635 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 637635 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 637635 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 637635 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 637639 INFO (zkConnectionManagerCallback-2334-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637639 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637647 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2582049a{/solr,null,AVAILABLE}
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6cf9ef55{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39441}
[junit4] 2> 637652 ERROR (jetty-launcher-2332-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 637652 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T20:14:37.922507Z
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6143c4e9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45813}
[junit4] 2> 637661 ERROR (jetty-launcher-2332-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 637661 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T20:14:37.931473Z
[junit4] 2> 637666 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637670 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637671 INFO (zkConnectionManagerCallback-2338-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637671 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637671 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 637679 INFO (zkConnectionManagerCallback-2340-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637679 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637679 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 637845 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 637852 WARN (jetty-launcher-2332-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c8a53fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637852 WARN (jetty-launcher-2332-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c8a53fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637864 WARN (jetty-launcher-2332-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@69d83509[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637864 WARN (jetty-launcher-2332-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@69d83509[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637865 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46231/solr
[junit4] 2> 637866 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637868 INFO (zkConnectionManagerCallback-2347-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637868 INFO (jetty-launcher-2332-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 637924 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 637926 WARN (jetty-launcher-2332-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3805327e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637926 WARN (jetty-launcher-2332-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3805327e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637944 WARN (jetty-launcher-2332-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@64056059[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637944 WARN (jetty-launcher-2332-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@64056059[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 637946 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46231/solr
[junit4] 2> 637947 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 637952 INFO (zkConnectionManagerCallback-2354-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 637952 INFO (jetty-launcher-2332-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638051 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 638052 WARN (jetty-launcher-2332-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@623c0a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638052 WARN (jetty-launcher-2332-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@623c0a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638053 WARN (jetty-launcher-2332-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6df6bc50[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638053 WARN (jetty-launcher-2332-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6df6bc50[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638054 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46231/solr
[junit4] 2> 638054 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638055 INFO (zkConnectionManagerCallback-2361-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638055 INFO (jetty-launcher-2332-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638170 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638171 INFO (zkConnectionManagerCallback-2364-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638171 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638175 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 638176 WARN (jetty-launcher-2332-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f592742[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638176 WARN (jetty-launcher-2332-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f592742[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638177 WARN (jetty-launcher-2332-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@25f6df48[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638177 WARN (jetty-launcher-2332-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@25f6df48[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 638178 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46231/solr
[junit4] 2> 638178 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638179 INFO (zkConnectionManagerCallback-2372-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638179 INFO (jetty-launcher-2332-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638205 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39441_solr
[junit4] 2> 638206 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.Overseer Overseer (id=72062429952016394-127.0.0.1:39441_solr-n_0000000000) starting
[junit4] 2> 638208 INFO (OverseerStateUpdate-72062429952016394-127.0.0.1:39441_solr-n_0000000000) [n:127.0.0.1:39441_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39441_solr
[junit4] 2> 638208 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39441_solr
[junit4] 2> 638208 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 638209 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 638209 WARN (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 638223 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 638241 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39441.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638246 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39441.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638246 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39441.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638247 INFO (jetty-launcher-2332-thread-2) [n:127.0.0.1:39441_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node2/.
[junit4] 2> 638270 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638270 INFO (zkConnectionManagerCallback-2376-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638270 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638272 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 638274 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45813_solr as DOWN
[junit4] 2> 638274 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 638274 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45813_solr
[junit4] 2> 638275 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 638275 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 638275 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 638276 WARN (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 638286 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 638300 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45813.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638306 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45813.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638307 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45813.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638308 INFO (jetty-launcher-2332-thread-3) [n:127.0.0.1:45813_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node3/.
[junit4] 2> 638370 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638371 INFO (zkConnectionManagerCallback-2382-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638371 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638395 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 638396 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33711_solr as DOWN
[junit4] 2> 638397 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 638397 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33711_solr
[junit4] 2> 638398 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 638398 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 638398 INFO (zkCallback-2381-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 638399 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 638400 WARN (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 638411 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 638426 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33711.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638432 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33711.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638432 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33711.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638433 INFO (jetty-launcher-2332-thread-4) [n:127.0.0.1:33711_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node4/.
[junit4] 2> 638470 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638472 INFO (zkConnectionManagerCallback-2388-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638472 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638474 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 638476 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41651_solr as DOWN
[junit4] 2> 638476 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 638476 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41651_solr
[junit4] 2> 638477 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 638477 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 638477 INFO (zkCallback-2381-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 638477 INFO (zkCallback-2387-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 638477 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 638478 WARN (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 638486 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 638497 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41651.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638502 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41651.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638503 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41651.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 638505 INFO (jetty-launcher-2332-thread-1) [n:127.0.0.1:41651_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node1/.
[junit4] 2> 638527 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 638533 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 638536 INFO (zkConnectionManagerCallback-2397-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 638536 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 638537 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 638538 INFO (TEST-TestPackages.testPluginLoading-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46231/solr ready
[junit4] 2> 638566 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:33711_solr} status=0 QTime=1
[junit4] 2> 638566 INFO (updateExecutor-2368-thread-1-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:33711_solr
[junit4] 2> 638568 INFO (qtp1908196604-7631) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 638569 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:33711_solr} status=0 QTime=1
[junit4] 2> 638570 INFO (updateExecutor-2357-thread-1-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:33711_solr
[junit4] 2> 638570 INFO (qtp1908196604-7631) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 638570 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 638570 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:33711_solr} status=0 QTime=0
[junit4] 2> 638571 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:33711_solr
[junit4] 2> 638571 INFO (qtp1908196604-7631) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 638571 INFO (updateExecutor-2368-thread-1-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 638571 INFO (updateExecutor-2368-thread-1-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 638571 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 638571 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={sync=true} status=0 QTime=14
[junit4] 1> sync resp: http://127.0.0.1:33711/api/node/files/_trusted_/keys/pub_key512.der?sync=true ,is: null
[junit4] 2> 638572 INFO (qtp1908196604-7631) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 638572 INFO (updateExecutor-2357-thread-1-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 638572 INFO (updateExecutor-2357-thread-1-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 638573 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 638573 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 638575 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 638578 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638583 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638587 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638597 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638601 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/runtimelibs.jar
[junit4] 2> 638605 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638607 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638608 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638608 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/runtimelibs.jar
[junit4] 2> 638610 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 638615 INFO (qtp1908196604-7631) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:39441_solr} status=0 QTime=3
[junit4] 2> 638615 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:39441_solr
[junit4] 2> 638616 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:39441_solr} status=0 QTime=0
[junit4] 2> 638616 INFO (updateExecutor-2368-thread-1-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:39441_solr
[junit4] 2> 638616 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 638616 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:39441_solr} status=0 QTime=0
[junit4] 2> 638617 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:39441_solr
[junit4] 2> 638617 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 638618 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 638618 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 638620 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 638620 INFO (updateExecutor-2368-thread-1-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 638620 INFO (updateExecutor-2368-thread-1-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 638620 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/runtimelibs.jar params={sig=L3q/qIGs4NaF6JiO0ZkMUFa88j0OmYc%2BI6O7BOdNuMct/xoZ4h73aZHZGc0%2BnmI1f/U3bOlMPINlSOM6LK3JpQ%3D%3D&wt=javabin&version=2} status=0 QTime=29
[junit4] 2> 638621 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 638621 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 638621 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 638623 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 638623 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 638632 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638639 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638646 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638652 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638657 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638659 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/testurpv1.jar
[junit4] 2> 638659 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638660 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638664 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638664 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/testurpv1.jar
[junit4] 2> 638665 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 638669 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:41651_solr} status=0 QTime=0
[junit4] 2> 638670 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:41651_solr
[junit4] 2> 638670 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 638671 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 638671 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:41651_solr} status=0 QTime=1
[junit4] 2> 638672 INFO (updateExecutor-2357-thread-2-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:41651_solr
[junit4] 2> 638672 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 638672 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 638672 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:41651_solr} status=0 QTime=0
[junit4] 2> 638672 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:41651_solr
[junit4] 2> 638672 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 638673 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/testurpv1.jar params={sig=h6UmMzuPqu4hQFGLBMJh/6kDSEXpJlgLsQDXx0KuxXWkV5giilRP57K3towiJRh2J%2BrqihqIghNCi3YgzgUnWQ%3D%3D&wt=javabin&version=2} status=0 QTime=18
[junit4] 2> 638673 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 638673 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 638674 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 638675 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 638675 INFO (updateExecutor-2350-thread-1-processing-n:127.0.0.1:45813_solr) [n:127.0.0.1:45813_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 638675 INFO (updateExecutor-2357-thread-2-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 638675 INFO (updateExecutor-2357-thread-2-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 638682 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638685 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638693 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638696 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638704 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638706 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/expressible.jar
[junit4] 2> 638708 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638710 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638711 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638711 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/expressible.jar
[junit4] 2> 638712 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 638714 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:45813_solr} status=0 QTime=0
[junit4] 2> 638714 INFO (updateExecutor-2368-thread-2-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:45813_solr
[junit4] 2> 638714 INFO (qtp1908196604-7631) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:45813_solr} status=0 QTime=0
[junit4] 2> 638714 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 638714 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:45813_solr
[junit4] 2> 638715 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 638715 INFO (qtp1648237922-7640) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:45813_solr} status=0 QTime=0
[junit4] 2> 638715 INFO (updateExecutor-2357-thread-2-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:45813_solr
[junit4] 2> 638715 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/expressible.jar params={sig=ZOT11arAiPmPZYOHzqodiNnxO9pRyRozWZEBX8XGjU1/HJptFnZK%2BDI7eXnUtbNaMcbXE2Ze8hh4M/eGyhY8BQ%3D%3D&wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 638716 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 638716 INFO (qtp1870931817-7659) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 638716 INFO (qtp1870931817-7660) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 638716 INFO (qtp1870931817-7659) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 638717 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 638717 INFO (updateExecutor-2343-thread-2-processing-n:127.0.0.1:33711_solr) [n:127.0.0.1:33711_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 638717 INFO (updateExecutor-2368-thread-2-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 638717 INFO (updateExecutor-2368-thread-2-processing-n:127.0.0.1:41651_solr) [n:127.0.0.1:41651_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 638717 INFO (updateExecutor-2357-thread-2-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 638717 INFO (updateExecutor-2357-thread-2-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 638719 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638721 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638724 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638726 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638758 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638759 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638761 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638762 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638763 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638764 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638767 DEBUG (zkCallback-2363-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 638767 DEBUG (zkCallback-2375-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 638767 DEBUG (zkCallback-2387-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 638767 DEBUG (zkCallback-2381-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 638767 INFO (qtp1908196604-7625) [n:127.0.0.1:33711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638768 INFO (qtp1648237922-7630) [n:127.0.0.1:39441_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638768 INFO (qtp1870931817-7642) [n:127.0.0.1:45813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 638768 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.p.PackageLoader /packages.json updated to version 1
[junit4] 2> 638768 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 638769 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 638771 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638772 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638774 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638775 INFO (zkCallback-2387-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 638775 INFO (zkCallback-2375-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 638775 INFO (zkCallback-2381-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 638775 INFO (zkCallback-2363-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 638775 INFO (zkCallback-2387-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 638775 INFO (zkCallback-2363-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 638775 INFO (zkCallback-2381-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 638775 INFO (zkCallback-2375-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 638775 INFO (zkCallback-2363-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 638775 INFO (zkCallback-2375-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 638775 INFO (zkCallback-2381-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 638776 INFO (zkCallback-2381-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638776 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638776 INFO (zkCallback-2363-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638777 INFO (zkCallback-2375-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638777 INFO (zkCallback-2363-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638777 INFO (zkCallback-2381-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638777 INFO (zkCallback-2375-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638778 INFO (zkCallback-2363-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638778 INFO (zkCallback-2381-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638778 INFO (zkCallback-2363-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638778 INFO (zkCallback-2381-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638779 INFO (zkCallback-2375-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638780 INFO (zkCallback-2381-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638780 INFO (zkCallback-2363-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638780 INFO (zkCallback-2375-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638780 INFO (zkCallback-2381-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638780 INFO (zkCallback-2363-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638781 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638781 INFO (zkCallback-2375-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638782 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 638782 INFO (zkCallback-2381-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 638782 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 638782 INFO (zkCallback-2381-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 638782 INFO (zkCallback-2363-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 638782 INFO (zkCallback-2381-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 638782 INFO (zkCallback-2363-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 638782 INFO (zkCallback-2381-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 638788 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638788 INFO (zkCallback-2375-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 638790 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 638790 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 638791 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 638791 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 638791 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 638791 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 1 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 638791 INFO (zkCallback-2375-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 638791 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 638791 INFO (qtp602466998-7627) [n:127.0.0.1:41651_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=62
[junit4] 2> 638793 INFO (qtp602466998-7645) [n:127.0.0.1:41651_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=testPluginLoadingColl&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 638808 INFO (OverseerThreadFactory-2634-thread-1-processing-n:127.0.0.1:39441_solr) [n:127.0.0.1:39441_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testPluginLoadingColl
[junit4] 2> 638915 INFO (OverseerStateUpdate-72062429952016394-127.0.0.1:39441_solr-n_0000000000) [n:127.0.0.1:39441_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:39441/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 638919 INFO (OverseerStateUpdate-72062429952016394-127.0.0.1:39441_solr-n_0000000000) [n:127.0.0.1:39441_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:45813/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 638920 INFO (OverseerStateUpdate-72062429952016394-127.0.0.1:39441_solr-n_0000000000) [n:127.0.0.1:39441_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33711/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 638920 INFO (OverseerStateUpdate-72062429952016394-127.0.0.1:39441_solr-n_0000000000) [n:127.0.0.1:39441_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41651/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 639126 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n4&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 639126 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n6&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 639126 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n2&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 639126 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n1&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 639127 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 640140 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 640140 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 640140 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 640145 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 640153 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard1_replica_n2] Schema name=minimal
[junit4] 2> 640153 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard1_replica_n1] Schema name=minimal
[junit4] 2> 640153 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard2_replica_n4] Schema name=minimal
[junit4] 2> 640155 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema [testPluginLoadingColl_shard2_replica_n6] Schema name=minimal
[junit4] 2> 640155 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 640155 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 640155 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 640155 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n4' using configuration from configset conf, trusted=true
[junit4] 2> 640155 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 640155 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 640156 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 640156 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 640157 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45813.solr.core.testPluginLoadingColl.shard1.replica_n2' (registry 'solr.core.testPluginLoadingColl.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 640157 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33711.solr.core.testPluginLoadingColl.shard2.replica_n4' (registry 'solr.core.testPluginLoadingColl.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 640157 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39441.solr.core.testPluginLoadingColl.shard1.replica_n1' (registry 'solr.core.testPluginLoadingColl.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 640157 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41651.solr.core.testPluginLoadingColl.shard2.replica_n6' (registry 'solr.core.testPluginLoadingColl.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2887ef97
[junit4] 2> 640157 INFO (qtp1648237922-7647) [n:127.0.0.1:39441_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node2/testPluginLoadingColl_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node2/./testPluginLoadingColl_shard1_replica_n1/data/]
[junit4] 2> 640157 INFO (qtp1908196604-7635) [n:127.0.0.1:33711_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard2_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node4/testPluginLoadingColl_shard2_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node4/./testPluginLoadingColl_shard2_replica_n4/data/]
[junit4] 2> 640157 INFO (qtp1870931817-7634) [n:127.0.0.1:45813_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node3/testPluginLoadingColl_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node3/./testPluginLoadingColl_shard1_replica_n2/data/]
[junit4] 2> 640157 INFO (qtp602466998-7653) [n:127.0.0.1:41651_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node1/testPluginLoadingColl_shard2_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001/tempDir-001/node1/./testPluginLoadingColl_shard2_replica_n6/data/]
[junit4
[...truncated too long message...]
JmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@327a9d15: rootName = solr_39725, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@f4e7aed
[junit4] 2> 650430 INFO (jetty-closer-2503-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 650430 INFO (jetty-closer-2503-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15489e54: rootName = solr_36813, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6fc80c84
[junit4] 2> 650430 INFO (jetty-closer-2503-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 650431 INFO (jetty-closer-2503-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 650431 INFO (jetty-closer-2503-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76e5b5a2: rootName = solr_39725, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6fc80c84
[junit4] 2> 650431 INFO (jetty-closer-2503-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 650441 INFO (zkCallback-2468-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 650444 INFO (closeThreadPool-2518-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72062430708695050-127.0.0.1:39725_solr-n_0000000000) closing
[junit4] 2> 650444 INFO (OverseerStateUpdate-72062430708695050-127.0.0.1:39725_solr-n_0000000000) [n:127.0.0.1:39725_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39725_solr
[junit4] 2> 650444 INFO (OverseerAutoScalingTriggerThread-72062430708695050-127.0.0.1:39725_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 650444 INFO (closeThreadPool-2518-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72062430708695050-127.0.0.1:39725_solr-n_0000000000) closing
[junit4] 2> 650538 INFO (zkCallback-2486-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 650538 INFO (zkCallback-2492-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (2)
[junit4] 2> 650539 INFO (jetty-closer-2503-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@10684368{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?}
[junit4] 2> 650539 INFO (jetty-closer-2503-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6ea17aec{/solr,null,UNAVAILABLE}
[junit4] 2> 650539 INFO (jetty-closer-2503-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 650639 INFO (jetty-closer-2503-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72062430708695050-127.0.0.1:39725_solr-n_0000000000) closing
[junit4] 2> 650742 INFO (jetty-closer-2503-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1683e5d7{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?}
[junit4] 2> 650742 INFO (jetty-closer-2503-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@67180efb{/solr,null,UNAVAILABLE}
[junit4] 2> 650742 INFO (jetty-closer-2503-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 660177 INFO (jetty-closer-2503-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@432f8859{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?}
[junit4] 2> 660177 INFO (jetty-closer-2503-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7544a526{/solr,null,UNAVAILABLE}
[junit4] 2> 660177 INFO (jetty-closer-2503-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 660236 INFO (jetty-closer-2503-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@30f50473{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?}
[junit4] 2> 660236 INFO (jetty-closer-2503-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@30152430{/solr,null,UNAVAILABLE}
[junit4] 2> 660236 INFO (jetty-closer-2503-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 660236 INFO (TEST-TestPackages.testAPI-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 660439 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/packages.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/live_nodes
[junit4] 2> 8 /solr/overseer/queue
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 660440 INFO (TEST-TestPackages.testAPI-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43113
[junit4] 2> 660440 INFO (TEST-TestPackages.testAPI-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43113
[junit4] 2> 660440 INFO (TEST-TestPackages.testAPI-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43113
[junit4] 2> 660441 INFO (TEST-TestPackages.testAPI-seed#[FCB8C97E5CFF839C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAPI
[junit4] 2> 660441 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 660441 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43113
[junit4] 2> 660441 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43113
[junit4] 2> 660441 INFO (SUITE-TestPackages-seed#[FCB8C97E5CFF839C]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43113
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_FCB8C97E5CFF839C-001
[junit4] 2> Jan 23, 2020 8:15:00 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1909, maxMBSortInHeap=7.417699365376715, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2c62b7b8), locale=ne, timezone=America/Inuvik
[junit4] 2> NOTE: Linux 5.3.0-26-generic amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=16,threads=1,free=373293056,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestHighlightDedupGrouping, DocExpirationUpdateProcessorFactoryTest, MetricsConfigTest, DateRangeFieldTest, SchemaVersionSpecificBehaviorTest, TestXmlQParserPlugin, TestSchemalessBufferedUpdates, TestFieldCache, TriLevelCompositeIdRoutingTest, NodeMutatorTest, TestSimPolicyCloud, SuggesterTSTTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestDefaultStatsCache, SolrCmdDistributorTest, IndexSizeTriggerTest, TermVectorComponentDistributedTest, TestSolrTestCaseJ4, TestShortCircuitedRequests, ClassificationUpdateProcessorIntegrationTest, ZkStateReaderTest, TestGeoJSONResponseWriter, LeaderElectionContextKeyTest, TestRawTransformer, MBeansHandlerTest, MoreLikeThisHandlerTest, HLLSerializationTest, LeaderVoteWaitTimeoutTest, SearchHandlerTest, SolrPluginUtilsTest, TestFieldSortValues, TestRangeQuery, TestConfigReload, TestBadConfig, TestConfigSets, TestNestedUpdateProcessor, CollectionTooManyReplicasTest, TestIntervalFaceting, TriggerIntegrationTest, TestCloudRecovery, TestIndexSearcher, XMLAtomicUpdateMultivalueTest, TestSerializedLuceneMatchVersion, TestSolr4Spatial2, TestJmxIntegration, TestConfigSetsAPIZkFailure, TestPackages]
[junit4] Completed [429/896 (1!)] on J1 in 24.01s, 2 tests, 1 failure <<< FAILURES!
[...truncated 46382 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/1882/consoleText
[repro] Revision: 89ded7060d2685a66ee25e218dbc8eb82f1e7380
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line: ant test -Dtestcase=TestPackages -Dtests.method=testPluginLoading -Dtests.seed=FCB8C97E5CFF839C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ne -Dtests.timezone=America/Inuvik -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestPackages
[repro] ant compile-test
[...truncated 3607 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestPackages" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=FCB8C97E5CFF839C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ne -Dtests.timezone=America/Inuvik -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.pkg.TestPackages
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk-13.0.2) - Build # 1884 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Error processing tokens: Error while parsing action 'Text/ZeroOrMore/FirstOf/Token/DelimitedToken/DelimitedToken_Action3' at input position (line 79, pos 4):
)"}
^
java.lang.OutOfMemoryError: Java heap space
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk1.8.0_201) - Build # 1883
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/1883/
Java: 64bit/jdk1.8.0_201 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.schema.TestBulkSchemaConcurrent.test
Error Message:
[[CopyField source=a0,dest=hello_lol0 not created, new type mystr0 not created, dynamic field *_lol0 not created, field a0 not created], [], [new type mystr2 not created, dynamic field *_lol2 not created, field a2 not created, CopyField source=a2,dest=hello_lol2 not created], [dynamic field *_lol3 not created, field a3 not created, new type mystr3 not created, CopyField source=a3,dest=hello_lol3 not created], []]
Stack Trace:
java.lang.AssertionError: [[CopyField source=a0,dest=hello_lol0 not created, new type mystr0 not created, dynamic field *_lol0 not created, field a0 not created], [], [new type mystr2 not created, dynamic field *_lol2 not created, field a2 not created, CopyField source=a2,dest=hello_lol2 not created], [dynamic field *_lol3 not created, field a3 not created, new type mystr3 not created, CopyField source=a3,dest=hello_lol3 not created], []]
at __randomizedtesting.SeedInfo.seed([AAA29BB9346E49F4:22F6A4639A92240C]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:94)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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 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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16630 lines...]
[junit4] Suite: org.apache.solr.schema.TestBulkSchemaConcurrent
[junit4] 2> 1068032 INFO (SUITE-TestBulkSchemaConcurrent-seed#[AAA29BB9346E49F4]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/data-dir-134-001
[junit4] 2> 1068032 WARN (SUITE-TestBulkSchemaConcurrent-seed#[AAA29BB9346E49F4]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=58 numCloses=58
[junit4] 2> 1068033 INFO (SUITE-TestBulkSchemaConcurrent-seed#[AAA29BB9346E49F4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1068034 INFO (SUITE-TestBulkSchemaConcurrent-seed#[AAA29BB9346E49F4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1068035 INFO (SUITE-TestBulkSchemaConcurrent-seed#[AAA29BB9346E49F4]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1068035 INFO (SUITE-TestBulkSchemaConcurrent-seed#[AAA29BB9346E49F4]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1068039 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1068039 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1068039 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1068139 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer start zk server on port:46409
[junit4] 2> 1068139 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46409
[junit4] 2> 1068139 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46409
[junit4] 2> 1068139 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46409
[junit4] 2> 1068142 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068149 INFO (zkConnectionManagerCallback-6137-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068149 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068162 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068169 INFO (zkConnectionManagerCallback-6139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068169 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068177 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1068187 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1068196 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1068200 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1068204 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1068204 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1068215 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1068216 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1068216 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1068217 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1068217 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1068218 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068221 INFO (zkConnectionManagerCallback-6143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068221 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068322 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1068401 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1068402 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1068402 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1068402 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 1068406 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1068406 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1068406 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1068407 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7ba0c864{/,null,AVAILABLE}
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2dc6770f{SSL,[ssl, http/1.1]}{127.0.0.1:34693}
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.s.Server Started @1068439ms
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/tempDir-001/control/data, hostContext=/, hostPort=34693, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/control-001/cores}
[junit4] 2> 1068408 ERROR (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1068408 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T22:39:22.189Z
[junit4] 2> 1068412 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068415 INFO (zkConnectionManagerCallback-6145-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068415 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068516 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1068516 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/control-001/solr.xml
[junit4] 2> 1068519 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1068519 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1068531 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1068775 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1068776 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1068777 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@31b1293b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068777 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@31b1293b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068780 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1068781 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@26f96572[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068781 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@26f96572[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1068782 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46409/solr
[junit4] 2> 1068783 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068783 INFO (zkConnectionManagerCallback-6152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068783 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068886 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068887 INFO (zkConnectionManagerCallback-6154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068887 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068916 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34693_
[junit4] 2> 1068916 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.Overseer Overseer (id=72062999077584901-127.0.0.1:34693_-n_0000000000) starting
[junit4] 2> 1068918 INFO (OverseerStateUpdate-72062999077584901-127.0.0.1:34693_-n_0000000000) [n:127.0.0.1:34693_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34693_
[junit4] 2> 1068919 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34693_
[junit4] 2> 1068920 INFO (OverseerStateUpdate-72062999077584901-127.0.0.1:34693_-n_0000000000) [n:127.0.0.1:34693_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1068922 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1068923 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1068934 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1068948 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1068954 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1068954 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1068955 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [n:127.0.0.1:34693_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/control-001/cores
[junit4] 2> 1068969 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1068969 INFO (zkConnectionManagerCallback-6163-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1068969 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1068970 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1068970 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46409/solr ready
[junit4] 2> 1068980 INFO (qtp499969544-21446) [n:127.0.0.1:34693_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34693_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1068989 INFO (OverseerThreadFactory-6368-thread-1-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1069097 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1069097 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1070106 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1070112 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1070115 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 1070508 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1070530 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 1070532 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 1070532 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1070532 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1070533 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1070535 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=447, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20310064860350752]
[junit4] 2> 1070537 WARN (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 1070566 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1070566 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1070566 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1070566 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1070567 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=24.673828125, floorSegmentMB=1.8876953125, forceMergeDeletesPctAllowed=19.3537666666824, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=40.173815253762776
[junit4] 2> 1070568 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@37879e98[control_collection_shard1_replica_n1] main]
[junit4] 2> 1070568 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1070568 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1070568 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 1070569 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1070569 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1070569 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656560460077465600
[junit4] 2> 1070570 INFO (searcherExecutor-6373-thread-1-processing-n:127.0.0.1:34693_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@37879e98[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1070571 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1070571 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 1070573 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1070573 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1070573 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34693/control_collection_shard1_replica_n1/
[junit4] 2> 1070573 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1070574 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34693/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1070574 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72062999077584901-core_node2-n_0000000000
[junit4] 2> 1070574 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34693/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1070675 INFO (zkCallback-6153-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1070676 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1070677 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1580
[junit4] 2> 1070678 INFO (zkCallback-6153-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1070681 INFO (qtp499969544-21446) [n:127.0.0.1:34693_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1070778 INFO (zkCallback-6153-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1070778 INFO (zkCallback-6153-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1070779 INFO (zkCallback-6153-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1070779 INFO (qtp499969544-21446) [n:127.0.0.1:34693_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34693_&wt=javabin&version=2} status=0 QTime=1798
[junit4] 2> 1070779 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1070882 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1070883 INFO (zkConnectionManagerCallback-6169-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1070883 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1070884 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1070884 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46409/solr ready
[junit4] 2> 1070884 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1070887 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1070890 INFO (OverseerThreadFactory-6368-thread-2-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1070890 INFO (OverseerCollectionConfigSetProcessor-72062999077584901-127.0.0.1:34693_-n_0000000000) [n:127.0.0.1:34693_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1071091 WARN (OverseerThreadFactory-6368-thread-2-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1071092 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1071092 INFO (qtp499969544-21444) [n:127.0.0.1:34693_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=205
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1071093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1071169 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-1-001 of type NRT for shard2
[junit4] 2> 1071170 WARN (closeThreadPool-6170-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1071170 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1071170 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1071170 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 1071172 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1071172 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1071172 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1071172 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5259d9f5{/,null,AVAILABLE}
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d7b619e{SSL,[ssl, http/1.1]}{127.0.0.1:46103}
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.e.j.s.Server Started @1071204ms
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=46103, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-1-001/cores}
[junit4] 2> 1071173 ERROR (closeThreadPool-6170-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1071173 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T22:39:24.954Z
[junit4] 2> 1071174 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071177 INFO (zkConnectionManagerCallback-6172-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071177 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071245 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-2-001 of type NRT for shard1
[junit4] 2> 1071247 WARN (closeThreadPool-6170-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1071247 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1071247 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1071247 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 1071248 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1071248 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1071248 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1071249 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42ea5444{/,null,AVAILABLE}
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@683f41b5{SSL,[ssl, http/1.1]}{127.0.0.1:33977}
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.e.j.s.Server Started @1071281ms
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=33977, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-2-001/cores}
[junit4] 2> 1071250 ERROR (closeThreadPool-6170-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1071250 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T22:39:25.031Z
[junit4] 2> 1071251 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071252 INFO (zkConnectionManagerCallback-6174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071252 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071278 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1071278 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-1-001/solr.xml
[junit4] 2> 1071282 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1071282 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1071283 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1071314 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-3-001 of type NRT for shard2
[junit4] 2> 1071315 WARN (closeThreadPool-6170-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1071315 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1071315 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1071315 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 1071317 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1071317 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1071317 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1071317 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30f37853{/,null,AVAILABLE}
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@359a3099{SSL,[ssl, http/1.1]}{127.0.0.1:34299}
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.e.j.s.Server Started @1071353ms
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/, hostPort=34299, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-3-001/cores}
[junit4] 2> 1071322 ERROR (closeThreadPool-6170-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1071322 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T22:39:25.103Z
[junit4] 2> 1071324 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071324 INFO (zkConnectionManagerCallback-6177-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071326 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071378 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1071378 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1071378 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-2-001/solr.xml
[junit4] 2> 1071379 WARN (closeThreadPool-6170-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071379 WARN (closeThreadPool-6170-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@166cf05a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071379 WARN (closeThreadPool-6170-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@166cf05a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071381 WARN (closeThreadPool-6170-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071381 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1071381 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1071381 WARN (closeThreadPool-6170-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4b5480a1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071381 WARN (closeThreadPool-6170-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4b5480a1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071382 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1071383 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46409/solr
[junit4] 2> 1071384 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071385 INFO (zkConnectionManagerCallback-6184-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071385 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071385 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-4-001 of type NRT for shard1
[junit4] 2> 1071387 WARN (closeThreadPool-6170-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1071387 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1071387 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1071387 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 1071392 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1071392 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1071392 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1071393 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b781e10{/,null,AVAILABLE}
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7add7633{SSL,[ssl, http/1.1]}{127.0.0.1:37167}
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.e.j.s.Server Started @1071425ms
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=37167, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-4-001/cores}
[junit4] 2> 1071394 ERROR (closeThreadPool-6170-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.5.0
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1071394 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T22:39:25.175Z
[junit4] 2> 1071395 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071395 INFO (zkConnectionManagerCallback-6186-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071395 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071479 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1071479 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-3-001/solr.xml
[junit4] 2> 1071480 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1071480 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1071482 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1071489 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1071489 WARN (closeThreadPool-6170-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071490 WARN (closeThreadPool-6170-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6e442e4a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071490 WARN (closeThreadPool-6170-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6e442e4a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071491 WARN (closeThreadPool-6170-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071492 WARN (closeThreadPool-6170-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2353d972[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071492 WARN (closeThreadPool-6170-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2353d972[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071493 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46409/solr
[junit4] 2> 1071493 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071494 INFO (zkConnectionManagerCallback-6193-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071494 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071579 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1071580 WARN (closeThreadPool-6170-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071580 WARN (closeThreadPool-6170-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7d3e6aad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071580 WARN (closeThreadPool-6170-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7d3e6aad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071581 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1071581 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-4-001/solr.xml
[junit4] 2> 1071583 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1071583 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1071584 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1071589 WARN (closeThreadPool-6170-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071590 WARN (closeThreadPool-6170-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5c156c26[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071590 WARN (closeThreadPool-6170-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5c156c26[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071591 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46409/solr
[junit4] 2> 1071591 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071592 INFO (zkConnectionManagerCallback-6200-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071592 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071683 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071684 INFO (zkConnectionManagerCallback-6202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071684 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071686 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1071688 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.ZkController Publish node=127.0.0.1:46103_ as DOWN
[junit4] 2> 1071688 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1071688 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46103_
[junit4] 2> 1071689 INFO (zkCallback-6168-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1071689 INFO (zkCallback-6153-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1071689 INFO (zkCallback-6201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1071689 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1071690 WARN (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1071700 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1071713 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071719 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071720 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071720 INFO (closeThreadPool-6170-thread-1) [n:127.0.0.1:46103_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-1-001/cores
[junit4] 2> 1071749 INFO (closeThreadPool-6170-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:46103_
[junit4] 2> 1071756 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1071756 WARN (closeThreadPool-6170-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071759 WARN (closeThreadPool-6170-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7af14527[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071759 WARN (closeThreadPool-6170-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7af14527[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071760 WARN (closeThreadPool-6170-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1071761 WARN (closeThreadPool-6170-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3be06ad0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071761 WARN (closeThreadPool-6170-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3be06ad0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1071762 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46409/solr
[junit4] 2> 1071762 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071763 INFO (zkConnectionManagerCallback-6212-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071763 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071782 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071783 INFO (zkConnectionManagerCallback-6214-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071783 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071784 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1071786 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.ZkController Publish node=127.0.0.1:34299_ as DOWN
[junit4] 2> 1071786 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1071786 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34299_
[junit4] 2> 1071787 INFO (zkCallback-6168-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1071787 INFO (zkCallback-6201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1071787 INFO (zkCallback-6153-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1071787 INFO (zkCallback-6213-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1071788 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1071788 WARN (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1071800 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1071813 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071820 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071820 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071821 INFO (closeThreadPool-6170-thread-3) [n:127.0.0.1:34299_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-3-001/cores
[junit4] 2> 1071852 INFO (closeThreadPool-6170-thread-3) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:34299_
[junit4] 2> 1071889 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071889 INFO (zkConnectionManagerCallback-6220-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071889 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071899 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1071900 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.ZkController Publish node=127.0.0.1:33977_ as DOWN
[junit4] 2> 1071901 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1071901 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33977_
[junit4] 2> 1071902 INFO (zkCallback-6213-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1071902 INFO (zkCallback-6168-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1071902 INFO (zkCallback-6153-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1071902 INFO (zkCallback-6201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1071903 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1071903 WARN (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1071917 INFO (zkCallback-6219-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1071917 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1071930 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071935 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071935 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1071936 INFO (closeThreadPool-6170-thread-2) [n:127.0.0.1:33977_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-2-001/cores
[junit4] 2> 1071960 INFO (closeThreadPool-6170-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:33977_
[junit4] 2> 1071985 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1071985 INFO (zkConnectionManagerCallback-6226-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1071985 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1071987 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1071989 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.ZkController Publish node=127.0.0.1:37167_ as DOWN
[junit4] 2> 1071989 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1071989 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37167_
[junit4] 2> 1071990 INFO (zkCallback-6168-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1071990 INFO (zkCallback-6201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1071990 INFO (zkCallback-6213-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1071990 INFO (zkCallback-6153-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1071992 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1071992 WARN (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1071993 INFO (zkCallback-6219-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1071993 INFO (zkCallback-6225-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1072007 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1072020 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1072025 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1072025 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1072026 INFO (closeThreadPool-6170-thread-4) [n:127.0.0.1:37167_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-4-001/cores
[junit4] 2> 1072079 INFO (closeThreadPool-6170-thread-4) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:37167_
[junit4] 2> 1072106 INFO (qtp1678971444-21605) [n:127.0.0.1:33977_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:34299_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1072109 INFO (qtp1678971444-21603) [n:127.0.0.1:33977_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:46103_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1072110 INFO (qtp1678971444-21606) [n:127.0.0.1:33977_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:33977_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1072113 INFO (OverseerCollectionConfigSetProcessor-72062999077584901-127.0.0.1:34693_-n_0000000000) [n:127.0.0.1:34693_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1072113 INFO (OverseerThreadFactory-6368-thread-3-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:34299_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 1072113 INFO (OverseerThreadFactory-6368-thread-3-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1072114 INFO (OverseerThreadFactory-6368-thread-4-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:33977_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 1072116 INFO (qtp1678971444-21609) [n:127.0.0.1:33977_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:37167_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1072117 INFO (OverseerThreadFactory-6368-thread-4-processing-n:127.0.0.1:34693_) [n:127.0.0.1:34693_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1072143 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ x:collection1_shard2_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1072161 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ x:collection1_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n2&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1073151 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1073162 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=test
[junit4] 2> 1073168 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 1073196 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n2] Schema name=test
[junit4] 2> 1073315 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1073315 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1073321 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using configuration from configset conf1, trusted=true
[junit4] 2> 1073322 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n2' (registry 'solr.core.collection1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1073322 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1073322 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-2-001/cores/collection1_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-2-001/cores/collection1_shard1_replica_n2/data/]
[junit4] 2> 1073322 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n1' (registry 'solr.core.collection1.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2682b526
[junit4] 2> 1073322 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-3-001/cores/collection1_shard2_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001/shard-3-001/cores/collection1_shard2_replica_n1/data/]
[junit4] 2> 1073323 INFO (qtp1678971444-21620) [n:127.0.0.1:33977_ c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=447, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20310064860350752]
[junit4] 2> 1073324 INFO (qtp1053343038-21615) [n:127.0.0.1:34299_ c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=447, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, cal
[...truncated too long message...]
ic reporters for registry=solr.jetty, tag=null
[junit4] 2> 821651 INFO (closeThreadPool-127-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6cf22586: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@ced648e
[junit4] 2> 821651 INFO (closeThreadPool-127-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 821663 INFO (OverseerStateUpdate-72063096517623813-127.0.0.1:39371_-n_0000000000) [n:127.0.0.1:39371_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39371_
[junit4] 2> 821667 INFO (zkCallback-82-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 821668 INFO (OverseerAutoScalingTriggerThread-72063096517623813-127.0.0.1:39371_-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 821670 INFO (closeThreadPool-144-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72063096517623813-127.0.0.1:39371_-n_0000000000) closing
[junit4] 2> 821745 INFO (closeThreadPool-127-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@28092baa{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 821747 INFO (closeThreadPool-127-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c974982{/,null,UNAVAILABLE}
[junit4] 2> 821747 INFO (closeThreadPool-127-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 821843 INFO (closeThreadPool-127-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@12d53b6b{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 821844 INFO (closeThreadPool-127-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1c2718f6{/,null,UNAVAILABLE}
[junit4] 2> 821844 INFO (closeThreadPool-127-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 821941 INFO (closeThreadPool-127-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72063096517623813-127.0.0.1:39371_-n_0000000000) closing
[junit4] 2> 821943 INFO (closeThreadPool-127-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@533ed89c{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 821943 INFO (closeThreadPool-127-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6db95a12{/,null,UNAVAILABLE}
[junit4] 2> 821943 INFO (closeThreadPool-127-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 822043 INFO (closeThreadPool-127-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5d276c38{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 822043 INFO (closeThreadPool-127-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7a009cdf{/,null,UNAVAILABLE}
[junit4] 2> 822044 INFO (closeThreadPool-127-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 822144 INFO (closeThreadPool-127-thread-5) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@ce6dea3{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 822144 INFO (closeThreadPool-127-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@678040f6{/,null,UNAVAILABLE}
[junit4] 2> 822144 INFO (closeThreadPool-127-thread-5) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 822147 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 822350 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> 17 /solr/collections/collection1/state.json
[junit4] 2> 17 /solr/configs/conf1/managed-schema
[junit4] 2> 15 /solr/configs/conf1
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/collections/collection1/terms/shard1
[junit4] 2> 5 /solr/collections/collection1/terms/shard2
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 62 /solr/clusterstate.json
[junit4] 2> 11 /solr/configs/conf1/managed-schema
[junit4] 2> 9 /solr/collections/control_collection/state.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/72063096517623813-127.0.0.1:39371_-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 36 /solr/overseer/queue
[junit4] 2> 31 /solr/live_nodes
[junit4] 2> 16 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/collections
[junit4] 2>
[junit4] 2> 822376 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43229
[junit4] 2> 822376 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43229
[junit4] 2> 822376 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[AAA29BB9346E49F4]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43229
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBulkSchemaConcurrent -Dtests.method=test -Dtests.seed=AAA29BB9346E49F4 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=de -Dtests.timezone=Australia/Victoria -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 820s J2 | TestBulkSchemaConcurrent.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: [[], [], [CopyField source=a4,dest=hello_lol4 not created, dynamic field *_lol4 not created, field a4 not created, new type mystr4 not created, dynamic field *_lol4 no longer present, field a4 no longer present, CopyField source=a4,dest=hello_lol4 no longer present, new type mystr4 no longer present], [], []]
[junit4] > at __randomizedtesting.SeedInfo.seed([AAA29BB9346E49F4:22F6A4639A92240C]:0)
[junit4] > at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:94)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_AAA29BB9346E49F4-001
[junit4] 2> Jan 23, 2020 11:17:48 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1864, maxMBSortInHeap=5.011075076119595, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@29424721), locale=de, timezone=Australia/Victoria
[junit4] 2> NOTE: Linux 5.3.0-26-generic amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=16,threads=2,free=309363008,total=535822336
[junit4] 2> NOTE: All tests run in this JVM: [TestBulkSchemaConcurrent]
[junit4] Completed [5/5 (2!)] on J2 in 822.28s, 1 test, 1 failure <<< FAILURES!
[...truncated 14 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1590: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1117: There were test failures: 5 suites, 5 tests, 2 failures [seed: AAA29BB9346E49F4]
Total time: 13 minutes 59 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 2/5 failed: org.apache.solr.schema.TestBulkSchemaConcurrent
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=8a8743159ecc32bede96672cb9a07ce8d64af8f3, workspace=/home/jenkins/workspace/Lucene-Solr-8.x-Linux
[WARNINGS] Computing warning deltas based on reference build #1882
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2