Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4199/ Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseParallelGC --illegal-access=deny
2 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testOutOfOrderDBQWithInPlaceUpdates Error Message: Can not find doc 1 in http://127.0.0.1:54727/solr Stack Trace: java.lang.AssertionError: Can not find doc 1 in http://127.0.0.1:54727/solr at __randomizedtesting.SeedInfo.seed([2972A68EB091A87D:AFB35E63EFC07E9D]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:861) at org.apache.solr.cloud.TestTlogReplica.testOutOfOrderDBQWithInPlaceUpdates(TestTlogReplica.java:664) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth Error Message: expected:<200> but was:<403> Stack Trace: java.lang.AssertionError: expected:<200> but was:<403> at __randomizedtesting.SeedInfo.seed([2972A68EB091A87D:951CD09C14C22B07]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.security.BasicAuthIntegrationTest.executeCommand(BasicAuthIntegrationTest.java:242) at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:154) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 11502 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> 150044 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/init-core-data-001 [junit4] 2> 150044 WARN (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=56 numCloses=56 [junit4] 2> 150044 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 150045 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth [junit4] 2> 150045 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001 [junit4] 2> 150045 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 150045 INFO (Thread-260) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 150045 INFO (Thread-260) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 150048 ERROR (Thread-260) [ ] 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> 150146 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:54724 [junit4] 2> 150166 INFO (jetty-launcher-204-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 150166 INFO (jetty-launcher-204-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 150169 INFO (jetty-launcher-204-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42a35f56{/solr,null,AVAILABLE} [junit4] 2> 150169 INFO (jetty-launcher-204-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@547b524c{/solr,null,AVAILABLE} [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5a5b0ca2{HTTP/1.1,[http/1.1]}{127.0.0.1:54727} [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@515d386b{HTTP/1.1,[http/1.1]}{127.0.0.1:54728} [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.e.j.s.Server Started @151929ms [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.e.j.s.Server Started @151929ms [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54728} [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54727} [junit4] 2> 150176 ERROR (jetty-launcher-204-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 150176 ERROR (jetty-launcher-204-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 150176 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-30T22:45:31.429754Z [junit4] 2> 150176 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-30T22:45:31.429744Z [junit4] 2> 150179 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 150179 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 150185 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54724/solr [junit4] 2> 150185 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54724/solr [junit4] 2> 150344 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 150344 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 150346 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54728_solr [junit4] 2> 150347 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54727_solr [junit4] 2> 150348 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98750400515276805-127.0.0.1:54728_solr-n_0000000000) starting [junit4] 2> 150349 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 150349 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 150386 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54728_solr [junit4] 2> 150388 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 150388 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 150403 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 150410 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 150411 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 150411 INFO (jetty-launcher-204-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/. [junit4] 2> 150426 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 150438 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 150438 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 150439 INFO (jetty-launcher-204-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/. [junit4] 2> 150464 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 150465 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54724/solr ready [junit4] 2> 150469 INFO (SUITE-TestTlogReplica-seed#[2972A68EB091A87D]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 150474 INFO (qtp1299586935-1195) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 150478 INFO (qtp1299586935-1195) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 150489 INFO (TEST-TestTlogReplica.testDeleteById-seed#[2972A68EB091A87D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteById [junit4] 2> 150491 INFO (qtp1299586935-1197) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_delete_by_id&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 150493 INFO (OverseerThreadFactory-635-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection tlog_replica_test_delete_by_id [junit4] 2> 150608 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_delete_by_id", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_delete_by_id_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54728/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 150612 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_delete_by_id", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_delete_by_id_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54727/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 150727 INFO (qtp1299586935-1199) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_delete_by_id_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_delete_by_id&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 150727 INFO (qtp2024837981-1203) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_delete_by_id_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_delete_by_id&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 150727 INFO (qtp1299586935-1199) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 150727 INFO (qtp2024837981-1203) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 150849 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 150849 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 150849 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 150850 INFO (zkCallback-215-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 151755 INFO (qtp2024837981-1203) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 151755 INFO (qtp1299586935-1199) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 151758 INFO (qtp1299586935-1199) [ ] o.a.s.s.IndexSchema [tlog_replica_test_delete_by_id_shard1_replica_t2] Schema name=minimal [junit4] 2> 151758 INFO (qtp2024837981-1203) [ ] o.a.s.s.IndexSchema [tlog_replica_test_delete_by_id_shard1_replica_t1] Schema name=minimal [junit4] 2> 151764 INFO (qtp2024837981-1203) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 151764 INFO (qtp2024837981-1203) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_delete_by_id_shard1_replica_t1' using configuration from collection tlog_replica_test_delete_by_id, trusted=true [junit4] 2> 151764 INFO (qtp1299586935-1199) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 151764 INFO (qtp1299586935-1199) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_delete_by_id_shard1_replica_t2' using configuration from collection tlog_replica_test_delete_by_id, trusted=true [junit4] 2> 151764 INFO (qtp1299586935-1199) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.core.tlog_replica_test_delete_by_id.shard1.replica_t2' (registry 'solr.core.tlog_replica_test_delete_by_id.shard1.replica_t2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 151764 INFO (qtp2024837981-1203) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.core.tlog_replica_test_delete_by_id.shard1.replica_t1' (registry 'solr.core.tlog_replica_test_delete_by_id.shard1.replica_t1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 151765 INFO (qtp1299586935-1199) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 151765 INFO (qtp2024837981-1203) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 151765 INFO (qtp1299586935-1199) [ ] o.a.s.c.SolrCore [[tlog_replica_test_delete_by_id_shard1_replica_t2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/tlog_replica_test_delete_by_id_shard1_replica_t2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/./tlog_replica_test_delete_by_id_shard1_replica_t2/data/] [junit4] 2> 151765 INFO (qtp2024837981-1203) [ ] o.a.s.c.SolrCore [[tlog_replica_test_delete_by_id_shard1_replica_t1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/tlog_replica_test_delete_by_id_shard1_replica_t1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/./tlog_replica_test_delete_by_id_shard1_replica_t1/data/] [junit4] 2> 151848 INFO (qtp2024837981-1203) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 151848 INFO (qtp2024837981-1203) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 151850 INFO (qtp2024837981-1203) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 151850 INFO (qtp2024837981-1203) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 151855 INFO (qtp1299586935-1199) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 151855 INFO (qtp1299586935-1199) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 151857 INFO (qtp1299586935-1199) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 151858 INFO (qtp1299586935-1199) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 151861 INFO (qtp2024837981-1203) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d8ab1d1[tlog_replica_test_delete_by_id_shard1_replica_t1] main] [junit4] 2> 151864 INFO (qtp2024837981-1203) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 151864 INFO (qtp1299586935-1199) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4464d982[tlog_replica_test_delete_by_id_shard1_replica_t2] main] [junit4] 2> 151864 INFO (qtp2024837981-1203) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 151865 INFO (qtp2024837981-1203) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 151866 INFO (qtp2024837981-1203) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580006410151788544 [junit4] 2> 151868 INFO (searcherExecutor-639-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t1] Registered new searcher Searcher@5d8ab1d1[tlog_replica_test_delete_by_id_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 151868 INFO (qtp1299586935-1199) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 151869 INFO (qtp1299586935-1199) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 151871 INFO (qtp1299586935-1199) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 151873 INFO (searcherExecutor-640-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t2] Registered new searcher Searcher@4464d982[tlog_replica_test_delete_by_id_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 151873 INFO (qtp1299586935-1199) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580006410159128576 [junit4] 2> 151878 INFO (qtp2024837981-1203) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 151879 INFO (qtp2024837981-1203) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 151879 INFO (qtp2024837981-1203) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/ [junit4] 2> 151879 INFO (qtp2024837981-1203) [ ] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_delete_by_id_shard1_replica_t1 url=http://127.0.0.1:54728/solr START replicas=[http://127.0.0.1:54727/solr/tlog_replica_test_delete_by_id_shard1_replica_t2/] nUpdates=100 [junit4] 2> 151882 INFO (qtp1299586935-1202) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 151987 INFO (zkCallback-215-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 151988 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 151988 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 151988 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152185 INFO (qtp2024837981-1203) [ ] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_delete_by_id_shard1_replica_t1 url=http://127.0.0.1:54728/solr DONE. We have no versions. sync failed. [junit4] 2> 152185 INFO (qtp2024837981-1203) [ ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 152185 INFO (qtp2024837981-1203) [ ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 152185 INFO (qtp2024837981-1203) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 152186 INFO (qtp2024837981-1203) [ ] o.a.s.c.ZkController tlog_replica_test_delete_by_id_shard1_replica_t1 stopping background replication from leader [junit4] 2> 152191 INFO (qtp2024837981-1203) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/ shard1 [junit4] 2> 152300 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152300 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152300 INFO (zkCallback-215-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152300 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152346 INFO (qtp2024837981-1203) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 152348 INFO (qtp2024837981-1203) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_delete_by_id_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_delete_by_id&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=1620 [junit4] 2> 152455 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152455 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152455 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152455 INFO (zkCallback-215-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 152888 INFO (qtp1299586935-1199) [ ] o.a.s.c.ZkController tlog_replica_test_delete_by_id_shard1_replica_t2 starting background replication from leader [junit4] 2> 152888 INFO (qtp1299586935-1199) [ ] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 152891 INFO (qtp1299586935-1199) [ ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 152891 INFO (qtp1299586935-1199) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 152892 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/ [junit4] 2> 152893 INFO (qtp1299586935-1199) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_delete_by_id_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_delete_by_id&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2167 [junit4] 2> 152894 INFO (qtp2024837981-1205) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 152894 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 152894 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 152894 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 152894 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 152895 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 152898 INFO (indexFetcher-650-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7dd4735[tlog_replica_test_delete_by_id_shard1_replica_t2] main] [junit4] 2> 152900 INFO (searcherExecutor-640-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t2] Registered new searcher Searcher@7dd4735[tlog_replica_test_delete_by_id_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 152902 INFO (qtp1299586935-1197) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 153006 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 153006 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 153006 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 153006 INFO (zkCallback-215-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 153911 INFO (qtp1299586935-1197) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_delete_by_id&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2} status=0 QTime=3420 [junit4] 2> 153931 INFO (qtp1299586935-1193) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&_version_=-1580006412304515072&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&wt=javabin&version=2}{deleteByQuery=*:* (-1580006412304515072)} 0 3 [junit4] 2> 153938 INFO (qtp1299586935-1195) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 153939 INFO (qtp2024837981-1209) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1580006412325486592,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 153939 INFO (qtp2024837981-1209) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 153944 INFO (qtp2024837981-1209) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 153944 INFO (qtp2024837981-1209) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 4 [junit4] 2> 153945 INFO (qtp2024837981-1206) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/update params={_stateVer_=tlog_replica_test_delete_by_id:6&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1580006412304515072),commit=} 0 25 [junit4] 2> 153960 INFO (qtp1299586935-1198) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&wt=javabin&version=2}{add=[1 (1580006412333875200)]} 0 0 [junit4] 2> 153961 INFO (qtp2024837981-1196) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/update params={_stateVer_=tlog_replica_test_delete_by_id:6&wt=javabin&version=2}{add=[1 (1580006412333875200)]} 0 13 [junit4] 2> 153963 INFO (qtp1299586935-1200) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 153963 INFO (qtp2024837981-1204) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1580006412350652416,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 153964 INFO (qtp2024837981-1204) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4adfa95a commitCommandVersion:1580006412350652416 [junit4] 2> 153990 INFO (qtp2024837981-1204) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@52d6fd98[tlog_replica_test_delete_by_id_shard1_replica_t1] main] [junit4] 2> 154001 INFO (qtp2024837981-1204) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 154002 INFO (searcherExecutor-639-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t1] Registered new searcher Searcher@52d6fd98[tlog_replica_test_delete_by_id_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 154002 INFO (qtp2024837981-1204) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 39 [junit4] 2> 154003 INFO (qtp2024837981-1203) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/update params={_stateVer_=tlog_replica_test_delete_by_id:6&commit=true&wt=javabin&version=2}{commit=} 0 41 [junit4] 2> 154008 INFO (qtp2024837981-1208) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 154011 INFO (qtp1299586935-1199) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154122 INFO (qtp1299586935-1197) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154226 INFO (qtp1299586935-1193) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154329 INFO (qtp1299586935-1195) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154435 INFO (qtp1299586935-1198) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154506 INFO (OverseerCollectionConfigSetProcessor-98750400515276805-127.0.0.1:54728_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> 154544 INFO (qtp1299586935-1200) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154646 INFO (qtp1299586935-1202) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154752 INFO (qtp1299586935-1199) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154854 INFO (qtp1299586935-1197) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 154956 INFO (qtp1299586935-1193) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155064 INFO (qtp1299586935-1195) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155165 INFO (qtp1299586935-1198) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155266 INFO (qtp1299586935-1200) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155374 INFO (qtp1299586935-1202) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155485 INFO (qtp1299586935-1199) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155590 INFO (qtp1299586935-1197) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155692 INFO (qtp1299586935-1197) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155794 INFO (qtp1299586935-1195) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155896 INFO (qtp1299586935-1198) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 155899 INFO (qtp2024837981-1206) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 155899 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 155899 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 1506811535217 [junit4] 2> 155899 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 155899 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 155899 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 155901 INFO (qtp2024837981-1206) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 155902 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 12 [junit4] 2> 155903 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@20db0909 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a1bd4fc) [junit4] 2> 155904 INFO (qtp2024837981-1203) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155906 INFO (qtp2024837981-1205) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155914 INFO (qtp2024837981-1208) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Direct_0.dvdd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155915 INFO (qtp2024837981-1209) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155918 INFO (qtp2024837981-1196) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155919 INFO (qtp2024837981-1196) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Memory_0.ram&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155921 INFO (qtp2024837981-1206) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155922 INFO (qtp2024837981-1203) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155923 INFO (qtp2024837981-1205) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155924 INFO (qtp2024837981-1208) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Direct_0.dvdm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155925 INFO (qtp2024837981-1209) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155927 INFO (qtp2024837981-1209) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 155930 INFO (indexFetcher-650-thread-1) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1902) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@20db0909 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a1bd4fc) [junit4] 2> 155938 INFO (indexFetcher-650-thread-1) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 155940 INFO (indexFetcher-650-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c4acaa5[tlog_replica_test_delete_by_id_shard1_replica_t2] main] [junit4] 2> 155942 INFO (searcherExecutor-640-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t2] Registered new searcher Searcher@3c4acaa5[tlog_replica_test_delete_by_id_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 155999 INFO (qtp1299586935-1200) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 156005 INFO (qtp1299586935-1202) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:54728/solr/tlog_replica_test_delete_by_id_shard1_replica_t1/&wt=javabin&version=2}{delete=[1 (-1580006414488698880)]} 0 1 [junit4] 2> 156006 INFO (qtp2024837981-1196) [ ] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_delete_by_id_shard1_replica_t1] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1 (-1580006414488698880)]} 0 3 [junit4] 2> 156013 INFO (qtp1299586935-1193) [ ] o.a.s.c.S.Request [tlog_replica_test_delete_by_id_shard1_replica_t2] webapp=/solr path=/get params={distrib=false&qt=/get&id=1&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 156014 INFO (TEST-TestTlogReplica.testDeleteById-seed#[2972A68EB091A87D]) [ ] o.a.s.c.TestTlogReplica tearDown deleting collection [junit4] 2> 156014 INFO (qtp1299586935-1197) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=tlog_replica_test_delete_by_id&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 156022 INFO (OverseerThreadFactory-635-thread-2) [ ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 156025 INFO (qtp2024837981-1206) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_delete_by_id.shard1.replica_t1, tag=null [junit4] 2> 156025 INFO (qtp2024837981-1206) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@233fde26: rootName = solr_54728, domain = solr.core.tlog_replica_test_delete_by_id.shard1.replica_t1, service url = null, agent id = null] for registry solr.core.tlog_replica_test_delete_by_id.shard1.replica_t1 / com.codahale.metrics.MetricRegistry@5cd16af [junit4] 2> 156030 INFO (qtp1299586935-1195) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_delete_by_id.shard1.replica_t2, tag=null [junit4] 2> 156031 INFO (qtp1299586935-1195) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@312c2a6a: rootName = solr_54727, domain = solr.core.tlog_replica_test_delete_by_id.shard1.replica_t2, service url = null, agent id = null] for registry solr.core.tlog_replica_test_delete_by_id.shard1.replica_t2 / com.codahale.metrics.MetricRegistry@37cab4c0 [junit4] 2> 156071 INFO (qtp2024837981-1206) [ ] o.a.s.c.ZkController tlog_replica_test_delete_by_id_shard1_replica_t1 stopping background replication from leader [junit4] 2> 156071 INFO (qtp2024837981-1206) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t1] CLOSING SolrCore org.apache.solr.core.SolrCore@6166a00a [junit4] 2> 156071 INFO (qtp2024837981-1206) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_delete_by_id.shard1.replica_t1, tag=1634115594 [junit4] 2> 156071 INFO (qtp2024837981-1206) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_delete_by_id.shard1.leader, tag=1634115594 [junit4] 2> 156072 INFO (qtp2024837981-1206) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 156072 INFO (qtp2024837981-1206) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4adfa95a commitCommandVersion:0 [junit4] 2> 156075 INFO (qtp1299586935-1195) [ ] o.a.s.c.ZkController tlog_replica_test_delete_by_id_shard1_replica_t2 stopping background replication from leader [junit4] 2> 156084 INFO (qtp1299586935-1195) [ ] o.a.s.c.SolrCore [tlog_replica_test_delete_by_id_shard1_replica_t2] CLOSING SolrCore org.apache.solr.core.SolrCore@7ec9626b [junit4] 2> 156084 INFO (qtp1299586935-1195) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_delete_by_id.shard1.replica_t2, tag=2127127147 [junit4] 2> 156084 INFO (qtp1299586935-1195) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_delete_by_id.shard1.leader, tag=2127127147 [junit4] 2> 156084 INFO (qtp1299586935-1195) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 156084 INFO (qtp1299586935-1195) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@592ca2eb commitCommandVersion:0 [junit4] 2> 156092 WARN (zkCallback-216-thread-2) [ ] o.a.s.c.LeaderElector [junit4] 2> org.apache.solr.common.SolrException: SolrCore not found:tlog_replica_test_delete_by_id_shard1_replica_t2 in [] [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:297) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 156093 INFO (qtp2024837981-1206) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=tlog_replica_test_delete_by_id_shard1_replica_t1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=68 [junit4] 2> 156097 INFO (zkCallback-222-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 156097 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 156097 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 156102 INFO (qtp1299586935-1195) [ ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 156105 INFO (qtp1299586935-1195) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=tlog_replica_test_delete_by_id_shard1_replica_t2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=75 [junit4] 2> 156221 INFO (zkCallback-222-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/tlog_replica_test_delete_by_id/state.json] for collection [tlog_replica_test_delete_by_id] has occurred - updating... (live nodes size: [2]) [junit4] 2> 156828 INFO (qtp1299586935-1197) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=tlog_replica_test_delete_by_id&action=DELETE&wt=javabin&version=2} status=0 QTime=813 [junit4] 2> 156828 INFO (TEST-TestTlogReplica.testDeleteById-seed#[2972A68EB091A87D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteById [junit4] 2> 156842 INFO (TEST-TestTlogReplica.testCreateDelete-seed#[2972A68EB091A87D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateDelete {seed=[2972A68EB091A87D:326257C24661504D]} [junit4] 2> 156843 INFO (qtp1299586935-1200) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_create_delete&nrtReplicas=0&action=CREATE&numShards=2&tlogReplicas=4&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 156846 INFO (OverseerThreadFactory-635-thread-3) [ ] o.a.s.c.CreateCollectionCmd Create collection tlog_replica_test_create_delete [junit4] 2> 156847 INFO (OverseerCollectionConfigSetProcessor-98750400515276805-127.0.0.1:54728_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> 156847 WARN (OverseerThreadFactory-635-thread-3) [ ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 4 on collection tlog_replica_test_create_delete is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 157066 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54727/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157071 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54728/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157075 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54727/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157080 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54728/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157084 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t8", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54727/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157093 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t10", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54728/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157097 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t12", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54727/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157102 INFO (OverseerStateUpdate-98750400515276805-127.0.0.1:54728_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t14", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:54728/solr", [junit4] 2> "type":"TLOG"} [junit4] 2> 157312 INFO (qtp2024837981-1203) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t2&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157312 INFO (qtp1299586935-1199) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t1&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157314 INFO (qtp1299586935-1193) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t4&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157315 INFO (qtp2024837981-1205) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t6&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157315 INFO (qtp1299586935-1198) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t8&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157315 INFO (qtp2024837981-1208) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t10&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157319 INFO (qtp1299586935-1195) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t12&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157321 INFO (qtp2024837981-1204) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node16&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t14&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 157457 INFO (zkCallback-216-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 157457 INFO (zkCallback-216-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 157457 INFO (zkCallback-215-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 157457 INFO (zkCallback-215-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 158337 INFO (qtp1299586935-1199) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158355 INFO (qtp1299586935-1193) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158356 INFO (qtp1299586935-1198) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158374 INFO (qtp1299586935-1195) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158376 INFO (qtp2024837981-1203) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158376 INFO (qtp2024837981-1205) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158379 INFO (qtp2024837981-1204) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158380 INFO (qtp2024837981-1208) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 158385 INFO (qtp2024837981-1204) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t14] Schema name=minimal [junit4] 2> 158385 INFO (qtp2024837981-1203) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t2] Schema name=minimal [junit4] 2> 158385 INFO (qtp2024837981-1205) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t6] Schema name=minimal [junit4] 2> 158392 INFO (qtp2024837981-1204) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158392 INFO (qtp2024837981-1204) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t14' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158392 INFO (qtp2024837981-1203) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158392 INFO (qtp2024837981-1203) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t2' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158392 INFO (qtp2024837981-1205) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158392 INFO (qtp2024837981-1205) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t6' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158392 INFO (qtp2024837981-1204) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.core.tlog_replica_test_create_delete.shard2.replica_t14' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t14') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158392 INFO (qtp2024837981-1203) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.core.tlog_replica_test_create_delete.shard1.replica_t2' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158393 INFO (qtp2024837981-1204) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158393 INFO (qtp2024837981-1203) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158393 INFO (qtp2024837981-1204) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t14] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/tlog_replica_test_create_delete_shard2_replica_t14], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard2_replica_t14/data/] [junit4] 2> 158393 INFO (qtp2024837981-1203) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/tlog_replica_test_create_delete_shard1_replica_t2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard1_replica_t2/data/] [junit4] 2> 158393 INFO (qtp2024837981-1205) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.core.tlog_replica_test_create_delete.shard1.replica_t6' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158393 INFO (qtp2024837981-1205) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158393 INFO (qtp2024837981-1205) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/tlog_replica_test_create_delete_shard1_replica_t6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard1_replica_t6/data/] [junit4] 2> 158396 INFO (qtp2024837981-1208) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t10] Schema name=minimal [junit4] 2> 158396 INFO (qtp1299586935-1198) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t8] Schema name=minimal [junit4] 2> 158397 INFO (qtp1299586935-1193) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t4] Schema name=minimal [junit4] 2> 158397 INFO (qtp1299586935-1198) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158397 INFO (qtp1299586935-1198) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t8' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158398 INFO (qtp2024837981-1208) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158398 INFO (qtp2024837981-1208) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t10' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158398 INFO (qtp1299586935-1198) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.core.tlog_replica_test_create_delete.shard2.replica_t8' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158398 INFO (qtp1299586935-1198) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158398 INFO (qtp1299586935-1198) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t8] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/tlog_replica_test_create_delete_shard2_replica_t8], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard2_replica_t8/data/] [junit4] 2> 158398 INFO (qtp2024837981-1208) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54728.solr.core.tlog_replica_test_create_delete.shard2.replica_t10' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t10') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158398 INFO (qtp2024837981-1208) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158398 INFO (qtp2024837981-1208) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t10] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/tlog_replica_test_create_delete_shard2_replica_t10], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard2_replica_t10/data/] [junit4] 2> 158399 INFO (qtp1299586935-1193) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158399 INFO (qtp1299586935-1193) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t4' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158399 INFO (qtp1299586935-1193) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.core.tlog_replica_test_create_delete.shard1.replica_t4' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158399 INFO (qtp1299586935-1193) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158399 INFO (qtp1299586935-1193) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/tlog_replica_test_create_delete_shard1_replica_t4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard1_replica_t4/data/] [junit4] 2> 158402 INFO (qtp1299586935-1199) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t1] Schema name=minimal [junit4] 2> 158402 INFO (qtp1299586935-1195) [ ] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t12] Schema name=minimal [junit4] 2> 158403 INFO (qtp1299586935-1195) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158403 INFO (qtp1299586935-1195) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t12' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158404 INFO (qtp1299586935-1195) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.core.tlog_replica_test_create_delete.shard2.replica_t12' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t12') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158404 INFO (qtp1299586935-1195) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158404 INFO (qtp1299586935-1195) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t12] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/tlog_replica_test_create_delete_shard2_replica_t12], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard2_replica_t12/data/] [junit4] 2> 158404 INFO (qtp1299586935-1199) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 158404 INFO (qtp1299586935-1199) [ ] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t1' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 158405 INFO (qtp1299586935-1199) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54727.solr.core.tlog_replica_test_create_delete.shard1.replica_t1' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f4a698 [junit4] 2> 158405 INFO (qtp1299586935-1199) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158405 INFO (qtp1299586935-1199) [ ] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/tlog_replica_test_create_delete_shard1_replica_t1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_2972A68EB091A87D-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard1_replica_t1/data/] [junit4] 2> 158634 INFO (qtp1299586935-1198) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158634 INFO (qtp1299586935-1198) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 158642 INFO (qtp1299586935-1198) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 158642 INFO (qtp1299586935-1198) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 158656 INFO (qtp1299586935-1199) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158657 INFO (qtp1299586935-1199) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 158660 INFO (qtp2024837981-1203) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158660 INFO (qtp2024837981-1203) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 158662 INFO (qtp1299586935-1199) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 158663 INFO (qtp1299586935-1199) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 158668 INFO (qtp2024837981-1203) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 158668 INFO (qtp2024837981-1203) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 158676 INFO (qtp2024837981-1204) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158676 INFO (qtp2024837981-1204) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 158676 INFO (qtp2024837981-1205) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158676 INFO (qtp2024837981-1205) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 158682 INFO (qtp2024837981-1208) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158682 INFO (qtp2024837981-1208) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 158683 INFO (qtp2024837981-1204) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 158683 INFO (qtp2024837981-1204) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 158683 INFO (qtp2024837981-1205) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 158683 INFO (qtp2024837981-1205) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 158686 INFO (qtp1299586935-1193) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 158687 INFO (qtp1299586935-1193) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNu [...truncated too long message...] d#[2972A68EB091A87D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52781 52781 [junit4] 2> 3093530 INFO (Thread-4919) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52781 52781 [junit4] 2> 3093902 WARN (Thread-4919) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 5 /solr/aliases.json [junit4] 2> 5 /solr/clusterprops.json [junit4] 2> 4 /solr/security.json [junit4] 2> 4 /solr/configs/conf [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 28 /solr/collections/authCollection/state.json [junit4] 2> 5 /solr/clusterstate.json [junit4] 2> 2 /solr/overseer_elect/election/98750592828571655-127.0.0.1:52784_solr-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/98750592828571657-127.0.0.1:52783_solr-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 5 /solr/collections [junit4] 2> 4 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_2972A68EB091A87D-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=336, maxMBSortInHeap=5.098394947335385, sim=RandomSimilarity(queryNorm=true): {}, locale=ca-FR, timezone=Asia/Kuching [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=332623648,total=528482304 [junit4] 2> NOTE: All tests run in this JVM: [PreAnalyzedUpdateProcessorTest, TestSchemaVersionResource, QueryElevationComponentTest, SyncSliceTest, VMParamsZkACLAndCredentialsProvidersTest, TestFaceting, DocValuesTest, WordBreakSolrSpellCheckerTest, ZkStateReaderTest, TestInitParams, SpatialRPTFieldTypeTest, TestJsonRequest, TestSizeLimitedDistributedMap, DistributedExpandComponentTest, OverseerRolesTest, TestConfigSets, SpatialHeatmapFacetsTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, OverseerTest, TestSubQueryTransformerCrossCore, ClusterStateUpdateTest, RAMDirectoryFactoryTest, DirectoryFactoryTest, TestOverriddenPrefixQueryForCustomFieldType, TestBackupRepositoryFactory, HdfsBasicDistributedZk2Test, HighlighterConfigTest, ReplaceNodeTest, TestLegacyFieldReuse, TestPhraseSuggestions, SuggesterWFSTTest, TestBinaryField, TestExtendedDismaxParser, TestDistributedSearch, TestDynamicFieldResource, ConfigureRecoveryStrategyTest, JvmMetricsTest, TestNumericTerms32, TestCollectionAPI, HLLUtilTest, LeaderElectionContextKeyTest, TestSolrCloudWithKerberosAlt, TestQuerySenderListener, BlockDirectoryTest, PrimUtilsTest, TestMaxScoreQueryParser, SortByFunctionTest, TestCollectionAPIs, AlternateDirectoryTest, HdfsDirectoryTest, TestRandomDVFaceting, DirectUpdateHandlerTest, SSLMigrationTest, StressHdfsTest, SolrIndexSplitterTest, AliasIntegrationTest, SolrTestCaseJ4Test, TestRestoreCore, TestCloudPseudoReturnFields, TestDeleteCollectionOnDownNodes, TestPHPSerializedResponseWriter, CustomCollectionTest, TemplateUpdateProcessorTest, UpdateLogTest, TestExceedMaxTermLength, HardAutoCommitTest, TestLRUStatsCache, RecoveryZkTest, HdfsCollectionsAPIDistributedZkTest, PeerSyncTest, TestCloudSearcherWarming, TestPullReplicaErrorHandling, TestObjectReleaseTracker, SolrCoreTest, DateRangeFieldTest, ResourceLoaderTest, TestReloadDeadlock, TestSort, DistribDocExpirationUpdateProcessorTest, TestConfigSetsAPIZkFailure, TestPayloadScoreQParserPlugin, TestFieldCacheWithThreads, UnloadDistributedZkTest, BadCopyFieldTest, TestJmxIntegration, TestJavabinTupleStreamParser, TestRawResponseWriter, HealthCheckHandlerTest, ImplicitSnitchTest, TestInitQParser, XsltUpdateRequestHandlerTest, TestExactSharedStatsCache, TestImplicitCoreProperties, TestSolrCloudWithHadoopAuthPlugin, TestCloudNestedDocsSort, TestLegacyTerms, TestCollationFieldDocValues, URLClassifyProcessorTest, SimpleFacetsTest, TestEmbeddedSolrServerAdminHandler, ChangedSchemaMergeTest, TestStressReorder, UninvertDocValuesMergePolicyTest, TestUnifiedSolrHighlighter, TestDFISimilarityFactory, TestUtils, RulesTest, TestRestManager, DistanceFunctionTest, TestPivotHelperCode, TestFieldSortValues, TestCustomDocTransformer, AnalysisErrorHandlingTest, TestRetrieveFieldsOptimizer, TestStressUserVersions, TestComplexPhraseQParserPlugin, TestRequestStatusCollectionAPI, AtomicUpdatesTest, TestSegmentSorting, CSVRequestHandlerTest, HdfsThreadLeakTest, TestCSVResponseWriter, TestSolrXml, TestFoldingMultitermQuery, TestSolrDeletionPolicy2, TestPerFieldSimilarityWithDefaultOverride, TestManagedResource, BitVectorTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestManagedSchemaThreadSafety, ZkCLITest, TestHighFrequencyDictionaryFactory, SimplePostToolTest, DistributedFacetPivotWhiteBoxTest, BinaryUpdateRequestHandlerTest, CursorMarkTest, TestSurroundQueryParser, OutputWriterTest, DeleteStatusTest, SolrCLIZkUtilsTest, SolrInfoBeanTest, FacetPivotSmallTest, ConnectionManagerTest, TestSortByMinMaxFunction, TestCollapseQParserPlugin, TestReplicationHandlerBackup, TestSchemaManager, MissingSegmentRecoveryTest, TestGeoJSONResponseWriter, CoreAdminCreateDiscoverTest, SolrMetricReporterTest, CloneFieldUpdateProcessorFactoryTest, TestConfigReload, BasicDistributedZkTest, SolrJmxReporterCloudTest, TestMiniSolrCloudClusterSSL, TestFieldCacheReopen, TestOmitPositions, TestDFRSimilarityFactory, ShardRoutingTest, DistributedSpellCheckComponentTest, TestFieldTypeResource, DistributedFacetPivotLargeTest, SoftAutoCommitTest, SmileWriterTest, TestMinMaxOnMultiValuedField, MetricsHandlerTest, MoveReplicaHDFSUlogDirTest, TestIndexingPerformance, JavabinLoaderTest, ScriptEngineTest, V2ApiIntegrationTest, DistributedQueryComponentOptimizationTest, TestCustomStream, RecoveryAfterSoftCommitTest, TestGroupingSearch, TimeZoneUtilsTest, MinimalSchemaTest, AutoScalingHandlerTest, TestAtomicUpdateErrorCases, SolrRequestParserTest, SuggesterTest, TestStressLucene, TestTrie, SpatialFilterTest, TestCSVLoader, NoCacheHeaderTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestWordDelimiterFilterFactory, QueryEqualityTest, DocValuesMultiTest, CacheHeaderTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, RegexBoostProcessorTest, ReturnFieldsTest, QueryParsingTest, JsonLoaderTest, SearchHandlerTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, BadComponentTest, TestStressRecovery, MultiTermTest, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, DirectSolrConnectionTest, TestLuceneMatchVersion, TestCharFilters, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, AssignTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CollectionsAPIAsyncDistributedZkTest, ConcurrentDeleteAndCreateCollectionTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, DistributedQueueTest, DistributedVersionInfoTest, LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, SolrCloudExampleTest, SolrXmlInZkTest, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudJSONFacetJoinDomain, TestCloudRecovery, TestClusterProperties, TestConfigSetsAPI, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestHdfsCloudBackupRestore, TestLeaderElectionZkExpiry, TestLeaderInitiatedRecoveryThread, TestLocalFSCloudBackupRestore, TestLockTree, TestOnReconnectListenerSupport, TestPrepRecovery, TestRandomFlRTGCloud, TestRandomRequestDistribution, TestReplicaProperties, TestSolrCloudWithDelegationTokens, TestStressLiveNodes, TestTolerantUpdateProcessorCloud, ZkFailoverTest, TestSQLHandler, TestSolrConfigHandlerConcurrent, ReplicaListTransformerTest, ShufflingReplicaListTransformerTest, SuggestComponentContextFilterQueryTest, TestExpandComponent, TestHttpShardHandlerFactory, TestSlowCompositeReaderWrapper, CheckHdfsIndexTest, TestMultiValuedNumericRangeQuery, TestNumericRangeQuery32, TestNumericRangeQuery64, TestNumericTokenStream, MetricsConfigTest, SolrMetricsIntegrationTest, SolrGangliaReporterTest, SolrSlf4jReporterTest, SolrShardReporterTest, TestCopyFieldCollectionResource, TestFieldResource, TestSchemaNameResource, TestSchemaResource, TestSerializedLuceneMatchVersion, TestUniqueKeyFieldResource, TestManagedSynonymFilterFactory, TestManagedSynonymGraphFilterFactory, EnumFieldTest, PreAnalyzedFieldManagedSchemaCloudTest, TestCloudManagedSchema, TestHalfAndHalfDocValues, TestManagedSchema, TestUseDocValuesAsStored, AnalyticsMergeStrategyTest, TestQueryWrapperFilter, TestRandomCollapseQParserPlugin, TestOrdValues, BlockJoinFacetDistribTest, BlockJoinFacetRandomTest, BlockJoinFacetSimpleTest, TestNestedDocsSort, TestScoreJoinQPNoScore, SimpleMLTQParserTest, TestClassicSimilarityFactory, TestNonDefinedSimilarityFactory, TestDefaultStatsCache, TestExactStatsCache, BasicAuthIntegrationTest] [junit4] Completed [687/734 (2!)] on J0 in 9.42s, 1 test, 1 failure <<< FAILURES! [...truncated 39787 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org