You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/08/12 18:52:31 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-10.0.1) - Build #
22656 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22656/
Java: 64bit/jdk-10.0.1 -XX:-UseCompressedOops -XX:+UseG1GC
3 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testDeleteWithCollection
Error Message:
Error from server at http://127.0.0.1:35999/solr: Could not find collection : testDeleteWithCollection_abc_2
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35999/solr: Could not find collection : testDeleteWithCollection_abc_2
at __randomizedtesting.SeedInfo.seed([9871DA426F18FDD8:E3A82DAF36822F66]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.cloud.TestWithCollection.testDeleteWithCollection(TestWithCollection.java:224)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:844)
FAILED: org.apache.solr.cloud.TestWithCollection.testMoveReplicaWithCollection
Error Message:
Expected moving a replica of 'withCollection': testMoveReplicaWithCollection_abc to fail
Stack Trace:
java.lang.AssertionError: Expected moving a replica of 'withCollection': testMoveReplicaWithCollection_abc to fail
at __randomizedtesting.SeedInfo.seed([9871DA426F18FDD8:D766E3E1FC6A34AE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.TestWithCollection.testMoveReplicaWithCollection(TestWithCollection.java:389)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:844)
FAILED: org.apache.solr.cloud.TestWithCollection.testMoveReplicaWithCollection
Error Message:
Expected moving a replica of 'withCollection': testMoveReplicaWithCollection_abc to fail
Stack Trace:
java.lang.AssertionError: Expected moving a replica of 'withCollection': testMoveReplicaWithCollection_abc to fail
at __randomizedtesting.SeedInfo.seed([9871DA426F18FDD8:D766E3E1FC6A34AE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.TestWithCollection.testMoveReplicaWithCollection(TestWithCollection.java:389)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:844)
Build Log:
[...truncated 12946 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/init-core-data-001
[junit4] 2> 348394 WARN (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 348394 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 348395 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-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> 348395 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 348395 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001
[junit4] 2> 348395 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 348395 INFO (Thread-1370) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 348395 INFO (Thread-1370) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 348397 ERROR (Thread-1370) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 348495 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43773
[junit4] 2> 348498 INFO (zkConnectionManagerCallback-1481-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348500 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 348500 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@153256c9{/solr,null,AVAILABLE}
[junit4] 2> 348501 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19c659b{/solr,null,AVAILABLE}
[junit4] 2> 348502 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@76a840b9{HTTP/1.1,[http/1.1]}{127.0.0.1:40529}
[junit4] 2> 348502 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7daa2634{HTTP/1.1,[http/1.1]}{127.0.0.1:44445}
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.e.j.s.Server Started @348532ms
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.e.j.s.Server Started @348532ms
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40529}
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44445}
[junit4] 2> 348503 ERROR (jetty-launcher-1478-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 348503 ERROR (jetty-launcher-1478-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-08-12T18:05:02.068408Z
[junit4] 2> 348503 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-08-12T18:05:02.068420Z
[junit4] 2> 348512 INFO (zkConnectionManagerCallback-1485-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348512 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348515 INFO (zkConnectionManagerCallback-1484-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348515 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 348601 INFO (jetty-launcher-1478-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43773/solr
[junit4] 2> 348602 INFO (zkConnectionManagerCallback-1489-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348603 INFO (zkConnectionManagerCallback-1491-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348637 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44445_solr
[junit4] 2> 348637 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.c.Overseer Overseer (id=72149304253087748-127.0.0.1:44445_solr-n_0000000000) starting
[junit4] 2> 348641 INFO (zkConnectionManagerCallback-1498-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348642 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43773/solr ready
[junit4] 2> 348642 INFO (OverseerStateUpdate-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44445_solr
[junit4] 2> 348643 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44445_solr
[junit4] 2> 348643 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 348647 INFO (zkCallback-1497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 348647 INFO (zkCallback-1490-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 348647 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 348648 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 348648 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 348649 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:44445_solr]
[junit4] 2> 348650 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 348650 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 348650 DEBUG (ScheduledTrigger-2464-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 348654 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 348665 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44445.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 348670 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44445.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 348670 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44445.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 348670 INFO (jetty-launcher-1478-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43773/solr
[junit4] 2> 348671 INFO (jetty-launcher-1478-thread-1) [n:127.0.0.1:44445_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/.
[junit4] 2> 348671 INFO (zkConnectionManagerCallback-1503-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348673 INFO (zkConnectionManagerCallback-1505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348676 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 348678 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 348678 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40529_solr
[junit4] 2> 348678 INFO (zkCallback-1490-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 348678 INFO (zkCallback-1504-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 348679 INFO (zkCallback-1497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 348687 INFO (zkConnectionManagerCallback-1512-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348688 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 348688 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43773/solr ready
[junit4] 2> 348693 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 348708 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40529.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 348717 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40529.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 348717 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40529.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 348718 INFO (jetty-launcher-1478-thread-2) [n:127.0.0.1:40529_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node2/.
[junit4] 2> 348759 INFO (zkConnectionManagerCallback-1515-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348762 INFO (zkConnectionManagerCallback-1520-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348763 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 348764 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43773/solr ready
[junit4] 2> 348782 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[9871DA426F18FDD8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteWithCollection
[junit4] 2> 348785 DEBUG (zkCallback-1497-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 348785 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 348785 INFO (TEST-TestWithCollection.testDeleteWithCollection-seed#[9871DA426F18FDD8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 348786 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 348787 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 348787 DEBUG (OverseerAutoScalingTriggerThread-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 348792 INFO (qtp2094702188-5669) [n:127.0.0.1:44445_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=testDeleteWithCollection_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 348795 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection testDeleteWithCollection_abc
[junit4] 2> 348796 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/protwords.txt
[junit4] 2> 348797 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testDeleteWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 348798 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testDeleteWithCollection_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 348799 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 348799 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 348800 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 348801 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 348802 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 348802 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 348803 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 348804 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 348805 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 348805 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 348806 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 348806 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 348807 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 348808 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 348808 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 348809 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 348810 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 348810 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 348811 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 348812 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 348812 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 348813 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 348814 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 348814 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 348815 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 348815 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 348816 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 348817 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 348817 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 348818 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 348818 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 348819 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 348820 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 348820 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 348821 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 348821 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 348822 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 348823 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 348823 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 348824 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testDeleteWithCollection_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 348825 INFO (OverseerThreadFactory-2466-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testDeleteWithCollection_abc.AUTOCREATED/params.json
[junit4] 2> 348932 INFO (OverseerStateUpdate-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testDeleteWithCollection_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testDeleteWithCollection_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44445/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 349134 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDeleteWithCollection_abc.AUTOCREATED&newCollection=true&name=testDeleteWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testDeleteWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 349135 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 350145 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 350145 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 350146 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 350146 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 350146 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 350146 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 350146 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 350146 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 350147 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 350147 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 350147 WARN (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 350152 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 350167 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.IndexSchema [testDeleteWithCollection_abc_shard1_replica_n1] Schema name=default-config
[junit4] 2> 350286 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 350301 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testDeleteWithCollection_abc_shard1_replica_n1' using configuration from collection testDeleteWithCollection_abc, trusted=true
[junit4] 2> 350301 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44445.solr.core.testDeleteWithCollection_abc.shard1.replica_n1' (registry 'solr.core.testDeleteWithCollection_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 350301 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 350301 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrCore [[testDeleteWithCollection_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_abc_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/./testDeleteWithCollection_abc_shard1_replica_n1/data/]
[junit4] 2> 350305 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 350412 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 350412 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 350421 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 350421 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 350423 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bc404d1[testDeleteWithCollection_abc_shard1_replica_n1] main]
[junit4] 2> 350425 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testDeleteWithCollection_abc.AUTOCREATED
[junit4] 2> 350425 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testDeleteWithCollection_abc.AUTOCREATED
[junit4] 2> 350425 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testDeleteWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 350426 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 350427 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 350427 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 350435 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 350435 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608617404923904000
[junit4] 2> 350437 INFO (searcherExecutor-2478-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@bc404d1[testDeleteWithCollection_abc_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 350437 INFO (searcherExecutor-2478-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 350437 INFO (searcherExecutor-2478-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 350438 INFO (searcherExecutor-2478-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_abc_shard1_replica_n1 c:testDeleteWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrCore [testDeleteWithCollection_abc_shard1_replica_n1] Registered new searcher Searcher@bc404d1[testDeleteWithCollection_abc_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 350495 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testDeleteWithCollection_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 350497 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 350498 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 350498 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44445/solr/testDeleteWithCollection_abc_shard1_replica_n1/
[junit4] 2> 350498 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 350498 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:44445/solr/testDeleteWithCollection_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 350498 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 350502 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:44445/solr/testDeleteWithCollection_abc_shard1_replica_n1/ shard1
[junit4] 2> 350603 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 350605 INFO (qtp2094702188-5659) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_abc s:shard1 r:core_node2 x:testDeleteWithCollection_abc_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDeleteWithCollection_abc.AUTOCREATED&newCollection=true&name=testDeleteWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testDeleteWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1470
[junit4] 2> 350606 INFO (qtp2094702188-5669) [n:127.0.0.1:44445_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 350705 INFO (zkCallback-1490-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testDeleteWithCollection_abc/state.json] for collection [testDeleteWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 350797 INFO (OverseerCollectionConfigSetProcessor-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 351606 INFO (qtp2094702188-5669) [n:127.0.0.1:44445_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testDeleteWithCollection_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=2813
[junit4] 2> 351607 INFO (qtp2094702188-5657) [n:127.0.0.1:44445_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&withCollection=testDeleteWithCollection_abc&name=testDeleteWithCollection_xyz&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 351608 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection testDeleteWithCollection_xyz
[junit4] 2> 351609 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 351610 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testDeleteWithCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2> 351610 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testDeleteWithCollection_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 351611 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/synonyms.txt
[junit4] 2> 351612 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/stopwords.txt
[junit4] 2> 351613 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 351614 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 351615 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 351615 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 351616 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 351617 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 351617 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 351618 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 351619 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 351619 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 351620 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 351620 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 351621 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 351622 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 351622 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 351623 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 351623 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 351624 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 351625 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 351625 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 351626 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 351626 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 351627 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 351628 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 351628 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 351629 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 351629 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 351630 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 351631 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 351631 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 351632 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 351632 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 351633 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 351633 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 351634 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 351634 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 351635 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 351635 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testDeleteWithCollection_xyz.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 351636 INFO (OverseerThreadFactory-2466-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testDeleteWithCollection_xyz.AUTOCREATED/params.json
[junit4] 2> 351841 INFO (OverseerStateUpdate-72149304253087748-127.0.0.1:44445_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testDeleteWithCollection_xyz",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testDeleteWithCollection_xyz_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44445/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 352043 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testDeleteWithCollection_xyz.AUTOCREATED&newCollection=true&name=testDeleteWithCollection_xyz_shard1_replica_n1&action=CREATE&numShards=1&collection=testDeleteWithCollection_xyz&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 353053 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 353053 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 353054 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 353055 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 353055 WARN (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 353058 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 353071 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.s.IndexSchema [testDeleteWithCollection_xyz_shard1_replica_n1] Schema name=default-config
[junit4] 2> 353165 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 353179 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testDeleteWithCollection_xyz_shard1_replica_n1' using configuration from collection testDeleteWithCollection_xyz, trusted=true
[junit4] 2> 353179 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44445.solr.core.testDeleteWithCollection_xyz.shard1.replica_n1' (registry 'solr.core.testDeleteWithCollection_xyz.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e046dd7
[junit4] 2> 353179 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 353179 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrCore [[testDeleteWithCollection_xyz_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/testDeleteWithCollection_xyz_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001/tempDir-001/node1/./testDeleteWithCollection_xyz_shard1_replica_n1/data/]
[junit4] 2> 353181 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 353214 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 353215 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 353216 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 353216 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 353217 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@53f5cc16[testDeleteWithCollection_xyz_shard1_replica_n1] main]
[junit4] 2> 353218 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testDeleteWithCollection_xyz.AUTOCREATED
[junit4] 2> 353218 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testDeleteWithCollection_xyz.AUTOCREATED
[junit4] 2> 353218 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testDeleteWithCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2> 353218 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 353219 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 353219 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 353220 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 353220 INFO (searcherExecutor-2483-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_xyz_shard1_replica_n1 c:testDeleteWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@53f5cc16[testDeleteWithCollection_xyz_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 353220 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608617407844188160
[junit4] 2> 353220 INFO (searcherExecutor-2483-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_xyz_shard1_replica_n1 c:testDeleteWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 353220 INFO (searcherExecutor-2483-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_xyz_shard1_replica_n1 c:testDeleteWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 353220 INFO (searcherExecutor-2483-thread-1-processing-n:127.0.0.1:44445_solr x:testDeleteWithCollection_xyz_shard1_replica_n1 c:testDeleteWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrCore [testDeleteWithCollection_xyz_shard1_replica_n1] Registered new searcher Searcher@53f5cc16[testDeleteWithCollection_xyz_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 353223 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testDeleteWithCollection_xyz/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 353224 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 353225 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 353225 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44445/solr/testDeleteWithCollection_xyz_shard1_replica_n1/
[junit4] 2> 353225 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 353225 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:44445/solr/testDeleteWithCollection_xyz_shard1_replica_n1/ has no replicas
[junit4] 2> 353225 INFO (qtp2094702188-5667) [n:127.0.0.1:44445_solr c:testDeleteWithCollection_xyz s:shard1 r:core_node2 x:testDeleteWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2>
[...truncated too long message...]
] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 226293 DEBUG (jetty-closer-129-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 226293 DEBUG (jetty-closer-129-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 226295 INFO (jetty-closer-129-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3b33e1ac{/solr,null,UNAVAILABLE}
[junit4] 2> 226295 INFO (jetty-closer-129-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 226296 ERROR (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 226296 INFO (SUITE-TestWithCollection-seed#[9871DA426F18FDD8]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35097 35097
[junit4] 2> 227345 INFO (Thread-58) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35097 35097
[junit4] 2> 227345 WARN (Thread-58) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/collections/testNodeAdded_xyz/terms/shard1
[junit4] 2> 25 /solr/collections/testNodeAdded_abc/terms/shard1
[junit4] 2> 20 /solr/aliases.json
[junit4] 2> 20 /solr/clusterprops.json
[junit4] 2> 12 /solr/collections/testMultipleWithCollections_abc2/terms/shard1
[junit4] 2> 12 /solr/collections/testMoveReplicaMainCollection_abc/terms/shard1
[junit4] 2> 12 /solr/collections/testAddReplicaWithPolicy_abc/terms/shard1
[junit4] 2> 12 /solr/collections/testMoveReplicaWithCollection_abc/terms/shard1
[junit4] 2> 12 /solr/collections/testMoveReplicaMainCollection_xyz/terms/shard1
[junit4] 2> 12 /solr/collections/testMultipleWithCollections_xyz/terms/shard1
[junit4] 2> 11 /solr/collections/testDeleteWithCollection_abc_2/terms/shard1
[junit4] 2> 10 /solr/collections/testMultipleWithCollections_xyz2/terms/shard1
[junit4] 2> 10 /solr/collections/testMultipleWithCollections_abc/terms/shard1
[junit4] 2> 10 /solr/collections/testAddReplicaWithPolicy_xyz/terms/shard1
[junit4] 2> 9 /solr/collections/testAddReplica_abc/terms/shard1
[junit4] 2> 7 /solr/collections/testAddReplica_xyz/terms/shard1
[junit4] 2> 5 /solr/security.json
[junit4] 2> 3 /solr/configs/testNodeAdded_xyz.AUTOCREATED
[junit4] 2> 3 /solr/collections/testCreateCollection_abc/terms/shard1
[junit4] 2> 3 /solr/configs/testNodeAdded_abc.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/collections/testCreateCollectionNoWithCollection_abc/terms/shard2
[junit4] 2> 3 /solr/collections/testCreateCollectionNoWithCollection_abc/terms/shard1
[junit4] 2> 3 /solr/configs/testNodeAdded_xyz.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/collections/testCreateCollection_xyz/terms/shard1
[junit4] 2> 3 /solr/configs/testNodeAdded_abc.AUTOCREATED
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_xyz/terms/shard1
[junit4] 2> 3 /solr/collections/testMoveReplicaWithCollection_xyz/terms/shard1
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_abc/terms/shard1
[junit4] 2> 3 /solr/collections/testDeleteWithCollection_xyz_2/terms/shard1
[junit4] 2> 2 /solr/configs/testDeleteWithCollection_abc_2.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplica_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz2.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMoveReplicaWithCollection_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplica_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc2.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc2.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMoveReplicaWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplica_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testDeleteWithCollection_abc_2.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplica_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testCreateCollectionNoWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz2.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testMultipleWithCollections_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testMoveReplicaMainCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 45 /solr/aliases.json
[junit4] 2> 20 /solr/clusterstate.json
[junit4] 2> 6 /solr/collections/testDeleteWithCollection_abc_2/state.json
[junit4] 2> 6 /solr/collections/testNodeAdded_xyz/state.json
[junit4] 2> 6 /solr/collections/testMoveReplicaWithCollection_abc/state.json
[junit4] 2> 6 /solr/collections/testMoveReplicaMainCollection_xyz/state.json
[junit4] 2> 6 /solr/collections/testMultipleWithCollections_abc2/state.json
[junit4] 2> 6 /solr/collections/testNodeAdded_abc/state.json
[junit4] 2> 5 /solr/collections/testMultipleWithCollections_xyz2/state.json
[junit4] 2> 5 /solr/collections/testMultipleWithCollections_abc/state.json
[junit4] 2> 5 /solr/collections/testMultipleWithCollections_xyz/state.json
[junit4] 2> 4 /solr/collections/testAddReplica_xyz/state.json
[junit4] 2> 3 /solr/collections/testAddReplicaWithPolicy_xyz/state.json
[junit4] 2> 2 /solr/collections/testMoveReplicaMainCollection_abc/state.json
[junit4] 2> 2 /solr/collections/testAddReplica_abc/state.json
[junit4] 2> 2 /solr/collections/testCreateCollectionNoWithCollection_abc/state.json
[junit4] 2> 2 /solr/collections/testAddReplicaWithPolicy_abc/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72149462951067655-127.0.0.1:33101_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 198 /solr/collections
[junit4] 2> 57 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestWithCollection_9871DA426F18FDD8-001
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2f1fa038), locale=so-DJ, timezone=Africa/Ndjamena
[junit4] 2> NOTE: Linux 4.15.0-29-generic amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=8,threads=1,free=150980000,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestWithCollection, TestWithCollection]
[junit4] Completed [5/5 (2!)] on J1 in 109.33s, 9 tests, 1 failure <<< FAILURES!
[...truncated 12 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 45 tests, 1 error, 1 failure [seed: 9871DA426F18FDD8]
Total time: 3 minutes 49 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.cloud.TestWithCollection
[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=/var/lib/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=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/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=17eb8cd14d27d2680fe7c4b3871f3eb883542d34, workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #22655
Recording test results
Setting ANT_1_8_2_HOME=/var/lib/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=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-9.0.4) - Build #
22657 - Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22657/
Java: 64bit/jdk-9.0.4 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest
Error Message:
Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.update.MaxSizeAutoCommitTest_3C66F996AC542D1-001/init-core-data-001/tlog/tlog.0000000000000000002, tlog size: 1265
Stack Trace:
java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.update.MaxSizeAutoCommitTest_3C66F996AC542D1-001/init-core-data-001/tlog/tlog.0000000000000000002, tlog size: 1265
at __randomizedtesting.SeedInfo.seed([3C66F996AC542D1:13888A66116B7B20]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:382)
at org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:202)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:844)
Build Log:
[...truncated 15198 lines...]
[junit4] JVM J0: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20180812_205800_92311964180906072912054.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # SIGSEGV (0xb) at pc=0x00007fbf60da4dc9, pid=6925, tid=7034
[junit4] #
[junit4] # JRE version: Java(TM) SE Runtime Environment (9.0+11) (build 9.0.4+11)
[junit4] # Java VM: Java HotSpot(TM) 64-Bit Server VM (9.0.4+11, mixed mode, tiered, g1 gc, linux-amd64)
[junit4] # Problematic frame:
[junit4] # V [libjvm.so+0xc5adc9] PhaseIdealLoop::split_up(Node*, Node*, Node*) [clone .part.40]+0x619
[junit4] #
[junit4] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/hs_err_pid6925.log
[junit4] [thread 25922 also had an error]
[junit4] # [ timer expired, abort... ]
[junit4] <<< JVM J0: EOF ----
[junit4] Suite: org.apache.solr.update.MaxSizeAutoCommitTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.update.MaxSizeAutoCommitTest_3C66F996AC542D1-001/init-core-data-001
[junit4] 2> 2143746 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2143763 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2143774 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2143864 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2143992 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2143992 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2144002 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2144007 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2144007 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2144009 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2144025 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2144035 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2144121 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2144125 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2144125 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2144126 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2144126 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.update.MaxSizeAutoCommitTest_3C66F996AC542D1-001/init-core-data-001/]
[junit4] 2> 2144126 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2144127 WARN (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2144148 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2144148 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2144177 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2144177 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2144178 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=73.3173828125, floorSegmentMB=1.6064453125, forceMergeDeletesPctAllowed=5.340000614952993, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6969336812489716, deletesPctAllowed=32.50513481288843
[junit4] 2> 2144178 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@675f8e09[collection1] main]
[junit4] 2> 2144179 WARN (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2144179 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2144179 INFO (coreLoadExecutor-10292-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608630534317015040
[junit4] 2> 2144180 INFO (searcherExecutor-10293-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@675f8e09[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2144186 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608630534321209344), 1 (1608630534322257920), 2 (1608630534322257921), 3 (1608630534322257922), 4 (1608630534322257923), 5 (1608630534322257924), 6 (1608630534323306496), 7 (1608630534323306497), 8 (1608630534323306498), 9 (1608630534323306499), ... (20 adds)]} 0 3
[junit4] 2> 2144408 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=225
[junit4] 2> 2144434 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1608630534583353344), 21 (1608630534583353345), 22 (1608630534583353346), 23 (1608630534583353347), 24 (1608630534583353348), 25 (1608630534583353349), 26 (1608630534583353350), 27 (1608630534583353351), 28 (1608630534583353352), 29 (1608630534583353353), ... (20 adds)]} 0 1
[junit4] 2> 2144634 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=452
[junit4] 2> 2144637 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1608630534795165696), 41 (1608630534795165697), 42 (1608630534795165698), 43 (1608630534795165699), 44 (1608630534795165700), 45 (1608630534795165701), 46 (1608630534795165702), 47 (1608630534796214272), 48 (1608630534796214273), 49 (1608630534796214274), ... (20 adds)]} 0 2
[junit4] 2> 2144838 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=655
[junit4] 2> 2144839 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1608630535008026624), 61 (1608630535009075200), 62 (1608630535009075201), 63 (1608630535009075202), 64 (1608630535009075203), 65 (1608630535009075204), 66 (1608630535009075205), 67 (1608630535009075206), 68 (1608630535009075207), 69 (1608630535009075208), ... (20 adds)]} 0 1
[junit4] 2> 2145040 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=857
[junit4] 2> 2145041 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1608630535219838976), 81 (1608630535220887552), 82 (1608630535220887553), 83 (1608630535220887554), 84 (1608630535220887555), 85 (1608630535220887556), 86 (1608630535220887557), 87 (1608630535220887558), 88 (1608630535220887559), 89 (1608630535220887560), ... (20 adds)]} 0 1
[junit4] 2> 2145242 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1059
[junit4] 2> 2145243 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1608630535431651328), 101 (1608630535432699904), 102 (1608630535432699905), 103 (1608630535432699906), 104 (1608630535432699907), 105 (1608630535432699908), 106 (1608630535432699909), 107 (1608630535432699910), 108 (1608630535432699911), 109 (1608630535432699912), ... (20 adds)]} 0 1
[junit4] 2> 2145444 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1261
[junit4] 2> 2145446 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2145446 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1608630535643463680), 121 (1608630535643463681), 122 (1608630535643463682), 123 (1608630535643463683), 124 (1608630535643463684), 125 (1608630535643463685), 126 (1608630535643463686), 127 (1608630535644512256), 128 (1608630535644512257), 129 (1608630535644512258), ... (20 adds)]} 0 2
[junit4] 2> 2145446 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2145455 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3fefe207[collection1] realtime]
[junit4] 2> 2145455 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2145496 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1314
[junit4] 2> 2145498 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1608630535699038208), 141 (1608630535699038209), 142 (1608630535700086784), 143 (1608630535700086785), 144 (1608630535700086786), 145 (1608630535700086787), 146 (1608630535700086788), 147 (1608630535700086789), 148 (1608630535700086790), 149 (1608630535700086791), ... (20 adds)]} 0 1
[junit4] 2> 2145699 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1516
[junit4] 2> 2145700 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1608630535910850560), 161 (1608630535910850561), 162 (1608630535910850562), 163 (1608630535910850563), 164 (1608630535910850564), 165 (1608630535910850565), 166 (1608630535910850566), 167 (1608630535910850567), 168 (1608630535910850568), 169 (1608630535910850569), ... (20 adds)]} 0 0
[junit4] 2> 2145900 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1717
[junit4] 2> 2145901 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1608630536122662912), 181 (1608630536122662913), 182 (1608630536122662914), 183 (1608630536122662915), 184 (1608630536122662916), 185 (1608630536122662917), 186 (1608630536122662918), 187 (1608630536122662919), 188 (1608630536122662920), 189 (1608630536122662921), ... (20 adds)]} 0 0
[junit4] 2> 2146102 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1919
[junit4] 2> 2146103 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 (1608630536333426688), 201 (1608630536333426689), 202 (1608630536333426690), 203 (1608630536333426691), 204 (1608630536333426692), 205 (1608630536333426693), 206 (1608630536333426694), 207 (1608630536333426695), 208 (1608630536333426696), 209 (1608630536333426697), ... (20 adds)]} 0 0
[junit4] 2> 2146303 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2121
[junit4] 2> 2146304 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 (1608630536545239040), 221 (1608630536545239041), 222 (1608630536545239042), 223 (1608630536545239043), 224 (1608630536545239044), 225 (1608630536545239045), 226 (1608630536545239046), 227 (1608630536545239047), 228 (1608630536545239048), 229 (1608630536545239049), ... (20 adds)]} 0 0
[junit4] 2> 2146505 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2322
[junit4] 2> 2146506 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 (1608630536756002816), 241 (1608630536756002817), 242 (1608630536756002818), 243 (1608630536756002819), 244 (1608630536756002820), 245 (1608630536756002821), 246 (1608630536756002822), 247 (1608630536756002823), 248 (1608630536757051392), 249 (1608630536757051393), ... (20 adds)]} 0 0
[junit4] 2> 2146707 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2524
[junit4] 2> 2146708 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 (1608630536967815168), 261 (1608630536967815169), 262 (1608630536967815170), 263 (1608630536967815171), 264 (1608630536967815172), 265 (1608630536967815173), 266 (1608630536967815174), 267 (1608630536967815175), 268 (1608630536967815176), 269 (1608630536967815177), ... (20 adds)]} 0 0
[junit4] 2> 2146709 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2146709 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2146711 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@663c42c[collection1] realtime]
[junit4] 2> 2146711 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2146758 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2575
[junit4] 2> 2146760 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 (1608630537021292544), 281 (1608630537022341120), 282 (1608630537022341121), 283 (1608630537022341122), 284 (1608630537022341123), 285 (1608630537022341124), 286 (1608630537022341125), 287 (1608630537022341126), 288 (1608630537022341127), 289 (1608630537022341128), ... (20 adds)]} 0 1
[junit4] 2> 2146960 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2778
[junit4] 2> 2146961 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 (1608630537234153472), 301 (1608630537234153473), 302 (1608630537234153474), 303 (1608630537234153475), 304 (1608630537234153476), 305 (1608630537234153477), 306 (1608630537234153478), 307 (1608630537234153479), 308 (1608630537234153480), 309 (1608630537234153481), ... (20 adds)]} 0 0
[junit4] 2> 2147162 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2979
[junit4] 2> 2147163 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 (1608630537444917248), 321 (1608630537444917249), 322 (1608630537444917250), 323 (1608630537444917251), 324 (1608630537444917252), 325 (1608630537444917253), 326 (1608630537444917254), 327 (1608630537444917255), 328 (1608630537444917256), 329 (1608630537444917257), ... (20 adds)]} 0 0
[junit4] 2> 2147363 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3180
[junit4] 2> 2147364 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 (1608630537656729600), 341 (1608630537656729601), 342 (1608630537656729602), 343 (1608630537656729603), 344 (1608630537656729604), 345 (1608630537656729605), 346 (1608630537656729606), 347 (1608630537656729607), 348 (1608630537656729608), 349 (1608630537656729609), ... (20 adds)]} 0 0
[junit4] 2> 2147565 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3382
[junit4] 2> 2147566 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 (1608630537867493376), 361 (1608630537867493377), 362 (1608630537867493378), 363 (1608630537867493379), 364 (1608630537867493380), 365 (1608630537867493381), 366 (1608630537867493382), 367 (1608630537867493383), 368 (1608630537867493384), 369 (1608630537867493385), ... (20 adds)]} 0 0
[junit4] 2> 2147766 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3584
[junit4] 2> 2147767 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 (1608630538079305728), 381 (1608630538079305729), 382 (1608630538079305730), 383 (1608630538079305731), 384 (1608630538079305732), 385 (1608630538079305733), 386 (1608630538079305734), 387 (1608630538079305735), 388 (1608630538079305736), 389 (1608630538079305737), ... (20 adds)]} 0 0
[junit4] 2> 2147968 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3785
[junit4] 2> 2147969 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 (1608630538290069504), 401 (1608630538291118080), 402 (1608630538291118081), 403 (1608630538291118082), 404 (1608630538291118083), 405 (1608630538291118084), 406 (1608630538291118085), 407 (1608630538291118086), 408 (1608630538291118087), 409 (1608630538291118088), ... (20 adds)]} 0 1
[junit4] 2> 2147970 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2147970 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2147973 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f95e19e[collection1] realtime]
[junit4] 2> 2147973 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2148020 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3837
[junit4] 2> 2148021 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 (1608630538344595456), 421 (1608630538345644032), 422 (1608630538345644033), 423 (1608630538345644034), 424 (1608630538345644035), 425 (1608630538345644036), 426 (1608630538345644037), 427 (1608630538345644038), 428 (1608630538345644039), 429 (1608630538345644040), ... (20 adds)]} 0 1
[junit4] 2> 2148222 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4039
[junit4] 2> 2148223 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 (1608630538556407808), 441 (1608630538556407809), 442 (1608630538556407810), 443 (1608630538556407811), 444 (1608630538557456384), 445 (1608630538557456385), 446 (1608630538557456386), 447 (1608630538557456387), 448 (1608630538557456388), 449 (1608630538557456389), ... (20 adds)]} 0 0
[junit4] 2> 2148424 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4241
[junit4] 2> 2148424 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 (1608630538768220160), 461 (1608630538768220161), 462 (1608630538768220162), 463 (1608630538768220163), 464 (1608630538768220164), 465 (1608630538768220165), 466 (1608630538768220166), 467 (1608630538768220167), 468 (1608630538768220168), 469 (1608630538768220169), ... (20 adds)]} 0 0
[junit4] 2> 2148625 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4442
[junit4] 2> 2148626 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 (1608630538978983936), 481 (1608630538978983937), 482 (1608630538978983938), 483 (1608630538978983939), 484 (1608630538980032512), 485 (1608630538980032513), 486 (1608630538980032514), 487 (1608630538980032515), 488 (1608630538980032516), 489 (1608630538980032517), ... (20 adds)]} 0 0
[junit4] 2> 2148827 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4644
[junit4] 2> 2148828 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1608630539190796288), 501 (1608630539190796289), 502 (1608630539190796290), 503 (1608630539190796291), 504 (1608630539190796292), 505 (1608630539190796293), 506 (1608630539190796294), 507 (1608630539190796295), 508 (1608630539190796296), 509 (1608630539190796297), ... (20 adds)]} 0 0
[junit4] 2> 2149028 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4845
[junit4] 2> 2149029 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 (1608630539401560064), 521 (1608630539401560065), 522 (1608630539402608640), 523 (1608630539402608641), 524 (1608630539402608642), 525 (1608630539402608643), 526 (1608630539402608644), 527 (1608630539402608645), 528 (1608630539402608646), 529 (1608630539402608647), ... (20 adds)]} 0 0
[junit4] 2> 2149229 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5047
[junit4] 2> 2149230 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 (1608630539613372416), 541 (1608630539613372417), 542 (1608630539613372418), 543 (1608630539613372419), 544 (1608630539613372420), 545 (1608630539613372421), 546 (1608630539613372422), 547 (1608630539613372423), 548 (1608630539613372424), 549 (1608630539613372425), ... (20 adds)]} 0 0
[junit4] 2> 2149231 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2149231 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2149234 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1986c79f[collection1] realtime]
[junit4] 2> 2149234 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2149281 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5098
[junit4] 2> 2149282 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 (1608630539666849792), 561 (1608630539666849793), 562 (1608630539666849794), 563 (1608630539666849795), 564 (1608630539666849796), 565 (1608630539666849797), 566 (1608630539666849798), 567 (1608630539666849799), 568 (1608630539666849800), 569 (1608630539666849801), ... (20 adds)]} 0 0
[junit4] 2> 2149482 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5300
[junit4] 2> 2149483 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 (1608630539878662144), 581 (1608630539878662145), 582 (1608630539878662146), 583 (1608630539878662147), 584 (1608630539878662148), 585 (1608630539878662149), 586 (1608630539878662150), 587 (1608630539878662151), 588 (1608630539878662152), 589 (1608630539878662153), ... (20 adds)]} 0 0
[junit4] 2> 2149684 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5501
[junit4] 2> 2149685 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 (1608630540089425920), 601 (1608630540089425921), 602 (1608630540089425922), 603 (1608630540089425923), 604 (1608630540089425924), 605 (1608630540089425925), 606 (1608630540089425926), 607 (1608630540089425927), 608 (1608630540089425928), 609 (1608630540089425929), ... (20 adds)]} 0 0
[junit4] 2> 2149885 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5703
[junit4] 2> 2149886 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 (1608630540301238272), 621 (1608630540301238273), 622 (1608630540301238274), 623 (1608630540301238275), 624 (1608630540301238276), 625 (1608630540301238277), 626 (1608630540301238278), 627 (1608630540301238279), 628 (1608630540301238280), 629 (1608630540301238281), ... (20 adds)]} 0 0
[junit4] 2> 2150087 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5904
[junit4] 2> 2150088 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 (1608630540512002048), 641 (1608630540512002049), 642 (1608630540512002050), 643 (1608630540512002051), 644 (1608630540512002052), 645 (1608630540512002053), 646 (1608630540512002054), 647 (1608630540512002055), 648 (1608630540512002056), 649 (1608630540512002057), ... (20 adds)]} 0 0
[junit4] 2> 2150288 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6106
[junit4] 2> 2150289 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 (1608630540723814400), 661 (1608630540723814401), 662 (1608630540723814402), 663 (1608630540723814403), 664 (1608630540723814404), 665 (1608630540723814405), 666 (1608630540723814406), 667 (1608630540723814407), 668 (1608630540723814408), 669 (1608630540723814409), ... (20 adds)]} 0 0
[junit4] 2> 2150490 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6307
[junit4] 2> 2150491 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 (1608630540934578176), 681 (1608630540934578177), 682 (1608630540934578178), 683 (1608630540934578179), 684 (1608630540934578180), 685 (1608630540934578181), 686 (1608630540935626752), 687 (1608630540935626753), 688 (1608630540935626754), 689 (1608630540935626755), ... (20 adds)]} 0 0
[junit4] 2> 2150492 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2150492 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2150494 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d79899b[collection1] realtime]
[junit4] 2> 2150494 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2150541 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6359
[junit4] 2> 2150543 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[700 (1608630540989104128), 701 (1608630540990152704), 702 (1608630540990152705), 703 (1608630540990152706), 704 (1608630540990152707), 705 (1608630540990152708), 706 (1608630540990152709), 707 (1608630540990152710), 708 (1608630540990152711), 709 (1608630540990152712), ... (20 adds)]} 0 1
[junit4] 2> 2150744 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6561
[junit4] 2> 2150745 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[720 (1608630541200916480), 721 (1608630541200916481), 722 (1608630541200916482), 723 (1608630541200916483), 724 (1608630541200916484), 725 (1608630541200916485), 726 (1608630541200916486), 727 (1608630541200916487), 728 (1608630541200916488), 729 (1608630541200916489), ... (20 adds)]} 0 0
[junit4] 2> 2150945 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6763
[junit4] 2> 2150946 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[740 (1608630541412728832), 741 (1608630541412728833), 742 (1608630541412728834), 743 (1608630541412728835), 744 (1608630541412728836), 745 (1608630541412728837), 746 (1608630541412728838), 747 (1608630541412728839), 748 (1608630541412728840), 749 (1608630541412728841), ... (20 adds)]} 0 0
[junit4] 2> 2151147 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6964
[junit4] 2> 2151148 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[760 (1608630541623492608), 761 (1608630541623492609), 762 (1608630541623492610), 763 (1608630541623492611), 764 (1608630541623492612), 765 (1608630541623492613), 766 (1608630541624541184), 767 (1608630541624541185), 768 (1608630541624541186), 769 (1608630541624541187), ... (20 adds)]} 0 0
[junit4] 2> 2151349 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7166
[junit4] 2> 2151349 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[780 (1608630541835304960), 781 (1608630541835304961), 782 (1608630541835304962), 783 (1608630541835304963), 784 (1608630541835304964), 785 (1608630541835304965), 786 (1608630541835304966), 787 (1608630541835304967), 788 (1608630541835304968), 789 (1608630541835304969), ... (20 adds)]} 0 0
[junit4] 2> 2151550 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7367
[junit4] 2> 2151551 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[800 (1608630542046068736), 801 (1608630542046068737), 802 (1608630542046068738), 803 (1608630542046068739), 804 (1608630542046068740), 805 (1608630542046068741), 806 (1608630542047117312), 807 (1608630542047117313), 808 (1608630542047117314), 809 (1608630542047117315), ... (20 adds)]} 0 0
[junit4] 2> 2151752 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7569
[junit4] 2> 2151752 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[820 (1608630542257881088), 821 (1608630542257881089), 822 (1608630542257881090), 823 (1608630542257881091), 824 (1608630542257881092), 825 (1608630542257881093), 826 (1608630542257881094), 827 (1608630542257881095), 828 (1608630542257881096), 829 (1608630542257881097), ... (20 adds)]} 0 0
[junit4] 2> 2151753 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2151753 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2151755 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56be9ef3[collection1] realtime]
[junit4] 2> 2151755 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2151803 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7620
[junit4] 2> 2151804 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[840 (1608630542311358464), 841 (1608630542312407040), 842 (1608630542312407041), 843 (1608630542312407042), 844 (1608630542312407043), 845 (1608630542312407044), 846 (1608630542312407045), 847 (1608630542312407046), 848 (1608630542312407047), 849 (1608630542312407048), ... (20 adds)]} 0 1
[junit4] 2> 2152005 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=7822
[junit4] 2> 2152006 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[860 (1608630542524219392), 861 (1608630542524219393), 862 (1608630542524219394), 863 (1608630542524219395), 864 (1608630542524219396), 865 (1608630542524219397), 866 (1608630542524219398), 867 (1608630542524219399), 868 (1608630542524219400), 869 (1608630542524219401), ... (20 adds)]} 0 0
[junit4] 2> 2152207 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8024
[junit4] 2> 2152208 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[880 (1608630542734983168), 881 (1608630542734983169), 882 (1608630542734983170), 883 (1608630542736031744), 884 (1608630542736031745), 885 (1608630542736031746), 886 (1608630542736031747), 887 (1608630542736031748), 888 (1608630542736031749), 889 (1608630542736031750), ... (20 adds)]} 0 0
[junit4] 2> 2152409 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8226
[junit4] 2> 2152409 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[900 (1608630542946795520), 901 (1608630542946795521), 902 (1608630542946795522), 903 (1608630542946795523), 904 (1608630542946795524), 905 (1608630542946795525), 906 (1608630542946795526), 907 (1608630542946795527), 908 (1608630542946795528), 909 (1608630542946795529), ... (20 adds)]} 0 0
[junit4] 2> 2152610 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8427
[junit4] 2> 2152611 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[920 (1608630543157559296), 921 (1608630543157559297), 922 (1608630543157559298), 923 (1608630543157559299), 924 (1608630543157559300), 925 (1608630543158607872), 926 (1608630543158607873), 927 (1608630543158607874), 928 (1608630543158607875), 929 (1608630543158607876), ... (20 adds)]} 0 0
[junit4] 2> 2152811 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8629
[junit4] 2> 2152812 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 (1608630543369371648), 941 (1608630543369371649), 942 (1608630543369371650), 943 (1608630543369371651), 944 (1608630543369371652), 945 (1608630543369371653), 946 (1608630543369371654), 947 (1608630543369371655), 948 (1608630543369371656), 949 (1608630543369371657), ... (20 adds)]} 0 0
[junit4] 2> 2153013 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8830
[junit4] 2> 2153014 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 (1608630543580135424), 961 (1608630543580135425), 962 (1608630543580135426), 963 (1608630543580135427), 964 (1608630543580135428), 965 (1608630543580135429), 966 (1608630543580135430), 967 (1608630543580135431), 968 (1608630543580135432), 969 (1608630543580135433), ... (20 adds)]} 0 0
[junit4] 2> 2153015 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2153015 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2153016 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@28b34554[collection1] realtime]
[junit4] 2> 2153017 INFO (commitScheduler-10296-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2153064 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=8881
[junit4] 2> 2153065 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 (1608630543633612800), 981 (1608630543634661376), 982 (1608630543634661377), 983 (1608630543634661378), 984 (1608630543634661379), 985 (1608630543634661380), 986 (1608630543634661381), 987 (1608630543634661382), 988 (1608630543634661383), 989 (1608630543634661384), ... (20 adds)]} 0 1
[junit4] 2> 2153266 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=9083
[junit4] 2> 2153266 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2153266 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5c4f168e commitCommandVersion:0
[junit4] 2> 2153268 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f81ae62[collection1] main]
[junit4] 2> 2153268 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2153269 INFO (searcherExecutor-10293-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f81ae62[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C140) Uninverting(_1(8.0.0):C140) Uninverting(_2(8.0.0):C140) Uninverting(_3(8.0.0):C140) Uninverting(_4(8.0.0):C140) Uninverting(_5(8.0.0):C140) Uninverting(_6(8.0.0):C140) Uninverting(_7(8.0.0):C20)))}
[junit4] 2> 2153319 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=1000 status=0 QTime=9137
[junit4] 2> 2153322 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1496305822
[junit4] 2> 2153322 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2153322 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c0f2a7b: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@61415d90
[junit4] 2> 2153326 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2153326 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13977cd2: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3a26a826
[junit4] 2> 2153328 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2153328 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c2869aa: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@66f49570
[junit4] 2> 2153329 INFO (coreCloseExecutor-10298-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4482a292
[junit4] 2> 2153329 INFO (coreCloseExecutor-10298-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4482a292
[junit4] 2> 2153329 INFO (coreCloseExecutor-10298-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c9f209d: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@f340888
[junit4] 2> 2153337 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2153348 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2153355 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2153410 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2153575 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2153575 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2153585 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2153592 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2153592 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2153593 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2153606 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2153613 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2153668 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2153673 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2153673 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@757db4c4
[junit4] 2> 2153673 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2153673 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.update.MaxSizeAutoCommitTest_3C66F996AC542D1-001/init-core-data-001/]
[junit4] 2> 2153674 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2153675 WARN (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2153696 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2153696 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2153704 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2153704 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2153704 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=73.3173828125, floorSegmentMB=1.6064453125, forceMergeDeletesPctAllowed=5.340000614952993, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6969336812489716, deletesPctAllowed=32.50513481288843
[junit4] 2> 2153704 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@42377fcb[collection1] main]
[junit4] 2> 2153705 WARN (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2153705 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2153705 INFO (coreLoadExecutor-10308-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608630544305750016
[junit4] 2> 2153706 INFO (searcherExecutor-10309-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@42377fcb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2153708 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608630544307847168), 1 (1608630544308895744), 2 (1608630544308895745), 3 (1608630544308895746), 4 (1608630544308895747), 5 (1608630544308895748), 6 (1608630544308895749), 7 (1608630544308895750), 8 (1608630544308895751), 9 (1608630544308895752), ... (20 adds)]} 0 1
[junit4] 2> 2153928 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=221
[junit4] 2> 2153930 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1608630544541679616), 21 (1608630544541679617), 22 (1608630544541679618), 23 (1608630544541679619), 24 (1608630544541679620), 25 (1608630544541679621), 26 (1608630544541679622), 27 (1608630544541679623), 28 (1608630544541679624), 29 (1608630544541679625), ... (20 adds)]} 0 0
[junit4] 2> 2154131 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=423
[junit4] 2> 2154131 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1608630544752443392), 41 (1608630544752443393), 42 (1608630544752443394), 43 (1608630544752443395), 44 (1608630544752443396), 45 (1608630544752443397), 46 (1608630544752443398), 47 (1608630544752443399), 48 (1608630544752443400), 49 (1608630544752443401), ... (20 adds)]} 0 0
[junit4] 2> 2154332 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=625
[junit4] 2> 2154333 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1608630544963207168), 61 (1608630544963207169), 62 (1608630544963207170), 63 (1608630544963207171), 64 (1608630544963207172), 65 (1608630544963207173), 66 (1608630544963207174), 67 (1608630544963207175), 68 (1608630544963207176), 69 (1608630544963207177), ... (20 adds)]} 0 0
[junit4] 2> 2154533 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=826
[junit4] 2> 2154534 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1608630545175019520), 81 (1608630545175019521), 82 (1608630545175019522), 83 (1608630545175019523), 84 (1608630545175019524), 85 (1608630545175019525), 86 (1608630545175019526), 87 (1608630545175019527), 88 (1608630545175019528), 89 (1608630545175019529), ... (20 adds)]} 0 0
[junit4] 2> 2154735 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1027
[junit4] 2> 2154735 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1608630545385783296), 101 (1608630545385783297), 102 (1608630545385783298), 103 (1608630545385783299), 104 (1608630545385783300), 105 (1608630545385783301), 106 (1608630545385783302), 107 (1608630545385783303), 108 (1608630545385783304), 109 (1608630545385783305), ... (20 adds)]} 0 0
[junit4] 2> 2154936 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1228
[junit4] 2> 2154937 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1608630545596547072), 121 (1608630545596547073), 122 (1608630545596547074), 123 (1608630545596547075), 124 (1608630545596547076), 125 (1608630545596547077), 126 (1608630545596547078), 127 (1608630545596547079), 128 (1608630545597595648), 129 (1608630545597595649), ... (20 adds)]} 0 1
[junit4] 2> 2154938 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2154938 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@560c2b31 commitCommandVersion:0
[junit4] 2> 2154941 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@42845437[collection1] realtime]
[junit4] 2> 2154941 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2154988 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1280
[junit4] 2> 2154989 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1608630545651073024), 141 (1608630545652121600), 142 (1608630545652121601), 143 (1608630545652121602), 144 (1608630545652121603), 145 (1608630545652121604), 146 (1608630545652121605), 147 (1608630545652121606), 148 (1608630545652121607), 149 (1608630545652121608), ... (20 adds)]} 0 1
[junit4] 2> 2155190 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1482
[junit4] 2> 2155191 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1608630545862885376), 161 (1608630545862885377), 162 (1608630545862885378), 163 (1608630545862885379), 164 (1608630545862885380), 165 (1608630545862885381), 166 (1608630545862885382), 167 (1608630545862885383), 168 (1608630545862885384), 169 (1608630545862885385), ... (20 adds)]} 0 0
[junit4] 2> 2155391 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1684
[junit4] 2> 2155392 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1608630546073649152), 181 (1608630546074697728), 182 (1608630546074697729), 183 (1608630546074697730), 184 (1608630546074697731), 185 (1608630546074697732), 186 (1608630546074697733), 187 (1608630546074697734), 188 (1608630546074697735), 189 (1608630546074697736), ... (20 adds)]} 0 0
[junit4] 2> 2155592 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1885
[junit4] 2> 2155593 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 (1608630546285461504), 201 (1608630546285461505), 202 (1608630546285461506), 203 (1608630546285461507), 204 (1608630546285461508), 205 (1608630546285461509), 206 (1608630546285461510), 207 (1608630546285461511), 208 (1608630546285461512), 209 (1608630546285461513), ... (20 adds)]} 0 0
[junit4] 2> 2155794 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2086
[junit4] 2> 2155795 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 (1608630546496225280), 221 (1608630546496225281), 222 (1608630546497273856), 223 (1608630546497273857), 224 (1608630546497273858), 225 (1608630546497273859), 226 (1608630546497273860), 227 (1608630546497273861), 228 (1608630546497273862), 229 (1608630546497273863), ... (20 adds)]} 0 0
[junit4] 2> 2155996 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2288
[junit4] 2> 2155996 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 (1608630546708037632), 241 (1608630546708037633), 242 (1608630546708037634), 243 (1608630546708037635), 244 (1608630546708037636), 245 (1608630546708037637), 246 (1608630546708037638), 247 (1608630546708037639), 248 (1608630546708037640), 249 (1608630546708037641), ... (20 adds)]} 0 0
[junit4] 2> 2156197 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2490
[junit4] 2> 2156198 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 (1608630546918801408), 261 (1608630546918801409), 262 (1608630546918801410), 263 (1608630546918801411), 264 (1608630546918801412), 265 (1608630546918801413), 266 (1608630546918801414), 267 (1608630546918801415), 268 (1608630546919849984), 269 (1608630546919849985), ... (20 adds)]} 0 0
[junit4] 2> 2156199 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2156199 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@560c2b31 commitCommandVersion:0
[junit4] 2> 2156200 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2fa5625d[collection1] realtime]
[junit4] 2> 2156201 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2156248 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2541
[junit4] 2> 2156249 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 (1608630546972278784), 281 (1608630546973327360), 282 (1608630546973327361), 283 (1608630546973327362), 284 (1608630546973327363), 285 (1608630546973327364), 286 (1608630546973327365), 287 (1608630546973327366), 288 (1608630546973327367), 289 (1608630546973327368), ... (20 adds)]} 0 0
[junit4] 2> 2156450 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2742
[junit4] 2> 2156451 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 (1608630547184091136), 301 (1608630547184091137), 302 (1608630547184091138), 303 (1608630547184091139), 304 (1608630547184091140), 305 (1608630547184091141), 306 (1608630547184091142), 307 (1608630547184091143), 308 (1608630547184091144), 309 (1608630547184091145), ... (20 adds)]} 0 0
[junit4] 2> 2156651 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=2944
[junit4] 2> 2156652 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 (1608630547395903488), 321 (1608630547395903489), 322 (1608630547395903490), 323 (1608630547395903491), 324 (1608630547395903492), 325 (1608630547395903493), 326 (1608630547395903494), 327 (1608630547395903495), 328 (1608630547395903496), 329 (1608630547395903497), ... (20 adds)]} 0 0
[junit4] 2> 2156853 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3145
[junit4] 2> 2156853 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 (1608630547606667264), 341 (1608630547606667265), 342 (1608630547606667266), 343 (1608630547606667267), 344 (1608630547606667268), 345 (1608630547606667269), 346 (1608630547606667270), 347 (1608630547606667271), 348 (1608630547606667272), 349 (1608630547606667273), ... (20 adds)]} 0 0
[junit4] 2> 2157054 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3346
[junit4] 2> 2157054 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 (1608630547817431040), 361 (1608630547817431041), 362 (1608630547817431042), 363 (1608630547817431043), 364 (1608630547817431044), 365 (1608630547817431045), 366 (1608630547817431046), 367 (1608630547817431047), 368 (1608630547817431048), 369 (1608630547817431049), ... (20 adds)]} 0 0
[junit4] 2> 2157255 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3547
[junit4] 2> 2157256 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 (1608630548028194816), 381 (1608630548028194817), 382 (1608630548028194818), 383 (1608630548028194819), 384 (1608630548028194820), 385 (1608630548028194821), 386 (1608630548028194822), 387 (1608630548028194823), 388 (1608630548028194824), 389 (1608630548028194825), ... (20 adds)]} 0 0
[junit4] 2> 2157456 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3749
[junit4] 2> 2157457 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 (1608630548240007168), 401 (1608630548240007169), 402 (1608630548240007170), 403 (1608630548240007171), 404 (1608630548240007172), 405 (1608630548240007173), 406 (1608630548240007174), 407 (1608630548240007175), 408 (1608630548240007176), 409 (1608630548240007177), ... (20 adds)]} 0 0
[junit4] 2> 2157458 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2157458 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@560c2b31 commitCommandVersion:0
[junit4] 2> 2157460 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f48f94f[collection1] realtime]
[junit4] 2> 2157460 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2157508 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=3800
[junit4] 2> 2157509 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 (1608630548293484544), 421 (1608630548293484545), 422 (1608630548293484546), 423 (1608630548293484547), 424 (1608630548293484548), 425 (1608630548293484549), 426 (1608630548293484550), 427 (1608630548293484551), 428 (1608630548293484552), 429 (1608630548293484553), ... (20 adds)]} 0 0
[junit4] 2> 2157709 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4002
[junit4] 2> 2157710 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 (1608630548505296896), 441 (1608630548505296897), 442 (1608630548505296898), 443 (1608630548505296899), 444 (1608630548505296900), 445 (1608630548505296901), 446 (1608630548505296902), 447 (1608630548505296903), 448 (1608630548505296904), 449 (1608630548505296905), ... (20 adds)]} 0 0
[junit4] 2> 2157911 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4204
[junit4] 2> 2157912 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 (1608630548716060672), 461 (1608630548716060673), 462 (1608630548717109248), 463 (1608630548717109249), 464 (1608630548717109250), 465 (1608630548717109251), 466 (1608630548717109252), 467 (1608630548717109253), 468 (1608630548717109254), 469 (1608630548717109255), ... (20 adds)]} 0 0
[junit4] 2> 2158112 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4405
[junit4] 2> 2158113 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 (1608630548927873024), 481 (1608630548927873025), 482 (1608630548927873026), 483 (1608630548927873027), 484 (1608630548927873028), 485 (1608630548927873029), 486 (1608630548927873030), 487 (1608630548927873031), 488 (1608630548927873032), 489 (1608630548927873033), ... (20 adds)]} 0 0
[junit4] 2> 2158314 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4606
[junit4] 2> 2158314 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1608630549138636800), 501 (1608630549138636801), 502 (1608630549138636802), 503 (1608630549138636803), 504 (1608630549138636804), 505 (1608630549138636805), 506 (1608630549138636806), 507 (1608630549138636807), 508 (1608630549138636808), 509 (1608630549138636809), ... (20 adds)]} 0 0
[junit4] 2> 2158515 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=4807
[junit4] 2> 2158516 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 (1608630549349400576), 521 (1608630549349400577), 522 (1608630549349400578), 523 (1608630549349400579), 524 (1608630549349400580), 525 (1608630549349400581), 526 (1608630549349400582), 527 (1608630549349400583), 528 (1608630549349400584), 529 (1608630549349400585), ... (20 adds)]} 0 0
[junit4] 2> 2158716 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5009
[junit4] 2> 2158717 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 (1608630549561212928), 541 (1608630549561212929), 542 (1608630549561212930), 543 (1608630549561212931), 544 (1608630549561212932), 545 (1608630549561212933), 546 (1608630549561212934), 547 (1608630549561212935), 548 (1608630549561212936), 549 (1608630549561212937), ... (20 adds)]} 0 0
[junit4] 2> 2158718 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2158718 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@560c2b31 commitCommandVersion:0
[junit4] 2> 2158720 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a17ce97[collection1] realtime]
[junit4] 2> 2158767 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5060
[junit4] 2> 2158856 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2158857 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 (1608630549614690304), 561 (1608630549708013568), 562 (1608630549708013569), 563 (1608630549708013570), 564 (1608630549708013571), 565 (1608630549708013572), 566 (1608630549708013573), 567 (1608630549708013574), 568 (1608630549708013575), 569 (1608630549708013576), ... (20 adds)]} 0 89
[junit4] 2> 2159058 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5350
[junit4] 2> 2159059 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 (1608630549918777344), 581 (1608630549918777345), 582 (1608630549918777346), 583 (1608630549918777347), 584 (1608630549918777348), 585 (1608630549918777349), 586 (1608630549918777350), 587 (1608630549918777351), 588 (1608630549918777352), 589 (1608630549918777353), ... (20 adds)]} 0 0
[junit4] 2> 2159259 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5552
[junit4] 2> 2159260 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 (1608630550130589696), 601 (1608630550130589697), 602 (1608630550130589698), 603 (1608630550130589699), 604 (1608630550130589700), 605 (1608630550130589701), 606 (1608630550130589702), 607 (1608630550130589703), 608 (1608630550130589704), 609 (1608630550130589705), ... (20 adds)]} 0 0
[junit4] 2> 2159461 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5753
[junit4] 2> 2159461 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 (1608630550341353472), 621 (1608630550341353473), 622 (1608630550341353474), 623 (1608630550341353475), 624 (1608630550341353476), 625 (1608630550341353477), 626 (1608630550341353478), 627 (1608630550341353479), 628 (1608630550341353480), 629 (1608630550341353481), ... (20 adds)]} 0 0
[junit4] 2> 2159662 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=5954
[junit4] 2> 2159663 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 (1608630550552117248), 641 (1608630550552117249), 642 (1608630550552117250), 643 (1608630550552117251), 644 (1608630550552117252), 645 (1608630550552117253), 646 (1608630550552117254), 647 (1608630550552117255), 648 (1608630550552117256), 649 (1608630550552117257), ... (20 adds)]} 0 0
[junit4] 2> 2159863 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6156
[junit4] 2> 2159864 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 (1608630550762881024), 661 (1608630550763929600), 662 (1608630550763929601), 663 (1608630550763929602), 664 (1608630550763929603), 665 (1608630550763929604), 666 (1608630550763929605), 667 (1608630550763929606), 668 (1608630550763929607), 669 (1608630550763929608), ... (20 adds)]} 0 0
[junit4] 2> 2160064 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6357
[junit4] 2> 2160065 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 (1608630550974693376), 681 (1608630550974693377), 682 (1608630550974693378), 683 (1608630550974693379), 684 (1608630550974693380), 685 (1608630550974693381), 686 (1608630550974693382), 687 (1608630550974693383), 688 (1608630550974693384), 689 (1608630550974693385), ... (20 adds)]} 0 0
[junit4] 2> 2160066 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2160066 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@560c2b31 commitCommandVersion:0
[junit4] 2> 2160068 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@733a81[collection1] realtime]
[junit4] 2> 2160068 INFO (commitScheduler-10312-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2160116 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3C66F996AC542D1]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6408
[ju
[...truncated too long message...]
ds-interceptors-admin-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/api-all-1.0.0-M20.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/bcprov-jdk15on-1.54.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/byte-buddy-1.6.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/commons-collections-3.2.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/commons-math3-3.6.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/ehcache-core-2.4.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/hadoop-common-2.7.4-tests.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/hadoop-hdfs-2.7.4-tests.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/hadoop-minikdc-2.7.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/jersey-core-1.9.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/jersey-server-1.9.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/jetty-6.1.26.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/jetty-sslengine-6.1.26.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/jetty-util-6.1.26.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/mina-core-2.0.0-M5.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/mockito-core-2.6.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/netty-all-4.0.36.Final.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/test-lib/objenesis-2.5.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/lucene-analyzers-icu-8.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-analysis-extras/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/classes/test:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/classes/test:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/analysis-extras/lib/icu4j-62.1.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/home/jenkins/.ant/lib/ivy-2.4.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/home/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.6.0.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20180812_205800_92317914706415026122960.events @/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20180812_205800_9236140317244312025666.suites -stdin
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20180812_205800_92311964180906072912054.sysout
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] at java.base/java.lang.Thread.run(Thread.java:844)
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:633: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:577: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:267: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:558: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20180812_205800_92311964180906072912054.sysout
Total time: 63 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2