You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/08/12 21:17:32 UTC
[JENKINS] Lucene-Solr-Tests-7.x - Build # 770 - Still Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/770/
1 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testDeleteWithCollection
Error Message:
Error from server at https://127.0.0.1:40765/solr: Could not find collection : testDeleteWithCollection_abc_2
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40765/solr: Could not find collection : testDeleteWithCollection_abc_2
at __randomizedtesting.SeedInfo.seed([D03CCBA6E7CDC997:ABE53C4BBE571B29]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13011 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/init-core-data-001
[junit4] 2> 1159824 WARN (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1159824 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1159826 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1159826 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1159827 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001
[junit4] 2> 1159827 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1159836 INFO (Thread-2197) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1159836 INFO (Thread-2197) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1159852 ERROR (Thread-2197) [ ] 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> 1159936 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:42777
[junit4] 2> 1159949 INFO (zkConnectionManagerCallback-1192-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1159954 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1159955 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1159956 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1159956 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1159956 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1159956 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@35c3ac79{/solr,null,AVAILABLE}
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@38e25776{SSL,[ssl, http/1.1]}{127.0.0.1:45057}
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.e.j.s.Server Started @1160023ms
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45057}
[junit4] 2> 1159958 ERROR (jetty-launcher-1189-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1159958 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-08-12T19:35:14.381Z
[junit4] 2> 1159996 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1159996 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1159996 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1160015 INFO (zkConnectionManagerCallback-1194-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160016 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45273d5{/solr,null,AVAILABLE}
[junit4] 2> 1160016 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@174d11a6{SSL,[ssl, http/1.1]}{127.0.0.1:40765}
[junit4] 2> 1160016 INFO (jetty-launcher-1189-thread-2) [ ] o.e.j.s.Server Started @1160081ms
[junit4] 2> 1160016 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40765}
[junit4] 2> 1160017 ERROR (jetty-launcher-1189-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1160017 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1160017 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.5.0
[junit4] 2> 1160017 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1160017 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1160017 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-08-12T19:35:14.440Z
[junit4] 2> 1160024 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1160035 INFO (zkConnectionManagerCallback-1196-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160040 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1160218 INFO (jetty-launcher-1189-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42777/solr
[junit4] 2> 1160219 INFO (zkConnectionManagerCallback-1200-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160221 INFO (zkConnectionManagerCallback-1202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160363 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40765_solr
[junit4] 2> 1160364 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.c.Overseer Overseer (id=73135000320475140-127.0.0.1:40765_solr-n_0000000000) starting
[junit4] 2> 1160372 INFO (zkConnectionManagerCallback-1209-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160378 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42777/solr ready
[junit4] 2> 1160380 INFO (OverseerStateUpdate-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:40765_solr
[junit4] 2> 1160381 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40765_solr
[junit4] 2> 1160397 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1160397 INFO (OverseerStateUpdate-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1160398 INFO (jetty-launcher-1189-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42777/solr
[junit4] 2> 1160407 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1160449 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 1160449 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1160501 INFO (zkConnectionManagerCallback-1213-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160508 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1160540 INFO (zkCallback-1208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1160546 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:40765_solr]
[junit4] 2> 1160549 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40765.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1160565 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40765.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1160565 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40765.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1160566 INFO (jetty-launcher-1189-thread-2) [n:127.0.0.1:40765_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/.
[junit4] 2> 1160574 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1160574 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1160575 DEBUG (ScheduledTrigger-2106-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 1160596 INFO (zkConnectionManagerCallback-1216-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160610 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1160614 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1160614 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45057_solr
[junit4] 2> 1160632 INFO (zkCallback-1208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1160668 INFO (zkCallback-1201-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1160683 INFO (zkCallback-1215-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1160683 INFO (zkConnectionManagerCallback-1223-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160691 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1160693 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42777/solr ready
[junit4] 2> 1160716 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1160736 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45057.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1160742 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45057.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1160742 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45057.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1160743 INFO (jetty-launcher-1189-thread-1) [n:127.0.0.1:45057_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node1/.
[junit4] 2> 1160824 INFO (zkConnectionManagerCallback-1226-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160865 INFO (zkConnectionManagerCallback-1231-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1160866 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1160866 INFO (SUITE-TestWithCollection-seed#[D03CCBA6E7CDC997]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42777/solr ready
[junit4] 2> 1160922 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[D03CCBA6E7CDC997]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMoveReplicaWithCollection
[junit4] 2> 1160923 DEBUG (zkCallback-1208-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1160923 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1160924 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[D03CCBA6E7CDC997]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1160954 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1160957 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1160958 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1161078 INFO (qtp352518337-6055) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1161142 INFO (qtp385612295-6048) [n:127.0.0.1:45057_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1161147 DEBUG (qtp352518337-6056) [n:127.0.0.1:40765_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1161148 INFO (qtp352518337-6056) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=140
[junit4] 2> 1161148 DEBUG (zkCallback-1208-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1161148 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1161148 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1161148 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[D03CCBA6E7CDC997]) [ ] o.a.s.c.TestWithCollection Chosen node 127.0.0.1:40765_solr for collection testMoveReplicaWithCollection_abc
[junit4] 2> 1161148 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1161149 DEBUG (OverseerAutoScalingTriggerThread-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1161149 INFO (qtp352518337-6056) [n:127.0.0.1:40765_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=testMoveReplicaWithCollection_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:40765_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1161160 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection testMoveReplicaWithCollection_abc
[junit4] 2> 1161161 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/protwords.txt
[junit4] 2> 1161163 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 1161164 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 1161167 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 1161168 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 1161169 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/params.json
[junit4] 2> 1161171 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1161172 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1161173 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1161175 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1161176 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1161178 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1161181 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1161182 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1161183 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1161192 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1161194 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1161195 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1161197 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1161199 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1161200 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1161201 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1161203 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1161220 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1161222 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1161223 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1161225 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1161226 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1161228 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1161229 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1161231 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1161232 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1161233 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1161235 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1161236 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1161238 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1161240 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1161273 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1161274 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1161284 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1161285 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1161287 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1161288 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1161289 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1161411 DEBUG (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1161426 INFO (qtp352518337-6060) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1161432 INFO (qtp385612295-6050) [n:127.0.0.1:45057_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1161437 DEBUG (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1161474 INFO (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 16439916834 sessionWrapper.createTime 16439916797551894, this.sessionWrapper.createTime 16439916797551894
[junit4] 2> 1161479 INFO (OverseerStateUpdate-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testMoveReplicaWithCollection_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testMoveReplicaWithCollection_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40765/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1161700 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testMoveReplicaWithCollection_abc.AUTOCREATED&newCollection=true&name=testMoveReplicaWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testMoveReplicaWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1161700 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1162782 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1162782 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1162801 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1162801 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 1162801 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1162802 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 1162802 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1162802 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 1162802 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 1162802 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1/../../../../dist
[junit4] 2> 1162835 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1162885 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.s.IndexSchema [testMoveReplicaWithCollection_abc_shard1_replica_n1] Schema name=default-config
[junit4] 2> 1163602 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1163659 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testMoveReplicaWithCollection_abc_shard1_replica_n1' using configuration from collection testMoveReplicaWithCollection_abc, trusted=true
[junit4] 2> 1163663 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40765.solr.core.testMoveReplicaWithCollection_abc.shard1.replica_n1' (registry 'solr.core.testMoveReplicaWithCollection_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1163663 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1163663 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrCore [[testMoveReplicaWithCollection_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/./testMoveReplicaWithCollection_abc_shard1_replica_n1/data/]
[junit4] 2> 1163690 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1163964 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1163964 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1163978 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 1163978 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1163980 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@42ab24c2[testMoveReplicaWithCollection_abc_shard1_replica_n1] main]
[junit4] 2> 1163982 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testMoveReplicaWithCollection_abc.AUTOCREATED
[junit4] 2> 1163982 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testMoveReplicaWithCollection_abc.AUTOCREATED
[junit4] 2> 1163982 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testMoveReplicaWithCollection_abc.AUTOCREATED/managed-schema
[junit4] 2> 1163983 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1163983 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1163983 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_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> 1163986 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1163986 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608623082343235584
[junit4] 2> 1163988 INFO (searcherExecutor-2120-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_abc_shard1_replica_n1 c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@42ab24c2[testMoveReplicaWithCollection_abc_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1163988 INFO (searcherExecutor-2120-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_abc_shard1_replica_n1 c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1163988 INFO (searcherExecutor-2120-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_abc_shard1_replica_n1 c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1163989 INFO (searcherExecutor-2120-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_abc_shard1_replica_n1 c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SolrCore [testMoveReplicaWithCollection_abc_shard1_replica_n1] Registered new searcher Searcher@42ab24c2[testMoveReplicaWithCollection_abc_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1163996 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMoveReplicaWithCollection_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1164001 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1164001 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1164001 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40765/solr/testMoveReplicaWithCollection_abc_shard1_replica_n1/
[junit4] 2> 1164001 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1164002 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:40765/solr/testMoveReplicaWithCollection_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 1164002 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1164011 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40765/solr/testMoveReplicaWithCollection_abc_shard1_replica_n1/ shard1
[junit4] 2> 1164015 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1164019 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_abc s:shard1 r:core_node2 x:testMoveReplicaWithCollection_abc_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testMoveReplicaWithCollection_abc.AUTOCREATED&newCollection=true&name=testMoveReplicaWithCollection_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testMoveReplicaWithCollection_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2319
[junit4] 2> 1164020 DEBUG (OverseerThreadFactory-2111-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1164032 INFO (qtp352518337-6056) [n:127.0.0.1:40765_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> 1164118 INFO (zkCallback-1201-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMoveReplicaWithCollection_abc/state.json] for collection [testMoveReplicaWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1165033 INFO (qtp352518337-6056) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testMoveReplicaWithCollection_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:40765_solr&wt=javabin&version=2} status=0 QTime=3883
[junit4] 2> 1165037 INFO (qtp352518337-6060) [n:127.0.0.1:40765_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&withCollection=testMoveReplicaWithCollection_abc&name=testMoveReplicaWithCollection_xyz&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1165057 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection testMoveReplicaWithCollection_xyz
[junit4] 2> 1165057 INFO (OverseerCollectionConfigSetProcessor-73135000320475140-127.0.0.1:40765_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> 1165068 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 1165070 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2> 1165072 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 1165074 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/synonyms.txt
[junit4] 2> 1165075 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/stopwords.txt
[junit4] 2> 1165086 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/params.json
[junit4] 2> 1165088 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1165090 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1165091 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1165093 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1165104 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1165114 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1165116 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1165126 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1165129 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1165135 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1165147 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1165151 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1165155 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1165157 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1165159 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1165164 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1165183 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1165189 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1165194 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1165200 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1165204 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1165211 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1165216 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1165219 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1165223 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1165232 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1165238 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1165249 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1165255 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1165260 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1165263 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1165266 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1165268 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1165276 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1165279 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1165288 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1165290 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1165295 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1165512 DEBUG (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1165516 INFO (qtp352518337-6058) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testMoveReplicaWithCollection_abc.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1165525 INFO (qtp352518337-6061) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1165532 INFO (qtp385612295-6048) [n:127.0.0.1:45057_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1165534 DEBUG (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1165539 INFO (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 16439920899 sessionWrapper.createTime 16439920894127547, this.sessionWrapper.createTime 16439920894127547
[junit4] 2> 1165542 INFO (OverseerStateUpdate-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testMoveReplicaWithCollection_xyz",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testMoveReplicaWithCollection_xyz_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40765/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1165747 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testMoveReplicaWithCollection_xyz.AUTOCREATED&newCollection=true&name=testMoveReplicaWithCollection_xyz_shard1_replica_n1&action=CREATE&numShards=1&collection=testMoveReplicaWithCollection_xyz&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1166805 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1166805 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1166806 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1166806 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 1166806 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1166806 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 1166808 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1166808 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 1166808 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 1166808 WARN (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1/../../../../dist
[junit4] 2> 1166862 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 1166993 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.s.IndexSchema [testMoveReplicaWithCollection_xyz_shard1_replica_n1] Schema name=default-config
[junit4] 2> 1167685 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1167816 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testMoveReplicaWithCollection_xyz_shard1_replica_n1' using configuration from collection testMoveReplicaWithCollection_xyz, trusted=true
[junit4] 2> 1167818 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40765.solr.core.testMoveReplicaWithCollection_xyz.shard1.replica_n1' (registry 'solr.core.testMoveReplicaWithCollection_xyz.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@653fdb3
[junit4] 2> 1167818 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1167818 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrCore [[testMoveReplicaWithCollection_xyz_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/testMoveReplicaWithCollection_xyz_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_D03CCBA6E7CDC997-001/tempDir-001/node2/./testMoveReplicaWithCollection_xyz_shard1_replica_n1/data/]
[junit4] 2> 1167848 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1168108 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1168108 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1168109 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 1168109 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1168124 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@766ca704[testMoveReplicaWithCollection_xyz_shard1_replica_n1] main]
[junit4] 2> 1168125 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED
[junit4] 2> 1168126 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testMoveReplicaWithCollection_xyz.AUTOCREATED
[junit4] 2> 1168126 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testMoveReplicaWithCollection_xyz.AUTOCREATED/managed-schema
[junit4] 2> 1168126 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1168127 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1168127 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_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> 1168129 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1168129 INFO (searcherExecutor-2125-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_xyz_shard1_replica_n1 c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@766ca704[testMoveReplicaWithCollection_xyz_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1168129 INFO (searcherExecutor-2125-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_xyz_shard1_replica_n1 c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1168129 INFO (searcherExecutor-2125-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_xyz_shard1_replica_n1 c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1168130 INFO (searcherExecutor-2125-thread-1-processing-n:127.0.0.1:40765_solr x:testMoveReplicaWithCollection_xyz_shard1_replica_n1 c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SolrCore [testMoveReplicaWithCollection_xyz_shard1_replica_n1] Registered new searcher Searcher@766ca704[testMoveReplicaWithCollection_xyz_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1168130 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608623086688534528
[junit4] 2> 1168138 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMoveReplicaWithCollection_xyz/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1168151 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1168151 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1168151 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40765/solr/testMoveReplicaWithCollection_xyz_shard1_replica_n1/
[junit4] 2> 1168152 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1168152 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:40765/solr/testMoveReplicaWithCollection_xyz_shard1_replica_n1/ has no replicas
[junit4] 2> 1168152 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1168158 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40765/solr/testMoveReplicaWithCollection_xyz_shard1_replica_n1/ shard1
[junit4] 2> 1168159 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1168167 INFO (qtp352518337-6059) [n:127.0.0.1:40765_solr c:testMoveReplicaWithCollection_xyz s:shard1 r:core_node2 x:testMoveReplicaWithCollection_xyz_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testMoveReplicaWithCollection_xyz.AUTOCREATED&newCollection=true&name=testMoveReplicaWithCollection_xyz_shard1_replica_n1&action=CREATE&numShards=1&collection=testMoveReplicaWithCollection_xyz&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2420
[junit4] 2> 1168171 DEBUG (OverseerThreadFactory-2111-thread-2) [ ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1168176 INFO (qtp352518337-6060) [n:127.0.0.1:40765_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> 1168273 INFO (zkCallback-1201-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMoveReplicaWithCollection_xyz/state.json] for collection [testMoveReplicaWithCollection_xyz] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1168274 INFO (zkCallback-1201-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMoveReplicaWithCollection_abc/state.json] for collection [testMoveReplicaWithCollection_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1169067 INFO (OverseerCollectionConfigSetProcessor-73135000320475140-127.0.0.1:40765_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1169177 INFO (qtp352518337-6060) [n:127.0.0.1:40765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&withCollection=testMoveReplicaWithCollection_abc&name=testMoveReplicaWithCollection_xyz&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=4140
[junit4] 2> 1169179 INFO (qtp352518337-6058) [n:127.0.0.1:40765_solr ] o.a.s.h.a.CollectionsHandler Invoked Collectio
[...truncated too long message...]
operty disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null259575851
[copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null259575851
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null259575851
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 60ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 197 minutes 48 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
[JENKINS] Lucene-Solr-Tests-7.x - Build # 771 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/771/
1 tests failed.
FAILED: org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest
Error Message:
Tlog size exceeds the max size bound. Tlog path: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
Stack Trace:
java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
at __randomizedtesting.SeedInfo.seed([3E85A1170572129:13A6BFEE0BF918D8]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15244 lines...]
[junit4] Suite: org.apache.solr.update.MaxSizeAutoCommitTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001
[junit4] 2> 4286490 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4286799 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4286910 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 4287572 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4289480 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4289480 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4289610 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4289721 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4289721 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4289739 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4289976 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4290089 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4291160 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4291267 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 4291267 INFO (coreLoadExecutor-11537-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@3ce546f1
[junit4] 2> 4291267 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4291267 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/]
[junit4] 2> 4291268 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.9384765625, floorSegmentMB=0.908203125, forceMergeDeletesPctAllowed=1.2948243395438896, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37129738572993487, deletesPctAllowed=20.68653750676652
[junit4] 2> 4291283 WARN (coreLoadExecutor-11537-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> 4291697 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4291697 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4291698 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4291698 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4291712 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=6.322265625, floorSegmentMB=1.580078125, forceMergeDeletesPctAllowed=14.992196955303736, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7210172291965198, deletesPctAllowed=43.15834683229663
[junit4] 2> 4291712 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13586f22[collection1] main]
[junit4] 2> 4291713 WARN (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 4291713 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4291714 INFO (searcherExecutor-11538-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13586f22[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4291714 INFO (coreLoadExecutor-11537-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608639257228869632
[junit4] 2> 4291773 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4291773 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
[junit4] 2> 4291795 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@210a520a[collection1] realtime]
[junit4] 2> 4291795 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4291821 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4291821 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
[junit4] 2> 4291824 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@74470c81[collection1] realtime]
[junit4] 2> 4291824 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4291861 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4291861 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
[junit4] 2> 4291896 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f50cdcf[collection1] realtime]
[junit4] 2> 4291915 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4291932 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608639257249841152), 1 (1608639257265569792), 2 (1608639257265569793), 3 (1608639257266618368), 4 (1608639257266618369), 5 (1608639257266618370), 6 (1608639257266618371), 7 (1608639257266618372), 8 (1608639257266618373), 9 (1608639257266618374), ... (150 adds)]} 0 198
[junit4] 2> 4292152 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[0 (-1608639257688145920), 1 (-1608639257688145921), 2 (-1608639257688145922), 3 (-1608639257688145923), 4 (-1608639257688145924), 5 (-1608639257688145925), 6 (-1608639257688145926), 7 (-1608639257688145927), 8 (-1608639257688145928), 9 (-1608639257688145929), ... (15 deletes)]} 0 1
[junit4] 2> 4292370 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[15 (-1608639257916735488), 16 (-1608639257916735489), 17 (-1608639257916735490), 18 (-1608639257916735491), 19 (-1608639257916735492), 20 (-1608639257916735493), 21 (-1608639257916735494), 22 (-1608639257916735495), 23 (-1608639257916735496), 24 (-1608639257916735497), ... (15 deletes)]} 0 0
[junit4] 2> 4292590 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[30 (-1608639258146373632), 31 (-1608639258146373633), 32 (-1608639258147422208), 33 (-1608639258147422209), 34 (-1608639258147422210), 35 (-1608639258147422211), 36 (-1608639258147422212), 37 (-1608639258147422213), 38 (-1608639258147422214), 39 (-1608639258147422215), ... (15 deletes)]} 0 0
[junit4] 2> 4292826 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[45 (-1608639258393837568), 46 (-1608639258394886144), 47 (-1608639258394886145), 48 (-1608639258394886146), 49 (-1608639258394886147), 50 (-1608639258394886148), 51 (-1608639258394886149), 52 (-1608639258394886150), 53 (-1608639258394886151), 54 (-1608639258394886152), ... (15 deletes)]} 0 0
[junit4] 2> 4292827 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4292827 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
[junit4] 2> 4292879 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[60 (-1608639258449412096), 61 (-1608639258450460672), 62 (-1608639258450460673), 63 (-1608639258450460674), 64 (-1608639258450460675), 65 (-1608639258450460676), 66 (-1608639258450460677), 67 (-1608639258450460678), 68 (-1608639258450460679), 69 (-1608639258450460680), ... (15 deletes)]} 0 0
[junit4] 2> 4292880 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5491d682[collection1] realtime]
[junit4] 2> 4292880 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4293098 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[75 (-1608639258680098816), 76 (-1608639258680098817), 77 (-1608639258680098818), 78 (-1608639258680098819), 79 (-1608639258680098820), 80 (-1608639258680098821), 81 (-1608639258680098822), 82 (-1608639258680098823), 83 (-1608639258680098824), 84 (-1608639258680098825), ... (15 deletes)]} 0 0
[junit4] 2> 4293318 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[90 (-1608639258909736960), 91 (-1608639258909736961), 92 (-1608639258909736962), 93 (-1608639258909736963), 94 (-1608639258909736964), 95 (-1608639258909736965), 96 (-1608639258909736966), 97 (-1608639258910785536), 98 (-1608639258910785537), 99 (-1608639258910785538), ... (15 deletes)]} 0 0
[junit4] 2> 4293537 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[105 (-1608639259140423680), 106 (-1608639259140423681), 107 (-1608639259140423682), 108 (-1608639259140423683), 109 (-1608639259140423684), 110 (-1608639259140423685), 111 (-1608639259140423686), 112 (-1608639259140423687), 113 (-1608639259140423688), 114 (-1608639259140423689), ... (15 deletes)]} 0 0
[junit4] 2> 4293557 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4293557 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
[junit4] 2> 4293595 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[120 (-1608639259200192512), 121 (-1608639259201241088), 122 (-1608639259201241089), 123 (-1608639259201241090), 124 (-1608639259201241091), 125 (-1608639259201241092), 126 (-1608639259201241093), 127 (-1608639259201241094), 128 (-1608639259201241095), 129 (-1608639259201241096), ... (15 deletes)]} 0 1
[junit4] 2> 4293668 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@da1e888[collection1] realtime]
[junit4] 2> 4293668 INFO (commitScheduler-11541-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4293814 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[135 (-1608639259429830656), 136 (-1608639259429830657), 137 (-1608639259429830658), 138 (-1608639259429830659), 139 (-1608639259430879232), 140 (-1608639259430879233), 141 (-1608639259430879234), 142 (-1608639259430879235), 143 (-1608639259430879236), 144 (-1608639259430879237), ... (15 deletes)]} 0 0
[junit4] 2> 4294049 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=530956483
[junit4] 2> 4294050 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 4294050 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5fcd9de6: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@295e1f01
[junit4] 2> 4294107 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 4294107 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aa08725: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3637d942
[junit4] 2> 4294159 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 4294159 INFO (TEST-MaxSizeAutoCommitTest.deleteTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4ea7d0fd: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@66cb95cb
[junit4] 2> 4294216 INFO (coreCloseExecutor-11543-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1f83e13b
[junit4] 2> 4294216 INFO (coreCloseExecutor-11543-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1f83e13b
[junit4] 2> 4294216 INFO (coreCloseExecutor-11543-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a8f61b3: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2f6bf785
[junit4] 2> 4294255 INFO (coreCloseExecutor-11543-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 4294255 INFO (coreCloseExecutor-11543-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@696335ff commitCommandVersion:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MaxSizeAutoCommitTest -Dtests.method=deleteTest -Dtests.seed=3E85A1170572129 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en-US -Dtests.timezone=Asia/Anadyr -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 10.4s J1 | MaxSizeAutoCommitTest.deleteTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Tlog size exceeds the max size bound. Tlog path: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/tlog/tlog.0000000000000000003, tlog size: 1302
[junit4] > at __randomizedtesting.SeedInfo.seed([3E85A1170572129:13A6BFEE0BF918D8]:0)
[junit4] > at org.apache.solr.update.MaxSizeAutoCommitTest.getTlogFileSizes(MaxSizeAutoCommitTest.java:382)
[junit4] > at org.apache.solr.update.MaxSizeAutoCommitTest.deleteTest(MaxSizeAutoCommitTest.java:202)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4294664 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4294992 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4295046 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 4295731 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4296959 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4296960 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4297071 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4297107 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4297107 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4297119 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4297225 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4297282 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4297810 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4297833 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 4297846 INFO (coreLoadExecutor-11553-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@3ce546f1
[junit4] 2> 4297846 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4297846 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/]
[junit4] 2> 4297847 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.9384765625, floorSegmentMB=0.908203125, forceMergeDeletesPctAllowed=1.2948243395438896, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37129738572993487, deletesPctAllowed=20.68653750676652
[junit4] 2> 4297848 WARN (coreLoadExecutor-11553-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> 4298049 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4298049 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4298050 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4298050 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4298051 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=6.322265625, floorSegmentMB=1.580078125, forceMergeDeletesPctAllowed=14.992196955303736, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7210172291965198, deletesPctAllowed=43.15834683229663
[junit4] 2> 4298051 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e8ed571[collection1] main]
[junit4] 2> 4298051 WARN (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 4298052 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4298052 INFO (coreLoadExecutor-11553-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608639263874744320
[junit4] 2> 4298065 INFO (searcherExecutor-11554-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e8ed571[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4298086 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608639263891521536), 1 (1608639263909347328), 2 (1608639263910395904)]} 0 17
[junit4] 2> 4298350 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1608639264166248448), 4 (1608639264166248449), 5 (1608639264166248450)]} 0 19
[junit4] 2> 4298566 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1608639264413712384), 7 (1608639264413712385), 8 (1608639264413712386)]} 0 0
[junit4] 2> 4298785 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1608639264643350528), 10 (1608639264643350529), 11 (1608639264643350530)]} 0 0
[junit4] 2> 4298997 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1608639264864600064), 13 (1608639264865648640), 14 (1608639264865648641)]} 0 0
[junit4] 2> 4299215 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1608639265094238208), 16 (1608639265094238209), 17 (1608639265094238210)]} 0 0
[junit4] 2> 4299434 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[18 (1608639265323876352), 19 (1608639265323876353), 20 (1608639265323876354)]} 0 0
[junit4] 2> 4299646 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[21 (1608639265546174464), 22 (1608639265546174465), 23 (1608639265546174466)]} 0 0
[junit4] 2> 4299881 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[24 (1608639265775812608), 25 (1608639265775812609), 26 (1608639265775812610)]} 0 16
[junit4] 2> 4299881 INFO (commitScheduler-11557-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4299881 INFO (commitScheduler-11557-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3de07d5d commitCommandVersion:0
[junit4] 2> 4299883 INFO (commitScheduler-11557-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19a2b254[collection1] realtime]
[junit4] 2> 4299883 INFO (commitScheduler-11557-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4299939 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[27 (1608639265852358656), 28 (1608639265853407232), 29 (1608639265853407233)]} 0 1
[junit4] 2> 4300158 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=438235900
[junit4] 2> 4300158 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 4300158 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4e6b4848: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1e9a2cb5
[junit4] 2> 4300193 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 4300193 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5befb80e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3637d942
[junit4] 2> 4300236 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 4300236 INFO (TEST-MaxSizeAutoCommitTest.simpleTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67b856a3: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@66cb95cb
[junit4] 2> 4300271 INFO (coreCloseExecutor-11559-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6cbceae3
[junit4] 2> 4300271 INFO (coreCloseExecutor-11559-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=6cbceae3
[junit4] 2> 4300271 INFO (coreCloseExecutor-11559-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@65f26b54: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@68b5341d
[junit4] 2> 4300289 INFO (coreCloseExecutor-11559-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 4300290 INFO (coreCloseExecutor-11559-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3de07d5d commitCommandVersion:0
[junit4] 2> 4300430 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4300709 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4300762 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 4301626 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4302566 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4302566 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4302679 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4302749 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4302749 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4302778 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4302962 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4303054 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4304285 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4304380 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 4304380 INFO (coreLoadExecutor-11569-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@3ce546f1
[junit4] 2> 4304380 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4304380 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.update.MaxSizeAutoCommitTest_3E85A1170572129-001/init-core-data-001/]
[junit4] 2> 4304382 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.9384765625, floorSegmentMB=0.908203125, forceMergeDeletesPctAllowed=1.2948243395438896, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.37129738572993487, deletesPctAllowed=20.68653750676652
[junit4] 2> 4304397 WARN (coreLoadExecutor-11569-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> 4304773 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4304773 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4304774 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4304774 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4304775 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=6.322265625, floorSegmentMB=1.580078125, forceMergeDeletesPctAllowed=14.992196955303736, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7210172291965198, deletesPctAllowed=43.15834683229663
[junit4] 2> 4304775 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2639592c[collection1] main]
[junit4] 2> 4304775 WARN (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 4304776 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4304776 INFO (coreLoadExecutor-11569-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1608639270925369344
[junit4] 2> 4304794 INFO (searcherExecutor-11570-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2639592c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4304819 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1608639270962069504), 1 (1608639270969409536), 2 (1608639270970458112), 3 (1608639270970458113), 4 (1608639270970458114), 5 (1608639270970458115), 6 (1608639270970458116), 7 (1608639270970458117), 8 (1608639270970458118), 9 (1608639270970458119), ... (20 adds)]} 0 8
[junit4] 2> 4305033 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=222
[junit4] 2> 4305475 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[20 (1608639271657275392), 21 (1608639271657275393), 22 (1608639271657275394), 23 (1608639271657275395), 24 (1608639271657275396), 25 (1608639271657275397), 26 (1608639271658323968), 27 (1608639271658323969), 28 (1608639271658323970), 29 (1608639271658323971), ... (20 adds)]} 0 1
[junit4] 2> 4305688 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=877
[junit4] 2> 4305689 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[40 (1608639271882719232), 41 (1608639271882719233), 42 (1608639271882719234), 43 (1608639271882719235), 44 (1608639271882719236), 45 (1608639271882719237), 46 (1608639271882719238), 47 (1608639271882719239), 48 (1608639271882719240), 49 (1608639271882719241), ... (20 adds)]} 0 1
[junit4] 2> 4305904 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=1092
[junit4] 2> 4305905 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[60 (1608639272108163072), 61 (1608639272108163073), 62 (1608639272108163074), 63 (1608639272108163075), 64 (1608639272108163076), 65 (1608639272108163077), 66 (1608639272108163078), 67 (1608639272108163079), 68 (1608639272108163080), 69 (1608639272108163081), ... (20 adds)]} 0 0
[junit4] 2> 4306123 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=1312
[junit4] 2> 4306124 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[80 (1608639272337801216), 81 (1608639272338849792), 82 (1608639272338849793), 83 (1608639272338849794), 84 (1608639272338849795), 85 (1608639272338849796), 86 (1608639272338849797), 87 (1608639272338849798), 88 (1608639272338849799), 89 (1608639272338849800), ... (20 adds)]} 0 0
[junit4] 2> 4306334 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=1523
[junit4] 2> 4306336 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[100 (1608639272560099328), 101 (1608639272560099329), 102 (1608639272560099330), 103 (1608639272560099331), 104 (1608639272560099332), 105 (1608639272560099333), 106 (1608639272560099334), 107 (1608639272560099335), 108 (1608639272560099336), 109 (1608639272560099337), ... (20 adds)]} 0 0
[junit4] 2> 4306549 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=1738
[junit4] 2> 4306551 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[120 (1608639272785543168), 121 (1608639272785543169), 122 (1608639272785543170), 123 (1608639272785543171), 124 (1608639272785543172), 125 (1608639272785543173), 126 (1608639272785543174), 127 (1608639272785543175), 128 (1608639272785543176), 129 (1608639272785543177), ... (20 adds)]} 0 1
[junit4] 2> 4306552 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4306552 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4306568 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d984c7[collection1] realtime]
[junit4] 2> 4306568 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4306614 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=1803
[junit4] 2> 4306616 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[140 (1608639272853700608), 141 (1608639272853700609), 142 (1608639272853700610), 143 (1608639272853700611), 144 (1608639272853700612), 145 (1608639272853700613), 146 (1608639272853700614), 147 (1608639272854749184), 148 (1608639272854749185), 149 (1608639272854749186), ... (20 adds)]} 0 1
[junit4] 2> 4306817 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=2006
[junit4] 2> 4306818 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[160 (1608639273065512960), 161 (1608639273066561536), 162 (1608639273066561537), 163 (1608639273066561538), 164 (1608639273066561539), 165 (1608639273066561540), 166 (1608639273066561541), 167 (1608639273066561542), 168 (1608639273066561543), 169 (1608639273066561544), ... (20 adds)]} 0 0
[junit4] 2> 4307023 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=2212
[junit4] 2> 4307025 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[180 (1608639273282568192), 181 (1608639273282568193), 182 (1608639273282568194), 183 (1608639273282568195), 184 (1608639273282568196), 185 (1608639273282568197), 186 (1608639273282568198), 187 (1608639273282568199), 188 (1608639273282568200), 189 (1608639273282568201), ... (20 adds)]} 0 0
[junit4] 2> 4307242 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=2430
[junit4] 2> 4307243 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[200 (1608639273511157760), 201 (1608639273511157761), 202 (1608639273511157762), 203 (1608639273511157763), 204 (1608639273511157764), 205 (1608639273511157765), 206 (1608639273512206336), 207 (1608639273512206337), 208 (1608639273512206338), 209 (1608639273512206339), ... (20 adds)]} 0 0
[junit4] 2> 4307464 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=2653
[junit4] 2> 4307465 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[220 (1608639273744990208), 221 (1608639273744990209), 222 (1608639273744990210), 223 (1608639273744990211), 224 (1608639273744990212), 225 (1608639273744990213), 226 (1608639273744990214), 227 (1608639273744990215), 228 (1608639273744990216), 229 (1608639273744990217), ... (20 adds)]} 0 0
[junit4] 2> 4307677 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=2866
[junit4] 2> 4307678 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[240 (1608639273967288320), 241 (1608639273967288321), 242 (1608639273967288322), 243 (1608639273968336896), 244 (1608639273968336897), 245 (1608639273968336898), 246 (1608639273968336899), 247 (1608639273968336900), 248 (1608639273968336901), 249 (1608639273968336902), ... (20 adds)]} 0 0
[junit4] 2> 4307890 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=3078
[junit4] 2> 4307952 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[260 (1608639274190635008), 261 (1608639274190635009), 262 (1608639274190635010), 263 (1608639274190635011), 264 (1608639274190635012), 265 (1608639274190635013), 266 (1608639274190635014), 267 (1608639274190635015), 268 (1608639274200072192), 269 (1608639274200072193), ... (20 adds)]} 0 62
[junit4] 2> 4307960 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4307960 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4307970 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9abf4ba[collection1] realtime]
[junit4] 2> 4307971 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4308009 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=3197
[junit4] 2> 4308010 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[280 (1608639274315415552), 281 (1608639274316464128), 282 (1608639274316464129), 283 (1608639274316464130), 284 (1608639274316464131), 285 (1608639274316464132), 286 (1608639274316464133), 287 (1608639274316464134), 288 (1608639274316464135), 289 (1608639274316464136), ... (20 adds)]} 0 1
[junit4] 2> 4308220 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=3408
[junit4] 2> 4308221 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[300 (1608639274536665088), 301 (1608639274536665089), 302 (1608639274536665090), 303 (1608639274536665091), 304 (1608639274536665092), 305 (1608639274536665093), 306 (1608639274536665094), 307 (1608639274536665095), 308 (1608639274536665096), 309 (1608639274536665097), ... (20 adds)]} 0 0
[junit4] 2> 4308423 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=3612
[junit4] 2> 4308424 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[320 (1608639274749526016), 321 (1608639274749526017), 322 (1608639274750574592), 323 (1608639274750574593), 324 (1608639274750574594), 325 (1608639274750574595), 326 (1608639274750574596), 327 (1608639274750574597), 328 (1608639274750574598), 329 (1608639274750574599), ... (20 adds)]} 0 0
[junit4] 2> 4308650 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=3838
[junit4] 2> 4308651 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[340 (1608639274987552768), 341 (1608639274987552769), 342 (1608639274987552770), 343 (1608639274987552771), 344 (1608639274987552772), 345 (1608639274987552773), 346 (1608639274987552774), 347 (1608639274988601344), 348 (1608639274988601345), 349 (1608639274988601346), ... (20 adds)]} 0 0
[junit4] 2> 4308866 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=4055
[junit4] 2> 4308867 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[360 (1608639275215093760), 361 (1608639275215093761), 362 (1608639275215093762), 363 (1608639275215093763), 364 (1608639275215093764), 365 (1608639275215093765), 366 (1608639275215093766), 367 (1608639275215093767), 368 (1608639275215093768), 369 (1608639275215093769), ... (20 adds)]} 0 0
[junit4] 2> 4309072 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=4261
[junit4] 2> 4309073 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[380 (1608639275431100416), 381 (1608639275431100417), 382 (1608639275431100418), 383 (1608639275431100419), 384 (1608639275431100420), 385 (1608639275431100421), 386 (1608639275431100422), 387 (1608639275431100423), 388 (1608639275431100424), 389 (1608639275431100425), ... (20 adds)]} 0 0
[junit4] 2> 4309275 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=4464
[junit4] 2> 4309277 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[400 (1608639275643961344), 401 (1608639275643961345), 402 (1608639275643961346), 403 (1608639275643961347), 404 (1608639275643961348), 405 (1608639275643961349), 406 (1608639275643961350), 407 (1608639275643961351), 408 (1608639275643961352), 409 (1608639275643961353), ... (20 adds)]} 0 1
[junit4] 2> 4309278 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4309278 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4309281 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@d70777f[collection1] realtime]
[junit4] 2> 4309281 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4309327 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=4516
[junit4] 2> 4309329 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[420 (1608639275697438720), 421 (1608639275698487296), 422 (1608639275698487297), 423 (1608639275698487298), 424 (1608639275698487299), 425 (1608639275698487300), 426 (1608639275698487301), 427 (1608639275699535872), 428 (1608639275699535873), 429 (1608639275699535874), ... (20 adds)]} 0 1
[junit4] 2> 4309543 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=4732
[junit4] 2> 4309544 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[440 (1608639275924979712), 441 (1608639275924979713), 442 (1608639275924979714), 443 (1608639275924979715), 444 (1608639275924979716), 445 (1608639275924979717), 446 (1608639275924979718), 447 (1608639275924979719), 448 (1608639275924979720), 449 (1608639275924979721), ... (20 adds)]} 0 0
[junit4] 2> 4309756 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=4944
[junit4] 2> 4309757 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[460 (1608639276147277824), 461 (1608639276147277825), 462 (1608639276147277826), 463 (1608639276147277827), 464 (1608639276148326400), 465 (1608639276148326401), 466 (1608639276148326402), 467 (1608639276148326403), 468 (1608639276148326404), 469 (1608639276148326405), ... (20 adds)]} 0 0
[junit4] 2> 4309966 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=5154
[junit4] 2> 4309967 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[480 (1608639276367478784), 481 (1608639276367478785), 482 (1608639276367478786), 483 (1608639276367478787), 484 (1608639276367478788), 485 (1608639276367478789), 486 (1608639276367478790), 487 (1608639276367478791), 488 (1608639276367478792), 489 (1608639276367478793), ... (20 adds)]} 0 0
[junit4] 2> 4310174 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=5363
[junit4] 2> 4310175 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1608639276585582592), 501 (1608639276585582593), 502 (1608639276586631168), 503 (1608639276586631169), 504 (1608639276586631170), 505 (1608639276586631171), 506 (1608639276586631172), 507 (1608639276586631173), 508 (1608639276586631174), 509 (1608639276586631175), ... (20 adds)]} 0 0
[junit4] 2> 4310376 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=5565
[junit4] 2> 4310377 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[520 (1608639276798443520), 521 (1608639276798443521), 522 (1608639276798443522), 523 (1608639276798443523), 524 (1608639276798443524), 525 (1608639276798443525), 526 (1608639276798443526), 527 (1608639276798443527), 528 (1608639276798443528), 529 (1608639276798443529), ... (20 adds)]} 0 0
[junit4] 2> 4310581 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=5770
[junit4] 2> 4310583 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[540 (1608639277013401600), 541 (1608639277013401601), 542 (1608639277013401602), 543 (1608639277013401603), 544 (1608639277013401604), 545 (1608639277013401605), 546 (1608639277013401606), 547 (1608639277013401607), 548 (1608639277013401608), 549 (1608639277013401609), ... (20 adds)]} 0 1
[junit4] 2> 4310590 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4310590 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4310593 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8b9326f[collection1] realtime]
[junit4] 2> 4310594 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4310633 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=5822
[junit4] 2> 4310635 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[560 (1608639277066878976), 561 (1608639277067927552), 562 (1608639277067927553), 563 (1608639277067927554), 564 (1608639277067927555), 565 (1608639277067927556), 566 (1608639277067927557), 567 (1608639277067927558), 568 (1608639277067927559), 569 (1608639277067927560), ... (20 adds)]} 0 1
[junit4] 2> 4310863 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=6051
[junit4] 2> 4310864 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[580 (1608639277308051456), 581 (1608639277308051457), 582 (1608639277308051458), 583 (1608639277308051459), 584 (1608639277308051460), 585 (1608639277308051461), 586 (1608639277308051462), 587 (1608639277308051463), 588 (1608639277308051464), 589 (1608639277308051465), ... (20 adds)]} 0 0
[junit4] 2> 4311065 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=6253
[junit4] 2> 4311066 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[600 (1608639277519863808), 601 (1608639277519863809), 602 (1608639277519863810), 603 (1608639277519863811), 604 (1608639277519863812), 605 (1608639277519863813), 606 (1608639277519863814), 607 (1608639277519863815), 608 (1608639277519863816), 609 (1608639277519863817), ... (20 adds)]} 0 0
[junit4] 2> 4311267 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=6455
[junit4] 2> 4311268 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[620 (1608639277731676160), 621 (1608639277731676161), 622 (1608639277731676162), 623 (1608639277731676163), 624 (1608639277731676164), 625 (1608639277731676165), 626 (1608639277731676166), 627 (1608639277731676167), 628 (1608639277732724736), 629 (1608639277732724737), ... (20 adds)]} 0 0
[junit4] 2> 4311469 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=6657
[junit4] 2> 4311470 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[640 (1608639277943488512), 641 (1608639277943488513), 642 (1608639277944537088), 643 (1608639277944537089), 644 (1608639277944537090), 645 (1608639277944537091), 646 (1608639277944537092), 647 (1608639277944537093), 648 (1608639277944537094), 649 (1608639277944537095), ... (20 adds)]} 0 0
[junit4] 2> 4311683 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=6872
[junit4] 2> 4311684 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[660 (1608639278167883776), 661 (1608639278168932352), 662 (1608639278168932353), 663 (1608639278168932354), 664 (1608639278168932355), 665 (1608639278168932356), 666 (1608639278168932357), 667 (1608639278168932358), 668 (1608639278168932359), 669 (1608639278168932360), ... (20 adds)]} 0 0
[junit4] 2> 4311900 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=7088
[junit4] 2> 4311915 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[680 (1608639278395424768), 681 (1608639278395424769), 682 (1608639278395424770), 683 (1608639278395424771), 684 (1608639278395424772), 685 (1608639278395424773), 686 (1608639278395424774), 687 (1608639278396473344), 688 (1608639278410104832), 689 (1608639278410104833), ... (20 adds)]} 0 14
[junit4] 2> 4311916 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4311916 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4311971 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=7160
[junit4] 2> 4311973 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[700 (1608639278470922240), 701 (1608639278471970816), 702 (1608639278471970817), 703 (1608639278471970818), 704 (1608639278471970819), 705 (1608639278471970820), 706 (1608639278471970821), 707 (1608639278471970822), 708 (1608639278471970823), 709 (1608639278471970824), ... (20 adds)]} 0 1
[junit4] 2> 4311974 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@24b15eed[collection1] realtime]
[junit4] 2> 4311974 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4312207 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=7396
[junit4] 2> 4312208 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[720 (1608639278718386176), 721 (1608639278718386177), 722 (1608639278718386178), 723 (1608639278718386179), 724 (1608639278718386180), 725 (1608639278718386181), 726 (1608639278718386182), 727 (1608639278718386183), 728 (1608639278718386184), 729 (1608639278718386185), ... (20 adds)]} 0 0
[junit4] 2> 4312431 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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> 4312432 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[740 (1608639278952218624), 741 (1608639278952218625), 742 (1608639278952218626), 743 (1608639278953267200), 744 (1608639278953267201), 745 (1608639278953267202), 746 (1608639278953267203), 747 (1608639278953267204), 748 (1608639278953267205), 749 (1608639278953267206), ... (20 adds)]} 0 0
[junit4] 2> 4312650 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=7839
[junit4] 2> 4312652 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[760 (1608639279182905344), 761 (1608639279182905345), 762 (1608639279182905346), 763 (1608639279182905347), 764 (1608639279182905348), 765 (1608639279182905349), 766 (1608639279182905350), 767 (1608639279182905351), 768 (1608639279182905352), 769 (1608639279182905353), ... (20 adds)]} 0 0
[junit4] 2> 4312861 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=8050
[junit4] 2> 4312863 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[780 (1608639279404154880), 781 (1608639279404154881), 782 (1608639279404154882), 783 (1608639279404154883), 784 (1608639279404154884), 785 (1608639279404154885), 786 (1608639279404154886), 787 (1608639279404154887), 788 (1608639279404154888), 789 (1608639279404154889), ... (20 adds)]} 0 0
[junit4] 2> 4313066 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=8255
[junit4] 2> 4313067 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[800 (1608639279618064384), 801 (1608639279618064385), 802 (1608639279618064386), 803 (1608639279619112960), 804 (1608639279619112961), 805 (1608639279619112962), 806 (1608639279619112963), 807 (1608639279619112964), 808 (1608639279619112965), 809 (1608639279619112966), ... (20 adds)]} 0 0
[junit4] 2> 4313280 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=8469
[junit4] 2> 4313283 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[820 (1608639279842459648), 821 (1608639279843508224), 822 (1608639279843508225), 823 (1608639279843508226), 824 (1608639279843508227), 825 (1608639279843508228), 826 (1608639279843508229), 827 (1608639279843508230), 828 (1608639279845605376), 829 (1608639279845605377), ... (20 adds)]} 0 2
[junit4] 2> 4313284 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4313284 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4313288 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a926a02[collection1] realtime]
[junit4] 2> 4313288 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4313334 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=8522
[junit4] 2> 4313335 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[840 (1608639279899082752), 841 (1608639279900131328), 842 (1608639279900131329), 843 (1608639279900131330), 844 (1608639279900131331), 845 (1608639279900131332), 846 (1608639279900131333), 847 (1608639279900131334), 848 (1608639279900131335), 849 (1608639279900131336), ... (20 adds)]} 0 1
[junit4] 2> 4313536 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=8725
[junit4] 2> 4313537 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[860 (1608639280110895104), 861 (1608639280111943680), 862 (1608639280111943681), 863 (1608639280111943682), 864 (1608639280111943683), 865 (1608639280111943684), 866 (1608639280111943685), 867 (1608639280111943686), 868 (1608639280111943687), 869 (1608639280111943688), ... (20 adds)]} 0 0
[junit4] 2> 4313738 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=8927
[junit4] 2> 4313739 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[880 (1608639280322707456), 881 (1608639280323756032), 882 (1608639280323756033), 883 (1608639280323756034), 884 (1608639280323756035), 885 (1608639280323756036), 886 (1608639280323756037), 887 (1608639280323756038), 888 (1608639280323756039), 889 (1608639280323756040), ... (20 adds)]} 0 0
[junit4] 2> 4313941 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=9130
[junit4] 2> 4313942 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[900 (1608639280536616960), 901 (1608639280536616961), 902 (1608639280536616962), 903 (1608639280536616963), 904 (1608639280536616964), 905 (1608639280536616965), 906 (1608639280536616966), 907 (1608639280536616967), 908 (1608639280536616968), 909 (1608639280536616969), ... (20 adds)]} 0 0
[junit4] 2> 4314151 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=9340
[junit4] 2> 4314152 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[920 (1608639280755769344), 921 (1608639280756817920), 922 (1608639280756817921), 923 (1608639280756817922), 924 (1608639280756817923), 925 (1608639280756817924), 926 (1608639280756817925), 927 (1608639280756817926), 928 (1608639280756817927), 929 (1608639280756817928), ... (20 adds)]} 0 0
[junit4] 2> 4314357 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=9546
[junit4] 2> 4314358 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[940 (1608639280972824576), 941 (1608639280972824577), 942 (1608639280972824578), 943 (1608639280972824579), 944 (1608639280972824580), 945 (1608639280972824581), 946 (1608639280972824582), 947 (1608639280972824583), 948 (1608639280972824584), 949 (1608639280972824585), ... (20 adds)]} 0 0
[junit4] 2> 4314573 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=9761
[junit4] 2> 4314574 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[960 (1608639281198268416), 961 (1608639281198268417), 962 (1608639281198268418), 963 (1608639281198268419), 964 (1608639281198268420), 965 (1608639281198268421), 966 (1608639281198268422), 967 (1608639281198268423), 968 (1608639281198268424), 969 (1608639281198268425), ... (20 adds)]} 0 0
[junit4] 2> 4314575 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4314575 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4314590 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c6348ef[collection1] realtime]
[junit4] 2> 4314591 INFO (commitScheduler-11573-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4314637 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=9826
[junit4] 2> 4314639 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[980 (1608639281266425856), 981 (1608639281266425857), 982 (1608639281267474432), 983 (1608639281267474433), 984 (1608639281267474434), 985 (1608639281267474435), 986 (1608639281267474436), 987 (1608639281267474437), 988 (1608639281267474438), 989 (1608639281267474439), ... (20 adds)]} 0 1
[junit4] 2> 4314861 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=10050
[junit4] 2> 4314861 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4314861 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@65bb4433 commitCommandVersion:0
[junit4] 2> 4314864 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a0e3ac3[collection1] main]
[junit4] 2> 4314898 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 4314899 INFO (searcherExecutor-11570-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a0e3ac3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):c140) Uninverting(_1(7.5.0):c140) Uninverting(_2(7.5.0):c140) Uninverting(_3(7.5.0):c140) Uninverting(_4(7.5.0):c140) Uninverting(_5(7.5.0):c140) Uninverting(_6(7.5.0):c140) Uninverting(_7(7.5.0):c20)))}
[junit4] 2> 4314954 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] 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=10143
[junit4] 2> 4314958 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=975879506
[junit4] 2> 4314970 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 4314971 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b04e783: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@23f15d75
[junit4] 2> 4315010 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 4315011 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@79aa9c15: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3637d942
[junit4] 2> 4315053 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 4315053 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6386733c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@66cb95cb
[junit4] 2> 4315087 INFO (coreCloseExecutor-11575-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4326b6cf
[junit4] 2> 4315087 INFO (coreCloseExecutor-11575-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4326b6cf
[junit4] 2> 4315087 INFO (coreCloseExecutor-11575-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5d8dc130: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1d8fa725
[junit4] 2> 4315198 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4315380 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4315437 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 4316038 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4317543 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4317543 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4317617 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4317653 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4317653 INFO (TEST-MaxSizeAutoCommitTest.endToEndTest-seed#[3E85A1170572129]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ce546f1
[junit4] 2> 4317665 INFO (coreLoadExecutor-11585-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 4317769 INFO (coreLoadExecutor-11585-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 4317827 INFO (coreLoadExecutor-11585-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 4318414 INFO (coreLoadExecutor-11585-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4318433 INFO (coreLoadExecutor-11585-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-file
[...truncated too long message...]
operty disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null218553652
[copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null218553652
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null218553652
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 19ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 194 minutes 19 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)