Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/512/ Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testBasicLeaderElection Error Message: Can not find doc 4 in http://127.0.0.1:46315/solr Stack Trace: java.lang.AssertionError: Can not find doc 4 in http://127.0.0.1:46315/solr at __randomizedtesting.SeedInfo.seed([A06CDB52765A4210:36E41D1A9EC005EA]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:861) at org.apache.solr.cloud.TestTlogReplica.testBasicLeaderElection(TestTlogReplica.java:637) 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 11851 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> 258325 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_A06CDB52765A4210-001/init-core-data-001 [junit4] 2> 258325 WARN (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 258325 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 258326 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 258327 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-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_A06CDB52765A4210-001/tempDir-001 [junit4] 2> 258327 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 258327 INFO (Thread-751) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 258327 INFO (Thread-751) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 258329 ERROR (Thread-751) [ ] 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> 258427 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43187 [junit4] 2> 258436 INFO (jetty-launcher-262-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 258436 INFO (jetty-launcher-262-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 258448 INFO (jetty-launcher-262-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7ade6f9b{/solr,null,AVAILABLE} [junit4] 2> 258448 INFO (jetty-launcher-262-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@72bd7832{/solr,null,AVAILABLE} [junit4] 2> 258452 INFO (jetty-launcher-262-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2c96a0a7{HTTP/1.1,[http/1.1]}{127.0.0.1:46315} [junit4] 2> 258452 INFO (jetty-launcher-262-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3b7a1861{HTTP/1.1,[http/1.1]}{127.0.0.1:40537} [junit4] 2> 258452 INFO (jetty-launcher-262-thread-1) [ ] o.e.j.s.Server Started @260713ms [junit4] 2> 258453 INFO (jetty-launcher-262-thread-2) [ ] o.e.j.s.Server Started @260713ms [junit4] 2> 258453 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46315} [junit4] 2> 258453 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40537} [junit4] 2> 258453 ERROR (jetty-launcher-262-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 258453 ERROR (jetty-launcher-262-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 258453 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0 [junit4] 2> 258453 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0 [junit4] 2> 258453 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 258453 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 258453 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 258453 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 258453 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-28T21:28:37.644Z [junit4] 2> 258453 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-28T21:28:37.644Z [junit4] 2> 258461 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 258462 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 258467 INFO (jetty-launcher-262-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43187/solr [junit4] 2> 258467 INFO (jetty-launcher-262-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43187/solr [junit4] 2> 258497 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 258507 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46315_solr [junit4] 2> 258508 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.c.Overseer Overseer (id=98738773525725190-127.0.0.1:46315_solr-n_0000000000) starting [junit4] 2> 258540 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46315_solr [junit4] 2> 258541 INFO (zkCallback-274-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 258563 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 258564 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 258565 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40537_solr [junit4] 2> 258568 INFO (zkCallback-273-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 258568 INFO (zkCallback-274-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 258607 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46315.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 258621 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46315.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 258622 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46315.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 258623 INFO (jetty-launcher-262-thread-1) [n:127.0.0.1:46315_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_A06CDB52765A4210-001/tempDir-001/node1/. [junit4] 2> 258625 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40537.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 258632 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40537.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 258632 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40537.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 258634 INFO (jetty-launcher-262-thread-2) [n:127.0.0.1:40537_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_A06CDB52765A4210-001/tempDir-001/node2/. [junit4] 2> 258709 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 258709 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43187/solr ready [junit4] 2> 258710 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 258712 INFO (qtp687315238-2080) [n:127.0.0.1:46315_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> 258716 INFO (qtp687315238-2080) [n:127.0.0.1:46315_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=3 [junit4] 2> 258737 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[A06CDB52765A4210]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRealTimeGet [junit4] 2> 258738 INFO (qtp687315238-2081) [n:127.0.0.1:46315_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=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 258739 INFO (OverseerThreadFactory-1062-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_solr ] o.a.s.c.CreateCollectionCmd Create collection tlog_replica_test_real_time_get [junit4] 2> 258739 WARN (OverseerThreadFactory-1062-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_solr ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 4 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> 258844 INFO (OverseerStateUpdate-98738773525725190-127.0.0.1:46315_solr-n_0000000000) [n:127.0.0.1:46315_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":"http://127.0.0.1:40537/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 258845 INFO (OverseerStateUpdate-98738773525725190-127.0.0.1:46315_solr-n_0000000000) [n:127.0.0.1:46315_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":"http://127.0.0.1:46315/solr";, [junit4] 2> "type":"NRT"} [junit4] 2> 258848 INFO (OverseerStateUpdate-98738773525725190-127.0.0.1:46315_solr-n_0000000000) [n:127.0.0.1:46315_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":"http://127.0.0.1:40537/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 258849 INFO (OverseerStateUpdate-98738773525725190-127.0.0.1:46315_solr-n_0000000000) [n:127.0.0.1:46315_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_t6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:46315/solr";, [junit4] 2> "type":"TLOG"} [junit4] 2> 259055 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 259055 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_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_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> 259055 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 259055 INFO (qtp687315238-2067) [n:127.0.0.1:46315_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 259055 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 259057 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t6&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 259162 INFO (zkCallback-273-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 259162 INFO (zkCallback-274-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 259162 INFO (zkCallback-273-thread-2-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 259162 INFO (zkCallback-274-thread-2-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 260071 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 260074 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260076 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0 [junit4] 2> 260077 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260091 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_t6] Schema name=minimal [junit4] 2> 260094 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 260094 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_t6' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 260095 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46315.solr.core.tlog_replica_test_real_time_get.shard1.replica_t6' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_t6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@553b2249 [junit4] 2> 260095 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 260095 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_t6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_A06CDB52765A4210-001/tempDir-001/node1/tlog_replica_test_real_time_get_shard1_replica_t6], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_A06CDB52765A4210-001/tempDir-001/node1/./tlog_replica_test_real_time_get_shard1_replica_t6/data/] [junit4] 2> 260098 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260100 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260100 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260101 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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_46315.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@553b2249 [junit4] 2> 260101 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260101 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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_A06CDB52765A4210-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_A06CDB52765A4210-001/tempDir-001/node1/./tlog_replica_test_real_time_get_shard1_replica_n2/data/] [junit4] 2> 260101 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260103 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260103 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260103 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260103 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40537.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@553b2249 [junit4] 2> 260104 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 260104 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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_A06CDB52765A4210-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_A06CDB52765A4210-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_t4/data/] [junit4] 2> 260105 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260105 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260106 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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_40537.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@553b2249 [junit4] 2> 260106 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260106 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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_A06CDB52765A4210-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_A06CDB52765A4210-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_n1/data/] [junit4] 2> 260175 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260175 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260176 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 260176 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 260178 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@4898148e[tlog_replica_test_real_time_get_shard1_replica_t4] main] [junit4] 2> 260179 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 260179 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260180 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 260184 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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 1579820378357760000 [junit4] 2> 260184 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260184 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260185 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 260185 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 260185 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260185 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260186 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 260186 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 260186 INFO (searcherExecutor-1069-thread-1-processing-n:127.0.0.1:40537_solr x:tlog_replica_test_real_time_get_shard1_replica_t4 s:shard1 c:tlog_replica_test_real_time_get r:core_node7) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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@4898148e[tlog_replica_test_real_time_get_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 260187 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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@bcad733[tlog_replica_test_real_time_get_shard1_replica_n1] main] [junit4] 2> 260187 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b3d5fc1[tlog_replica_test_real_time_get_shard1_replica_t6] main] [junit4] 2> 260188 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 260188 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 260188 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 260191 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260192 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260193 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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> 260194 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=4 found=1 timeoutin=9999ms [junit4] 2> 260195 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1579820378369294336 [junit4] 2> 260195 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260195 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260196 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260197 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260197 INFO (searcherExecutor-1070-thread-1-processing-n:127.0.0.1:40537_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:40537_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@bcad733[tlog_replica_test_real_time_get_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 260197 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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 1579820378371391488 [junit4] 2> 260198 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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@187645c5[tlog_replica_test_real_time_get_shard1_replica_n2] main] [junit4] 2> 260200 INFO (searcherExecutor-1067-thread-1-processing-n:127.0.0.1:46315_solr x:tlog_replica_test_real_time_get_shard1_replica_t6 s:shard1 c:tlog_replica_test_real_time_get r:core_node8) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t6] Registered new searcher Searcher@5b3d5fc1[tlog_replica_test_real_time_get_shard1_replica_t6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 260201 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260201 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260202 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 260203 INFO (searcherExecutor-1068-thread-1-processing-n:127.0.0.1:46315_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:46315_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@187645c5[tlog_replica_test_real_time_get_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 260203 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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 1579820378377682944 [junit4] 2> 260295 INFO (zkCallback-274-thread-2-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 260295 INFO (zkCallback-274-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 260295 INFO (zkCallback-273-thread-2-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 260295 INFO (zkCallback-273-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 260694 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 260694 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260695 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/ [junit4] 2> 260695 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=http://127.0.0.1:40537/solr START replicas=[http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/, http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/, http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_t6/] nUpdates=100 [junit4] 2> 260706 INFO (qtp1443201092-2072) [n:127.0.0.1:40537_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=9 [junit4] 2> 260707 INFO (qtp687315238-2080) [n:127.0.0.1:46315_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> 260707 INFO (qtp687315238-2073) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 260999 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=http://127.0.0.1:40537/solr DONE. We have no versions. sync failed. [junit4] 2> 260999 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260999 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 260999 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 261000 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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> 261003 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/ shard1 [junit4] 2> 261113 INFO (zkCallback-273-thread-2-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 261113 INFO (zkCallback-273-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 261113 INFO (zkCallback-274-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 261113 INFO (zkCallback-274-thread-2-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 261157 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 261158 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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_node7&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=2103 [junit4] 2> 261203 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ZkController tlog_replica_test_real_time_get_shard1_replica_t6 starting background replication from leader [junit4] 2> 261204 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 261209 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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=2154 [junit4] 2> 261212 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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=2157 [junit4] 2> 261215 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 261215 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 261215 INFO (indexFetcher-1088-thread-1) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.IndexFetcher Replica core_node7 is leader but it's state is down, skipping replication [junit4] 2> 261216 INFO (qtp687315238-2071) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t6&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2159 [junit4] 2> 261218 INFO (qtp687315238-2081) [n:127.0.0.1:46315_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> 261317 INFO (zkCallback-274-thread-2-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 261317 INFO (zkCallback-274-thread-1-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_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> 261317 INFO (zkCallback-273-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 261317 INFO (zkCallback-273-thread-2-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_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> 262218 INFO (qtp687315238-2081) [n:127.0.0.1:46315_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=2&wt=javabin&version=2} status=0 QTime=3480 [junit4] 2> 262243 INFO (qtp687315238-2148) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[0 (1579820380497903616)]} 0 4 [junit4] 2> 262244 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[0 (1579820380497903616)]} 0 14 [junit4] 2> 262244 INFO (qtp687315238-2131) [n:127.0.0.1:46315_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[0 (1579820380497903616)]} 0 4 [junit4] 2> 262244 INFO (qtp1443201092-2068) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2}{add=[0 (1579820380497903616)]} 0 19 [junit4] 2> 262244 INFO (qtp1443201092-2074) [n:127.0.0.1:40537_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 20 [junit4] 2> 262245 INFO (qtp687315238-2082) [n:127.0.0.1:46315_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=0 [junit4] 2> 262246 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_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> 262246 INFO (qtp687315238-2080) [n:127.0.0.1:46315_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> 262247 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262248 INFO (qtp687315238-2150) [n:127.0.0.1:46315_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&omitHeader=true&shards.purpose=1&NOW=1506634121438&ids=0&isShard=true&shard.url=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262249 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=1 [junit4] 2> 262249 INFO (qtp687315238-2080) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262250 INFO (qtp687315238-2069) [n:127.0.0.1:46315_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&omitHeader=true&shards.purpose=1&NOW=1506634121440&ids=0&isShard=true&shard.url=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262250 INFO (qtp687315238-2080) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 262252 INFO (qtp687315238-2149) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1579820380525166592)]} 0 0 [junit4] 2> 262253 INFO (qtp1443201092-2072) [n:127.0.0.1:40537_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1579820380525166592)]} 0 0 [junit4] 2> 262255 INFO (qtp687315238-2150) [n:127.0.0.1:46315_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1579820380525166592)]} 0 2 [junit4] 2> 262255 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/&wt=javabin&version=2}{add=[1 (1579820380525166592)]} 0 4 [junit4] 2> 262256 INFO (qtp687315238-2071) [n:127.0.0.1:46315_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 5 [junit4] 2> 262256 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_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> 262256 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_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> 262257 INFO (qtp687315238-2148) [n:127.0.0.1:46315_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> 262257 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/ [junit4] 2> 262258 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=1506634121448&ids=1&isShard=true&shard.url=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262259 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=1 [junit4] 2> 262259 INFO (qtp687315238-2073) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/ [junit4] 2> 262260 INFO (qtp1443201092-2070) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=1506634121450&ids=1&isShard=true&shard.url=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262260 INFO (qtp687315238-2073) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 262261 INFO (qtp687315238-2131) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[2 (1579820380535652352)]} 0 0 [junit4] 2> 262261 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[2 (1579820380535652352)]} 0 0 [junit4] 2> 262262 INFO (qtp687315238-2149) [n:127.0.0.1:46315_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[2 (1579820380535652352)]} 0 0 [junit4] 2> 262262 INFO (qtp1443201092-2072) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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 (1579820380535652352)]} 0 1 [junit4] 2> 262262 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 262263 INFO (qtp1443201092-2074) [n:127.0.0.1:40537_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> 262263 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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> 262263 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262264 INFO (qtp687315238-2081) [n:127.0.0.1:46315_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&omitHeader=true&shards.purpose=1&NOW=1506634121454&ids=2&isShard=true&shard.url=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262264 INFO (qtp1443201092-2076) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=0 [junit4] 2> 262264 INFO (qtp687315238-2150) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262265 INFO (qtp687315238-2069) [n:127.0.0.1:46315_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&omitHeader=true&shards.purpose=1&NOW=1506634121455&ids=2&isShard=true&shard.url=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262265 INFO (qtp687315238-2150) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262266 INFO (qtp687315238-2081) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[3 (1579820380540895232)]} 0 0 [junit4] 2> 262267 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[3 (1579820380540895232)]} 0 0 [junit4] 2> 262267 INFO (qtp687315238-2080) [n:127.0.0.1:46315_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=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[3 (1579820380540895232)]} 0 0 [junit4] 2> 262267 INFO (qtp1443201092-2068) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[3 (1579820380540895232)]} 0 1 [junit4] 2> 262268 INFO (qtp687315238-2148) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 3 [junit4] 2> 262269 INFO (qtp687315238-2131) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262269 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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&_stateVer_=tlog_replica_test_real_time_get:5&omitHeader=true&shards.purpose=1&NOW=1506634121460&ids=3&isShard=true&shard.url=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262270 INFO (qtp687315238-2131) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&_stateVer_=tlog_replica_test_real_time_get:5&ids=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262270 INFO (qtp1443201092-2070) [n:127.0.0.1:40537_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=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262270 INFO (qtp687315238-2067) [n:127.0.0.1:46315_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=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262271 INFO (qtp1443201092-2070) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262271 INFO (qtp1443201092-2072) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=1506634121462&ids=3&isShard=true&shard.url=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262272 INFO (qtp1443201092-2070) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262272 INFO (qtp687315238-2067) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262272 INFO (qtp687315238-2069) [n:127.0.0.1:46315_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&omitHeader=true&shards.purpose=1&NOW=1506634121463&ids=3&isShard=true&shard.url=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262273 INFO (qtp687315238-2067) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262273 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_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&ids=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262273 INFO (qtp687315238-2073) [n:127.0.0.1:46315_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&ids=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262274 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 262275 INFO (qtp687315238-2149) [n:127.0.0.1:46315_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&omitHeader=true&shards.purpose=1&NOW=1506634121465&ids=0,1,2,3&isShard=true&shard.url=http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262275 INFO (qtp1443201092-2079) [n:127.0.0.1:40537_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 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&ids=3&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 262275 INFO (qtp687315238-2082) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/ [junit4] 2> 262276 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_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=1506634121466&ids=0,1,2,3&isShard=true&shard.url=http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|http://127.0.0.1:40537/solr/tlog_replica_test_real_time_get_shard1_replica_t4/|http://127.0.0.1:46315/solr/tlog_replica_test_real_time_get_shard1_replica_n2/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 262276 INFO (qtp687315238-2082) [n:127.0.0.1:46315_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=0&ids=1&ids=2&ids=3&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 262277 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[A06CDB52765A4210]) [ ] o.a.s.c.TestTlogReplica tearDown deleting collection [junit4] 2> 262278 INFO (qtp687315238-2148) [n:127.0.0.1:46315_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> 262280 INFO (OverseerCollectionConfigSetProcessor-98738773525725190-127.0.0.1:46315_solr-n_0000000000) [n:127.0.0.1:46315_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> 262283 INFO (OverseerThreadFactory-1062-thread-2-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true [junit4] 2> 262284 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_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> 262284 INFO (qtp1443201092-2147) [n:127.0.0.1:40537_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d3cca3: rootName = solr_40537, 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@7d6d21fb [junit4] 2> 262288 INFO (qtp687315238-2069) [n:127.0.0.1:46315_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> 262288 INFO (qtp687315238-2069) [n:127.0.0.1:46315_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d024c96: rootName = solr_46315, 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@51d81609 [junit4] 2> 262299 INFO (qtp687315238-2080) [n:127.0.0.1:46315_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_t6, tag=null [junit4] 2> 262299 INFO (qtp687315238-2080) [n:127.0.0.1:46315_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61c86093: rootName = solr_46315, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_t6, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_t6 / com.codahale.metrics.MetricRegistry@4e514543 [junit4] 2> 262300 INFO (qtp687315238-2069) [n:127.0.0.1:46315_solr ] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@d3f4186 [junit4] 2> 262303 INFO (qtp1443201092-2078) [n:127.0.0.1:40537_solr ] o.a.s.m.SolrMetricManage [...truncated too long message...] cManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 368297 INFO (jetty-closer-263-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6bf782fa: rootName = solr_46315, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@65ccfeec [junit4] 2> 368297 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 368297 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61598eba: rootName = solr_40537, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@774db4cb [junit4] 2> 368301 INFO (jetty-closer-263-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 368301 INFO (jetty-closer-263-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6bd46355: rootName = solr_46315, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@44bb1d7d [junit4] 2> 368303 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 368303 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@753835c2: rootName = solr_40537, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@44bb1d7d [junit4] 2> 368303 INFO (jetty-closer-263-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 368303 INFO (jetty-closer-263-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36cf2447: rootName = solr_46315, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5280cdba [junit4] 2> 368304 INFO (jetty-closer-263-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 368304 INFO (jetty-closer-263-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98738773525725210-127.0.0.1:46315_solr-n_0000000007) closing [junit4] 2> 368304 INFO (OverseerStateUpdate-98738773525725210-127.0.0.1:46315_solr-n_0000000007) [n:127.0.0.1:46315_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:46315_solr [junit4] 2> 368305 WARN (zkCallback-321-thread-2-processing-n:127.0.0.1:46315_solr) [n:127.0.0.1:46315_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 368305 INFO (zkCallback-329-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40537_solr [junit4] 2> 368305 INFO (zkCallback-329-thread-2-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 368305 INFO (jetty-closer-263-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6297395a{/solr,null,UNAVAILABLE} [junit4] 2> 368307 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 368307 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f63df7d: rootName = solr_40537, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5280cdba [junit4] 2> 368308 INFO (jetty-closer-263-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 368310 WARN (zkCallback-329-thread-1-processing-n:127.0.0.1:40537_solr) [n:127.0.0.1:40537_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 368310 INFO (jetty-closer-263-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6f255189{/solr,null,UNAVAILABLE} [junit4] 2> 368311 ERROR (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-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> 368312 INFO (SUITE-TestTlogReplica-seed#[A06CDB52765A4210]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43187 43187 [junit4] 2> 373333 INFO (Thread-751) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43187 43187 [junit4] 2> 373335 WARN (Thread-751) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 34 /solr/configs/conf [junit4] 2> 10 /solr/aliases.json [junit4] 2> 9 /solr/security.json [junit4] 2> 3 /solr/clusterprops.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 32 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 25 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 24 /solr/collections/tlog_replica_test_kill_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_remove_leader/state.json [junit4] 2> 22 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 22 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 20 /solr/collections/tlog_replica_test_create_delete/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> 13 /solr/collections/tlog_replica_test_only_leader_indexes/state.json [junit4] 2> 13 /solr/collections/tlog_replica_test_delete_by_id/state.json [junit4] 2> 10 /solr/clusterstate.json [junit4] 2> 10 /solr/clusterprops.json [junit4] 2> 4 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/98738773525725198-core_node3-n_0000000000 [junit4] 2> 3 /solr/overseer_elect/election/98738773525725198-127.0.0.1:46315_solr-n_0000000003 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/98738773525725213-core_node11-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_kill_tlog_replica/leader_elect/shard1/election/98738773525725195-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/98738773525725210-core_node3-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_add_docs/leader_elect/shard1/election/98738773525725189-core_node6-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/98738773525725190-127.0.0.1:46315_solr-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 10 /solr/collections [junit4] 2> 5 /solr/overseer/queue [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/live_nodes [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_A06CDB52765A4210-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {foo=PostingsFormat(name=Memory), title_s=FSTOrd50, foo_s=PostingsFormat(name=Memory), id=FST50}, docValues:{_version_=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Direct), inplace_updatable_int=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1017, maxMBSortInHeap=6.959732749229657, sim=RandomSimilarity(queryNorm=true): {}, locale=he, timezone=Australia/Lindeman [junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=8,threads=1,free=243738144,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestHighlightDedupGrouping, ChangedSchemaMergeTest, OverseerTaskQueueTest, TestFieldCollectionResource, TestWriterPerf, WordBreakSolrSpellCheckerTest, TestRuleBasedAuthorizationPlugin, TestCSVResponseWriter, TestFunctionQuery, TestFileDictionaryLookup, TestPerFieldSimilarityWithDefaultOverride, TestLuceneMatchVersion, TestReloadAndDeleteDocs, TestConfig, SolrCoreTest, AsyncCallRequestStatusResponseTest, ImplicitSnitchTest, ExternalFileFieldSortTest, SolrIndexConfigTest, TestLegacyFieldReuse, PreAnalyzedFieldManagedSchemaCloudTest, OverseerModifyCollectionTest, TestConfigSetsAPIZkFailure, OpenExchangeRatesOrgProviderTest, TestIndexingPerformance, RAMDirectoryFactoryTest, CdcrUpdateLogTest, TestNestedDocsSort, DistributedSuggestComponentTest, TestDistributedSearch, TestMultiValuedNumericRangeQuery, TestStreamBody, ClassificationUpdateProcessorIntegrationTest, TestSubQueryTransformerCrossCore, TestStressCloudBlindAtomicUpdates, DistributedFacetPivotWhiteBoxTest, SuggesterWFSTTest, TestRawResponseWriter, ConfigureRecoveryStrategyTest, DocExpirationUpdateProcessorFactoryTest, TestCSVLoader, PeerSyncTest, TestRandomCollapseQParserPlugin, TestTlogReplica] [junit4] Completed [136/732 (1!)] on J0 in 115.02s, 13 tests, 1 failure <<< FAILURES! [...truncated 48731 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
