Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/492/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 3 in https://127.0.0.1:38399/solr Stack Trace: java.lang.AssertionError: Can not find doc 3 in https://127.0.0.1:38399/solr at __randomizedtesting.SeedInfo.seed([47D3774E87C6C015:86230EE2AA960AB2]: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:868) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:559) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 11496 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/init-core-data-001 [junit4] 2> 56399 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 56401 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 56401 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 56401 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001 [junit4] 2> 56401 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 56402 INFO (Thread-147) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 56402 INFO (Thread-147) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 56420 ERROR (Thread-147) [ ] 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> 56502 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38845 [junit4] 2> 56514 INFO (jetty-launcher-168-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 56518 INFO (jetty-launcher-168-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 56533 INFO (jetty-launcher-168-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3a5d989{/solr,null,AVAILABLE} [junit4] 2> 56533 INFO (jetty-launcher-168-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@745b50e7{/solr,null,AVAILABLE} [junit4] 2> 56534 INFO (jetty-launcher-168-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5401f964{SSL,[ssl, http/1.1]}{127.0.0.1:34031} [junit4] 2> 56534 INFO (jetty-launcher-168-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6339165e{SSL,[ssl, http/1.1]}{127.0.0.1:38399} [junit4] 2> 56534 INFO (jetty-launcher-168-thread-1) [ ] o.e.j.s.Server Started @57945ms [junit4] 2> 56534 INFO (jetty-launcher-168-thread-2) [ ] o.e.j.s.Server Started @57945ms [junit4] 2> 56534 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38399} [junit4] 2> 56534 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34031} [junit4] 2> 56534 ERROR (jetty-launcher-168-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 56535 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0 [junit4] 2> 56535 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 56535 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 56535 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-26T09:04:21.849Z [junit4] 2> 56534 ERROR (jetty-launcher-168-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 56536 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0 [junit4] 2> 56536 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 56536 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 56536 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-26T09:04:21.850Z [junit4] 2> 56541 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 56541 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 56546 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15ebd6e14d60002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 56549 INFO (jetty-launcher-168-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38845/solr [junit4] 2> 56561 INFO (jetty-launcher-168-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38845/solr [junit4] 2> 56572 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15ebd6e14d60003, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 56684 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 56684 INFO (jetty-launcher-168-thread-2) [n:127.0.0.1:38399_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 56684 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34031_solr [junit4] 2> 56685 INFO (jetty-launcher-168-thread-2) [n:127.0.0.1:38399_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38399_solr [junit4] 2> 56685 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.c.Overseer Overseer (id=98724522342350854-127.0.0.1:34031_solr-n_0000000000) starting [junit4] 2> 56686 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 56686 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 56691 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34031_solr [junit4] 2> 56691 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 56691 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 56752 INFO (jetty-launcher-168-thread-2) [n:127.0.0.1:38399_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38399.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 56757 INFO (jetty-launcher-168-thread-2) [n:127.0.0.1:38399_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38399.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 56757 INFO (jetty-launcher-168-thread-2) [n:127.0.0.1:38399_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38399.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 56758 INFO (jetty-launcher-168-thread-2) [n:127.0.0.1:38399_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/. [junit4] 2> 56762 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34031.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 56771 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34031.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 56772 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34031.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 56773 INFO (jetty-launcher-168-thread-1) [n:127.0.0.1:34031_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node1/. [junit4] 2> 56869 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 56870 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38845/solr ready [junit4] 2> 56871 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 56889 INFO (qtp375948239-716) [n:127.0.0.1:38399_solr ] 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> 56891 INFO (qtp375948239-716) [n:127.0.0.1:38399_solr ] 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=1 [junit4] 2> 56900 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[47D3774E87C6C015]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRealTimeGet [junit4] 2> 56903 INFO (qtp375948239-706) [n:127.0.0.1:38399_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=2&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_real_time_get&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 56905 INFO (OverseerThreadFactory-184-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.CreateCollectionCmd Create collection tlog_replica_test_real_time_get [junit4] 2> 56905 WARN (OverseerThreadFactory-184-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 3 on collection tlog_replica_test_real_time_get 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> 57014 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38399/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 57017 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:34031/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 57022 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_t4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38399/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 57228 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 57228 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 57241 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_n1&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 57258 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_n2&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 57259 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 57365 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 57365 INFO (zkCallback-180-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 57365 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 57365 INFO (zkCallback-179-thread-2-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 58254 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 58258 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 58273 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_n1] Schema name=minimal [junit4] 2> 58275 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_t4] Schema name=minimal [junit4] 2> 58277 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 58277 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_t4' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 58278 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38399.solr.core.tlog_replica_test_real_time_get.shard1.replica_t4' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_t4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 58278 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 58279 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_t4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/tlog_replica_test_real_time_get_shard1_replica_t4], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_t4/data/] [junit4] 2> 58279 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 58279 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_n1' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 58280 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38399.solr.core.tlog_replica_test_real_time_get.shard1.replica_n1' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 58280 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 58280 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/tlog_replica_test_real_time_get_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_n1/data/] [junit4] 2> 58290 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 58311 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_n2] Schema name=minimal [junit4] 2> 58315 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 58315 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_n2' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 58317 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34031.solr.core.tlog_replica_test_real_time_get.shard1.replica_n2' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6134a8 [junit4] 2> 58317 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 58317 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node1/tlog_replica_test_real_time_get_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node1/./tlog_replica_test_real_time_get_shard1_replica_n2/data/] [junit4] 2> 58349 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 58349 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 58350 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 58350 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 58352 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@33bd124a[tlog_replica_test_real_time_get_shard1_replica_t4] main] [junit4] 2> 58353 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 58354 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 58354 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 58356 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1579592359524433920 [junit4] 2> 58359 INFO (searcherExecutor-189-thread-1-processing-n:127.0.0.1:38399_solr x:tlog_replica_test_real_time_get_shard1_replica_t4 s:shard1 c:tlog_replica_test_real_time_get r:core_node6) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t4] Registered new searcher Searcher@33bd124a[tlog_replica_test_real_time_get_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 58363 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoutin=9999ms [junit4] 2> 58370 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 58370 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 58372 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 58373 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 58375 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d1cf0b6[tlog_replica_test_real_time_get_shard1_replica_n1] main] [junit4] 2> 58376 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 58376 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 58377 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 58377 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1579592359546454016 [junit4] 2> 58380 INFO (searcherExecutor-190-thread-1-processing-n:127.0.0.1:38399_solr x:tlog_replica_test_real_time_get_shard1_replica_n1 s:shard1 c:tlog_replica_test_real_time_get r:core_node3) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n1] Registered new searcher Searcher@2d1cf0b6[tlog_replica_test_real_time_get_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 58408 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 58408 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 58410 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 58410 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 58411 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@50f44477[tlog_replica_test_real_time_get_shard1_replica_n2] main] [junit4] 2> 58413 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 58414 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 58415 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 58421 INFO (searcherExecutor-191-thread-1-processing-n:127.0.0.1:34031_solr x:tlog_replica_test_real_time_get_shard1_replica_n2 s:shard1 c:tlog_replica_test_real_time_get r:core_node5) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n2] Registered new searcher Searcher@50f44477[tlog_replica_test_real_time_get_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 58421 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1579592359592591360 [junit4] 2> 58465 INFO (zkCallback-179-thread-2-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 58465 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 58465 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 58465 INFO (zkCallback-180-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 58863 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 58863 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 58863 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/ [junit4] 2> 58864 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_real_time_get_shard1_replica_t4 url=https://127.0.0.1:38399/solr START replicas=[https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/, https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/] nUpdates=100 [junit4] 2> 58879 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 58880 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 59165 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_real_time_get_shard1_replica_t4 url=https://127.0.0.1:38399/solr DONE. We have no versions. sync failed. [junit4] 2> 59165 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 59165 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] 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> 59165 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 59166 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ZkController tlog_replica_test_real_time_get_shard1_replica_t4 stopping background replication from leader [junit4] 2> 59168 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/ shard1 [junit4] 2> 59270 INFO (zkCallback-179-thread-2-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59270 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59270 INFO (zkCallback-180-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59270 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59319 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 59320 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2093 [junit4] 2> 59387 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] 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_real_time_get_shard1_replica_n1&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2145 [junit4] 2> 59428 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_n2&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2170 [junit4] 2> 59432 INFO (qtp375948239-706) [n:127.0.0.1:38399_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 59530 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59530 INFO (zkCallback-179-thread-2-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59530 INFO (zkCallback-180-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 59530 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 60433 INFO (qtp375948239-706) [n:127.0.0.1:38399_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=2&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_real_time_get&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=1&wt=javabin&version=2} status=0 QTime=3530 [junit4] 2> 60522 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[0 (1579592361743220736)]} 0 25 [junit4] 2> 60523 INFO (qtp1355711058-711) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[0 (1579592361743220736)]} 0 37 [junit4] 2> 60524 INFO (qtp375948239-782) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2}{add=[0 (1579592361743220736)]} 0 52 [junit4] 2> 60524 INFO (qtp375948239-712) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 72 [junit4] 2> 60535 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&_stateVer_=tlog_replica_test_real_time_get:5&ids=0&wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 60538 INFO (qtp375948239-709) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60540 INFO (qtp1355711058-711) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60544 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 60549 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1506416665856&ids=0&isShard=true&shard.url=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 60550 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=7 [junit4] 2> 60559 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1579592361830252544)]} 0 1 [junit4] 2> 60560 INFO (qtp1355711058-718) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1579592361830252544)]} 0 2 [junit4] 2> 60561 INFO (qtp375948239-716) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/&wt=javabin&version=2}{add=[1 (1579592361830252544)]} 0 5 [junit4] 2> 60562 INFO (qtp1355711058-711) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 10 [junit4] 2> 60564 INFO (qtp375948239-706) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&_stateVer_=tlog_replica_test_real_time_get:5&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60566 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60568 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60570 INFO (qtp375948239-782) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/ [junit4] 2> 60572 INFO (qtp375948239-712) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1506416665883&ids=1&isShard=true&shard.url=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 60573 INFO (qtp375948239-782) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 60578 INFO (qtp375948239-709) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[2 (1579592361850175488)]} 0 1 [junit4] 2> 60579 INFO (qtp1355711058-713) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[2 (1579592361850175488)]} 0 2 [junit4] 2> 60580 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1579592361850175488)]} 0 5 [junit4] 2> 60582 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&_stateVer_=tlog_replica_test_real_time_get:5&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60584 INFO (qtp375948239-716) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60586 INFO (qtp1355711058-711) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60588 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/ [junit4] 2> 60590 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1506416665902&ids=2&isShard=true&shard.url=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 60591 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={qt=/get&ids=2&wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 60593 INFO (qtp375948239-712) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&ids=0&ids=1&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60596 INFO (qtp1355711058-721) [n:127.0.0.1:34031_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&ids=0&ids=1&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 60598 INFO (qtp375948239-709) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 60600 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1506416665911&ids=0,1,2&isShard=true&shard.url=https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|https://127.0.0.1:34031/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:38399/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 60600 INFO (qtp375948239-709) [n:127.0.0.1:38399_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={qt=/get&ids=0&ids=1&ids=2&wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 60601 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[47D3774E87C6C015]) [ ] o.a.s.c.TestTlogReplica tearDown deleting collection [junit4] 2> 60604 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=tlog_replica_test_real_time_get&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 60609 INFO (OverseerCollectionConfigSetProcessor-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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> 60612 INFO (OverseerThreadFactory-184-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 60615 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n1, tag=null [junit4] 2> 60615 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n2, tag=null [junit4] 2> 60615 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e11e7b7: rootName = solr_34031, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_n2, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_n2 / com.codahale.metrics.MetricRegistry@7f72985d [junit4] 2> 60615 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43a1f9f7: rootName = solr_38399, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@34ac0359 [junit4] 2> 60636 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@551fe8e8 [junit4] 2> 60636 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n2, tag=1428154600 [junit4] 2> 60636 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_real_time_get.shard1.leader, tag=1428154600 [junit4] 2> 60636 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 60636 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4e47e2a9 commitCommandVersion:0 [junit4] 2> 60640 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_t4, tag=null [junit4] 2> 60641 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@78437fe6: rootName = solr_38399, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_t4, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_t4 / com.codahale.metrics.MetricRegistry@f7e00 [junit4] 2> 60657 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.c.ZkController tlog_replica_test_real_time_get_shard1_replica_t4 stopping background replication from leader [junit4] 2> 60658 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@3ed19837 [junit4] 2> 60658 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n1, tag=1053923383 [junit4] 2> 60658 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_real_time_get.shard1.leader, tag=1053923383 [junit4] 2> 60659 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 60659 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t4] CLOSING SolrCore org.apache.solr.core.SolrCore@6b280502 [junit4] 2> 60659 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_t4, tag=1797784834 [junit4] 2> 60660 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_real_time_get.shard1.leader, tag=1797784834 [junit4] 2> 60659 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@75910962 commitCommandVersion:0 [junit4] 2> 60660 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 60660 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@72314ac commitCommandVersion:0 [junit4] 2> 60690 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 60692 INFO (qtp375948239-768) [n:127.0.0.1:38399_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=tlog_replica_test_real_time_get_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=77 [junit4] 2> 60693 WARN (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 60693 WARN (zkCallback-180-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.LeaderElector [junit4] 2> org.apache.solr.common.SolrException: SolrCore not found:tlog_replica_test_real_time_get_shard1_replica_n2 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.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 60697 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 60700 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=tlog_replica_test_real_time_get_shard1_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=85 [junit4] 2> 60702 INFO (qtp375948239-714) [n:127.0.0.1:38399_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=tlog_replica_test_real_time_get_shard1_replica_t4&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=87 [junit4] 2> 61411 INFO (qtp375948239-708) [n:127.0.0.1:38399_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=tlog_replica_test_real_time_get&action=DELETE&wt=javabin&version=2} status=0 QTime=807 [junit4] 2> 61512 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[47D3774E87C6C015]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRealTimeGet [junit4] 2> 61524 INFO (TEST-TestTlogReplica.testCreateDelete-seed#[47D3774E87C6C015]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateDelete {seed=[47D3774E87C6C015:5CC3860271363825]} [junit4] 2> 61526 INFO (qtp375948239-782) [n:127.0.0.1:38399_solr ] 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> 61533 INFO (OverseerThreadFactory-184-thread-3-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.CreateCollectionCmd Create collection tlog_replica_test_create_delete [junit4] 2> 61533 INFO (OverseerCollectionConfigSetProcessor-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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> 61534 WARN (OverseerThreadFactory-184-thread-3-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] 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> 61740 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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":"https://127.0.0.1:38399/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61743 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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":"https://127.0.0.1:34031/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61745 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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":"https://127.0.0.1:38399/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61746 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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":"https://127.0.0.1:34031/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61750 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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":"https://127.0.0.1:38399/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61761 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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_t9", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:34031/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61768 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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_t11", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38399/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61769 INFO (OverseerStateUpdate-98724522342350854-127.0.0.1:34031_solr-n_0000000000) [n:127.0.0.1:34031_solr ] 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":"https://127.0.0.1:34031/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 61954 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr ] 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> 61954 INFO (qtp375948239-712) [n:127.0.0.1:38399_solr ] 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> 61958 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr ] 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> 61967 INFO (qtp375948239-716) [n:127.0.0.1:38399_solr ] 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_t8&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 61972 INFO (qtp1355711058-718) [n:127.0.0.1:34031_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&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> 61974 INFO (qtp1355711058-798) [n:127.0.0.1:34031_solr ] 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_t12&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 61988 INFO (qtp375948239-800) [n:127.0.0.1:38399_solr ] 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_t11&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 61995 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node14&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t9&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 62098 INFO (zkCallback-179-thread-1-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] 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> 62098 INFO (zkCallback-179-thread-2-processing-n:127.0.0.1:38399_solr) [n:127.0.0.1:38399_solr ] 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> 62098 INFO (zkCallback-180-thread-2-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] 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> 62098 INFO (zkCallback-180-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] 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> 63017 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63017 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63030 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t2] Schema name=minimal [junit4] 2> 63034 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 63034 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] 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> 63036 INFO (qtp375948239-716) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63036 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t4] Schema name=minimal [junit4] 2> 63038 INFO (qtp375948239-800) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t11] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63040 INFO (qtp1355711058-718) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node10 x:tlog_replica_test_create_delete_shard1_replica_t6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63041 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34031.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@3c6134a8 [junit4] 2> 63041 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 63042 INFO (qtp1355711058-719) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node1/tlog_replica_test_create_delete_shard1_replica_t2], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard1_replica_t2/data/] [junit4] 2> 63043 INFO (qtp1355711058-798) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63044 INFO (qtp375948239-712) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63044 INFO (qtp1355711058-705) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard2 r:core_node14 x:tlog_replica_test_create_delete_shard2_replica_t9] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 63046 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 63046 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] 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> 63046 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38399.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@3c6134a8 [junit4] 2> 63047 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 63047 INFO (qtp375948239-707) [n:127.0.0.1:38399_solr c:tlog_replica_test_create_delete s:shard1 r:core_node7 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/tlog_replica_test_create_delete_shard1_replica_t4], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard1_replica_t4/data/] [junit4] 2> 63049 INFO (qtp1355711058-718) [n:127.0.0.1:34031_solr c:tlog_replica_test_create_delete s:shard1 r:core_node10 x:tlog_replica_test_create_delete_shard1_replica_t6] o. [...truncated too long message...] ger Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 157788 INFO (jetty-closer-169-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98724522342350862-127.0.0.1:34031_solr-n_0000000003) closing [junit4] 2> 157788 INFO (OverseerStateUpdate-98724522342350862-127.0.0.1:34031_solr-n_0000000003) [n:127.0.0.1:34031_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34031_solr [junit4] 2> 157788 WARN (zkCallback-198-thread-1-processing-n:127.0.0.1:34031_solr) [n:127.0.0.1:34031_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 157788 INFO (jetty-closer-169-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@51dd5aff{/solr,null,UNAVAILABLE} [junit4] 2> 158673 ERROR (qtp850057765-1004) [n:127.0.0.1:34031_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Solr is shutting down [junit4] 2> at org.apache.solr.handler.admin.PrepRecoveryOp.execute(PrepRecoveryOp.java:177) [junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:384) [junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:389) [junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:745) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:726) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:507) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 158674 INFO (qtp850057765-1004) [n:127.0.0.1:34031_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:38399_solr&onlyIfLeaderActive=true&core=tlog_replica_test_recovery_shard1_replica_t2&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=400 QTime=13002 [junit4] 2> 158675 ERROR (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 158676 INFO (SUITE-TestTlogReplica-seed#[47D3774E87C6C015]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38845 38845 [junit4] 2> 164490 INFO (Thread-147) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38845 38845 [junit4] 2> 164491 WARN (Thread-147) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 33 /solr/configs/conf [junit4] 2> 9 /solr/aliases.json [junit4] 2> 8 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 28 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 25 /solr/collections/tlog_replica_test_kill_leader/state.json [junit4] 2> 25 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 25 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 24 /solr/collections/tlog_replica_test_remove_leader/state.json [junit4] 2> 23 /solr/collections/tlog_replica_test_add_remove_tlog_replica/state.json [junit4] 2> 22 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 19 /solr/collections/tlog_replica_test_create_delete/state.json [junit4] 2> 15 /solr/collections/tlog_replica_test_only_leader_indexes/state.json [junit4] 2> 15 /solr/collections/tlog_replica_test_delete_by_id/state.json [junit4] 2> 13 /solr/collections/tlog_replica_test_add_docs/state.json [junit4] 2> 13 /solr/collections/tlog_replica_test_real_time_get/state.json [junit4] 2> 9 /solr/clusterprops.json [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 4 /solr/overseer_elect/election/98724522342350862-127.0.0.1:34031_solr-n_0000000003 [junit4] 2> 3 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/98724522342350862-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_kill_tlog_replica/leader_elect/shard1/election/98724522342350859-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/98724522342350854-core_node16-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/98724522342350854-core_node16-n_0000000001 [junit4] 2> 2 /solr/overseer_elect/election/98724522342350854-127.0.0.1:34031_solr-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/98724522342350853-core_node7-n_0000000001 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/98724522342350854-core_node5-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_real_time_get/leader_elect/shard1/election/98724522342350853-core_node6-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/98724522342350854-core_node9-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 9 /solr/collections [junit4] 2> 3 /solr/overseer/queue [junit4] 2> 3 /solr/live_nodes [junit4] 2> 3 /solr/overseer/collection-queue-work [junit4] 2> 2 /solr/overseer/queue-work [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_47D3774E87C6C015-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {foo=BlockTreeOrds(blocksize=128), title_s=BlockTreeOrds(blocksize=128), foo_s=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Direct)}, docValues:{_version_=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene70), inplace_updatable_int=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1258, maxMBSortInHeap=5.663316749774738, sim=RandomSimilarity(queryNorm=false): {}, locale=es, timezone=America/Regina [junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=8,threads=1,free=237059800,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [BasicZkTest, TestSolrConfigHandlerConcurrent, TestFieldCollectionResource, DefaultValueUpdateProcessorTest, TestImpersonationWithHadoopAuth, V2StandaloneTest, TestTlogReplica] [junit4] Completed [30/732 (1!)] on J0 in 108.22s, 13 tests, 1 failure <<< FAILURES! [...truncated 49028 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
