Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/498/
7 tests failed. FAILED: org.apache.solr.cloud.BasicDistributedZkTest.test Error Message: Test abandoned because suite timeout was reached. Stack Trace: java.lang.Exception: Test abandoned because suite timeout was reached. at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZkTest Error Message: Suite timeout exceeded (>= 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestLeaderElectionZkExpiry Error Message: ObjectTracker found 1 object(s) that were not released!!! [Overseer] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.cloud.Overseer at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.cloud.Overseer.start(Overseer.java:545) at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:850) at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:307) at org.apache.solr.cloud.LeaderElector.retryElection(LeaderElector.java:393) at org.apache.solr.cloud.ZkController.rejoinOverseerElection(ZkController.java:2055) at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:331) at java.lang.Thread.run(Thread.java:748) Stack Trace: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [Overseer] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.cloud.Overseer at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.cloud.Overseer.start(Overseer.java:545) at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:850) at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:307) at org.apache.solr.cloud.LeaderElector.retryElection(LeaderElector.java:393) at org.apache.solr.cloud.ZkController.rejoinOverseerElection(ZkController.java:2055) at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:331) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:301) at sun.reflect.GeneratedMethodAccessor65.invoke(Unknown Source) 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$7.evaluate(RandomizedRunner.java:897) 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) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestLeaderElectionZkExpiry Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestLeaderElectionZkExpiry: 1) Thread[id=16681, name=OverseerAutoScalingTriggerThread-72269253585862666-dummy.host.com:8984_solr-n_0000000007, state=RUNNABLE, group=Overseer autoscaling triggers] at org.apache.lucene.util.TestRuleLimitSysouts$DelegateStream.write(TestRuleLimitSysouts.java:128) at java.io.PrintStream.write(PrintStream.java:480) at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221) at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291) at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295) at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141) at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229) at org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59) at org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324) at org.apache.log4j.WriterAppender.append(WriterAppender.java:162) at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:251) at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66) at org.apache.log4j.Category.callAppenders(Category.java:206) at org.apache.log4j.Category.forcedLog(Category.java:391) at org.apache.log4j.Category.log(Category.java:856) at org.slf4j.impl.Log4jLoggerAdapter.error(Log4jLoggerAdapter.java:576) at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:140) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestLeaderElectionZkExpiry: 1) Thread[id=16681, name=OverseerAutoScalingTriggerThread-72269253585862666-dummy.host.com:8984_solr-n_0000000007, state=RUNNABLE, group=Overseer autoscaling triggers] at org.apache.lucene.util.TestRuleLimitSysouts$DelegateStream.write(TestRuleLimitSysouts.java:128) at java.io.PrintStream.write(PrintStream.java:480) at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221) at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291) at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295) at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141) at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229) at org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59) at org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324) at org.apache.log4j.WriterAppender.append(WriterAppender.java:162) at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:251) at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66) at org.apache.log4j.Category.callAppenders(Category.java:206) at org.apache.log4j.Category.forcedLog(Category.java:391) at org.apache.log4j.Category.log(Category.java:856) at org.slf4j.impl.Log4jLoggerAdapter.error(Log4jLoggerAdapter.java:576) at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:140) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestLeaderElectionZkExpiry Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=16681, name=OverseerAutoScalingTriggerThread-72269253585862666-dummy.host.com:8984_solr-n_0000000007, state=RUNNABLE, group=Overseer autoscaling triggers] at org.apache.lucene.util.TestRuleLimitSysouts$DelegateStream.write(TestRuleLimitSysouts.java:128) at java.io.PrintStream.write(PrintStream.java:480) at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221) at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291) at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295) at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141) at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229) at org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59) at org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324) at org.apache.log4j.WriterAppender.append(WriterAppender.java:162) at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:251) at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66) at org.apache.log4j.Category.callAppenders(Category.java:206) at org.apache.log4j.Category.forcedLog(Category.java:391) at org.apache.log4j.Category.log(Category.java:856) at org.slf4j.impl.Log4jLoggerAdapter.error(Log4jLoggerAdapter.java:576) at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:140) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=16681, name=OverseerAutoScalingTriggerThread-72269253585862666-dummy.host.com:8984_solr-n_0000000007, state=RUNNABLE, group=Overseer autoscaling triggers] at org.apache.lucene.util.TestRuleLimitSysouts$DelegateStream.write(TestRuleLimitSysouts.java:128) at java.io.PrintStream.write(PrintStream.java:480) at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221) at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291) at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295) at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141) at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229) at org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59) at org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324) at org.apache.log4j.WriterAppender.append(WriterAppender.java:162) at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:251) at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66) at org.apache.log4j.Category.callAppenders(Category.java:206) at org.apache.log4j.Category.forcedLog(Category.java:391) at org.apache.log4j.Category.log(Category.java:856) at org.slf4j.impl.Log4jLoggerAdapter.error(Log4jLoggerAdapter.java:576) at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:140) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1]:0) FAILED: org.apache.solr.cloud.TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs Error Message: Could not load collection from ZK: org.apache.solr.cloud.TestRandomFlRTGCloud_collection Stack Trace: org.apache.solr.common.SolrException: Could not load collection from ZK: org.apache.solr.cloud.TestRandomFlRTGCloud_collection at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1:243F7A04674CEEED]:0) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1231) at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:669) at org.apache.solr.common.cloud.ClusterState$CollectionRef.get(ClusterState.java:386) at org.apache.solr.client.solrj.impl.CloudSolrClient.getDocCollection(CloudSolrClient.java:1207) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:850) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:818) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) at org.apache.solr.cloud.TestRandomFlRTGCloud.assertRTG(TestRandomFlRTGCloud.java:396) at org.apache.solr.cloud.TestRandomFlRTGCloud.assertOneIter(TestRandomFlRTGCloud.java:266) at org.apache.solr.cloud.TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs(TestRandomFlRTGCloud.java:223) 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) Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215) at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340) at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1243) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1229) ... 50 more FAILED: org.apache.solr.logging.TestLogWatcher.testLog4jWatcher Error Message: expected:<50> but was:<1> Stack Trace: java.lang.AssertionError: expected:<50> but was:<1> at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.logging.TestLogWatcher.testLog4jWatcher(TestLogWatcher.java:52) 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 org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:251) at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:368) at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:13) Build Log: [...truncated 12556 lines...] [junit4] Suite: org.apache.solr.cloud.TestRandomFlRTGCloud [junit4] 2> 669010 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-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/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/init-core-data-001 [junit4] 2> 669011 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 669012 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=client auth uses too much RAM, value=NaN, ssl=NaN, clientAuth=0.0) [junit4] 2> 669012 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001 [junit4] 2> 669013 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 669013 INFO (Thread-543) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 669013 INFO (Thread-543) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 669015 ERROR (Thread-543) [ ] 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> 669120 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35647 [junit4] 2> 669160 INFO (zkConnectionManagerCallback-798-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669163 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100c061ae7b0000, likely client has closed socket [junit4] 2> 669194 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 669198 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 669216 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T00:27:37+03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 669295 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 669295 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 669295 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 669295 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2d8c4109{/solr,null,AVAILABLE} [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2563214d{SSL,[ssl, http/1.1]}{127.0.0.1:42811} [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.e.j.s.Server Started @679685ms [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42811} [junit4] 2> 669297 ERROR (jetty-launcher-795-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 669297 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T21:30:26.477Z [junit4] 2> 669360 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 669361 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 669361 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 669361 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 669361 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 669361 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 669362 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a7da037{/solr,null,AVAILABLE} [junit4] 2> 669362 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@684fb881{SSL,[ssl, http/1.1]}{127.0.0.1:36970} [junit4] 2> 669362 INFO (jetty-launcher-795-thread-2) [ ] o.e.j.s.Server Started @679750ms [junit4] 2> 669362 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36970} [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2a671b35{/solr,null,AVAILABLE} [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@56bc0897{SSL,[ssl, http/1.1]}{127.0.0.1:37581} [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.e.j.s.Server Started @679751ms [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37581} [junit4] 2> 669363 ERROR (jetty-launcher-795-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 669363 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 669364 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T21:30:26.544Z [junit4] 2> 669364 ERROR (jetty-launcher-795-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 669364 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 669364 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 669364 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 669364 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-12T21:30:26.544Z [junit4] 2> 669388 INFO (zkConnectionManagerCallback-800-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669404 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 669404 INFO (zkConnectionManagerCallback-802-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669417 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 669422 INFO (zkConnectionManagerCallback-804-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669427 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 669601 INFO (jetty-launcher-795-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35647/solr [junit4] 2> 669620 INFO (zkConnectionManagerCallback-808-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669681 INFO (jetty-launcher-795-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35647/solr [junit4] 2> 669705 INFO (jetty-launcher-795-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35647/solr [junit4] 2> 669710 INFO (zkConnectionManagerCallback-816-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669710 INFO (zkConnectionManagerCallback-814-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669711 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100c061ae7b0004, likely client has closed socket [junit4] 2> 669712 INFO (zkConnectionManagerCallback-818-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669843 INFO (zkConnectionManagerCallback-820-thread-1-processing-n:127.0.0.1:36970_solr) [n:127.0.0.1:36970_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 669953 INFO (zkConnectionManagerCallback-822-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 670336 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 670337 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36970_solr [junit4] 2> 670338 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.Overseer Overseer (id=72269119809585160-127.0.0.1:36970_solr-n_0000000000) starting [junit4] 2> 670355 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 670384 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42811_solr [junit4] 2> 670434 INFO (zkCallback-821-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 670435 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 670449 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 670450 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37581_solr [junit4] 2> 670465 INFO (zkCallback-821-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 670575 INFO (zkCallback-817-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 670582 INFO (zkCallback-819-thread-1-processing-n:127.0.0.1:36970_solr) [n:127.0.0.1:36970_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 670594 INFO (zkConnectionManagerCallback-829-thread-1-processing-n:127.0.0.1:36970_solr) [n:127.0.0.1:36970_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 670651 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 670716 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35647/solr ready [junit4] 2> 670732 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42811.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 670756 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42811.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 670757 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42811.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 670774 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node1/. [junit4] 2> 670819 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36970_solr [junit4] 2> 670819 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37581.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 670838 INFO (zkCallback-821-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 670855 INFO (zkCallback-817-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 670867 INFO (zkCallback-819-thread-1-processing-n:127.0.0.1:36970_solr) [n:127.0.0.1:36970_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 670876 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37581.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 670876 INFO (zkConnectionManagerCallback-833-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 670876 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37581.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 670877 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node3/. [junit4] 2> 670932 INFO (zkCallback-828-thread-1-processing-n:127.0.0.1:36970_solr) [n:127.0.0.1:36970_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 670936 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 670939 INFO (jetty-launcher-795-thread-1) [n:127.0.0.1:42811_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35647/solr ready [junit4] 2> 670976 INFO (zkConnectionManagerCallback-837-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 670997 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 670998 INFO (jetty-launcher-795-thread-3) [n:127.0.0.1:37581_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35647/solr ready [junit4] 2> 671071 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36970.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 671107 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36970.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 671107 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36970.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 671108 INFO (jetty-launcher-795-thread-2) [n:127.0.0.1:36970_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node2/. [junit4] 2> 671312 INFO (zkConnectionManagerCallback-839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 671338 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100c061ae7b000d, likely client has closed socket [junit4] 2> 671339 INFO (zkConnectionManagerCallback-843-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 671340 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 671340 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35647/solr ready [junit4] 2> 672854 INFO (qtp1930049981-2805) [n:127.0.0.1:36970_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&collection.configName=org.apache.solr.cloud.TestRandomFlRTGCloud_config-set&name=org.apache.solr.cloud.TestRandomFlRTGCloud_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 672872 INFO (OverseerThreadFactory-1039-thread-1-processing-n:127.0.0.1:36970_solr) [n:127.0.0.1:36970_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection org.apache.solr.cloud.TestRandomFlRTGCloud_collection [junit4] 2> 673030 INFO (OverseerStateUpdate-72269119809585160-127.0.0.1:36970_solr-n_0000000000) [n:127.0.0.1:36970_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"org.apache.solr.cloud.TestRandomFlRTGCloud_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:37581/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 673043 INFO (OverseerStateUpdate-72269119809585160-127.0.0.1:36970_solr-n_0000000000) [n:127.0.0.1:36970_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"org.apache.solr.cloud.TestRandomFlRTGCloud_collection", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:42811/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 673447 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestRandomFlRTGCloud_config-set&newCollection=true&collection=org.apache.solr.cloud.TestRandomFlRTGCloud_collection&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&coreNodeName=core_node3&name=org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin [junit4] 2> 673448 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 673487 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestRandomFlRTGCloud_config-set&newCollection=true&collection=org.apache.solr.cloud.TestRandomFlRTGCloud_collection&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&coreNodeName=core_node4&name=org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2&action=CREATE&numShards=2&shard=shard2&wt=javabin [junit4] 2> 673488 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 673625 INFO (zkCallback-817-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 673625 INFO (zkCallback-821-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 673625 INFO (zkCallback-821-thread-2-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 673625 INFO (zkCallback-817-thread-2-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 674554 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 674554 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 674593 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.s.IndexSchema [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] Schema name=test [junit4] 2> 674600 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.s.IndexSchema [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] Schema name=test [junit4] 2> 674610 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id [junit4] 2> 674617 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id [junit4] 2> 674632 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2' using configuration from collection org.apache.solr.cloud.TestRandomFlRTGCloud_collection, trusted=true [junit4] 2> 674632 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42811.solr.core.org.apache.solr.cloud.TestRandomFlRTGCloud_collection.shard2.replica_n2' (registry 'solr.core.org.apache.solr.cloud.TestRandomFlRTGCloud_collection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 674633 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 674633 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SolrCore [[org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node1/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node1/./org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/data/] [junit4] 2> 674652 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=19.76953125, floorSegmentMB=0.822265625, forceMergeDeletesPctAllowed=2.163578947914677, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5617353461081368 [junit4] 2> 674655 WARN (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 674670 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1' using configuration from collection org.apache.solr.cloud.TestRandomFlRTGCloud_collection, trusted=true [junit4] 2> 674670 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37581.solr.core.org.apache.solr.cloud.TestRandomFlRTGCloud_collection.shard1.replica_n1' (registry 'solr.core.org.apache.solr.cloud.TestRandomFlRTGCloud_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a5dbe0b [junit4] 2> 674671 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 674671 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SolrCore [[org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node3/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestRandomFlRTGCloud_9AE3B2F8A0EB66C1-001/tempDir-001/node3/./org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1/data/] [junit4] 2> 674686 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=19.76953125, floorSegmentMB=0.822265625, forceMergeDeletesPctAllowed=2.163578947914677, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5617353461081368 [junit4] 2> 674725 WARN (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 674928 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 674928 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 674929 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 674929 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 674931 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18956422125226277] [junit4] 2> 675035 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@559deea2[org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] main] [junit4] 2> 675036 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 675036 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 675050 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 675050 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 675051 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18956422125226277] [junit4] 2> 675051 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2926fdf9[org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] main] [junit4] 2> 675062 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.cloud.TestRandomFlRTGCloud_config-set [junit4] 2> 675063 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestRandomFlRTGCloud_config-set [junit4] 2> 675064 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 675137 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.cloud.TestRandomFlRTGCloud_config-set [junit4] 2> 675138 INFO (searcherExecutor-1045-thread-1-processing-n:127.0.0.1:37581_solr x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1 s:shard1 c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection r:core_node3) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SolrCore [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] Registered new searcher Searcher@2926fdf9[org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 675138 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestRandomFlRTGCloud_config-set [junit4] 2> 675151 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594768996256710656 [junit4] 2> 675153 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 675153 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594768996258807808 [junit4] 2> 675158 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update terms at /collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/terms/shard1 to Terms{values={core_node3=0}, version=0} [junit4] 2> 675248 INFO (searcherExecutor-1044-thread-1-processing-n:127.0.0.1:42811_solr x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2 s:shard2 c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection r:core_node4) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SolrCore [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] Registered new searcher Searcher@559deea2[org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 675250 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 675250 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 675250 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37581/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1/ [junit4] 2> 675250 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 675250 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:37581/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1/ has no replicas [junit4] 2> 675251 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 675252 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update terms at /collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/terms/shard2 to Terms{values={core_node4=0}, version=0} [junit4] 2> 675267 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 675267 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 675267 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/ [junit4] 2> 675267 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 675267 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37581/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1/ shard1 [junit4] 2> 675267 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.SyncStrategy https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/ has no replicas [junit4] 2> 675267 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 675300 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/ shard2 [junit4] 2> 675428 INFO (zkCallback-821-thread-2-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675428 INFO (zkCallback-817-thread-2-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675428 INFO (zkCallback-821-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675428 INFO (zkCallback-817-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675447 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 675449 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestRandomFlRTGCloud_config-set&newCollection=true&collection=org.apache.solr.cloud.TestRandomFlRTGCloud_collection&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&coreNodeName=core_node3&name=org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=2002 [junit4] 2> 675460 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 675466 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestRandomFlRTGCloud_config-set&newCollection=true&collection=org.apache.solr.cloud.TestRandomFlRTGCloud_collection&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&coreNodeName=core_node4&name=org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=1978 [junit4] 2> 675491 INFO (qtp1930049981-2805) [n:127.0.0.1:36970_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> 675567 INFO (zkCallback-821-thread-1-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675567 INFO (zkCallback-821-thread-2-processing-n:127.0.0.1:42811_solr) [n:127.0.0.1:42811_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675568 INFO (zkCallback-817-thread-2-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 675568 INFO (zkCallback-817-thread-1-processing-n:127.0.0.1:37581_solr) [n:127.0.0.1:37581_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/state.json] for collection [org.apache.solr.cloud.TestRandomFlRTGCloud_collection] has occurred - updating... (live nodes size: [3]) [junit4] 2> 676505 INFO (qtp1930049981-2805) [n:127.0.0.1:36970_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&collection.configName=org.apache.solr.cloud.TestRandomFlRTGCloud_config-set&name=org.apache.solr.cloud.TestRandomFlRTGCloud_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=3651 [junit4] 2> 676511 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: org.apache.solr.cloud.TestRandomFlRTGCloud_collection failOnTimeout:true timeout (sec):330 [junit4] 1> - [junit4] 1> replica:core_node3 rstate:active live:true [junit4] 1> replica:core_node4 rstate:active live:true [junit4] 1> no one is recoverying [junit4] 2> 676512 INFO (SUITE-TestRandomFlRTGCloud-seed#[9AE3B2F8A0EB66C1]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: org.apache.solr.cloud.TestRandomFlRTGCloud_collection [junit4] 2> 676712 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomizedUpdatesAndRTGs [junit4] 2> 676712 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud Starting 6226 iters by seeding 2 of 219 max docs [junit4] 2> 676713 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 0 = SolrInputDocument(fields: [id=0, aaa_i=1661438809, bbb_i=1265477494, ccc_s=lxnhtavslk, ddd_s=vzvufmzbnp, eee_s=skrtnvh, fff_s=qd, ggg_s=muzkztp, hhh_s=, geo_1_srpt=POINT( 42 -42 ), geo_2_srpt=POINT( 42 -3 ), next_2_ids_ss=[1, 2], axx_i=-1330185601, ayy_i=-529977292, azz_s=jts]) [junit4] 2> 676859 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.s.AbstractSpatialPrefixTreeFieldType location_rpt{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={geo=true, maxDistErr=0.001, distErrPct=0.025, distanceUnits=kilometers, class=solr.SpatialRecursivePrefixTreeFieldType}} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11 [junit4] 2> 676900 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.s.AbstractSpatialPrefixTreeFieldType location_rpt{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={geo=true, maxDistErr=0.001, distErrPct=0.025, distanceUnits=kilometers, class=solr.SpatialRecursivePrefixTreeFieldType}} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11 [junit4] 2> 676916 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update terms at /collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/terms/shard1 to Terms{values={core_node3=1}, version=1} [junit4] 2> 676916 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0 (1594768997940723712)]} 0 160 [junit4] 2> 676917 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 1 = SolrInputDocument(fields: [id=1, aaa_i=-756431253, bbb_i=1684587044, ccc_s=ilmsuct, ddd_s=rjg, eee_s=krmfkx, fff_s=ejdmmcq, ggg_s=duwkei, hhh_s=nxuzm, geo_1_srpt=POINT( 42 23 ), geo_2_srpt=POINT( 42 -29 ), next_2_ids_ss=[2, 3], axx_i=-998328056, ayy_i=-968909338, azz_s=njkf]) [junit4] 2> 676920 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1594768998109544448)]} 0 2 [junit4] 2> 676929 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud UPD: numDocsThisIter=4 numDocsThisIterThatExist=0, docIds=[95, 64, 212, 19] [junit4] 2> 676929 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 95 = SolrInputDocument(fields: [id=95, aaa_i=-2143930877, bbb_i=600248589, ccc_s=mlav, ddd_s=isms, eee_s=syuchubla, fff_s=zuwdtzl, ggg_s=oycbyimodf, hhh_s=, geo_1_srpt=POINT( 42 62 ), geo_2_srpt=POINT( 42 -42 ), next_2_ids_ss=[96, 97], axx_i=1750598581, ayy_i=114059447, azz_s=f]) [junit4] 2> 676973 INFO (OverseerCollectionConfigSetProcessor-72269119809585160-127.0.0.1:36970_solr-n_0000000000) [n:127.0.0.1:36970_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> 677092 INFO (qtp943035213-2788) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.s.AbstractSpatialPrefixTreeFieldType location_rpt{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={geo=true, maxDistErr=0.001, distErrPct=0.025, distanceUnits=kilometers, class=solr.SpatialRecursivePrefixTreeFieldType}} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11 [junit4] 2> 677092 INFO (qtp943035213-2788) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.s.AbstractSpatialPrefixTreeFieldType location_rpt{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={geo=true, maxDistErr=0.001, distErrPct=0.025, distanceUnits=kilometers, class=solr.SpatialRecursivePrefixTreeFieldType}} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11 [junit4] 2> 677161 INFO (qtp943035213-2788) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update terms at /collections/org.apache.solr.cloud.TestRandomFlRTGCloud_collection/terms/shard2 to Terms{values={core_node4=1}, version=1} [junit4] 2> 677161 INFO (qtp943035213-2788) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95 (1594768998290948096)]} 0 78 [junit4] 2> 677162 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 64 = SolrInputDocument(fields: [id=64, aaa_i=-1062660679, bbb_i=919161933, ccc_s=dnhwxjxtzz, ddd_s=, eee_s=nualjieb, fff_s=vgrxwg, ggg_s=t, hhh_s=nammqendw, geo_1_srpt=POINT( 42 10 ), geo_2_srpt=POINT( 42 -42 ), next_2_ids_ss=[65, 66], axx_i=-798631806, ayy_i=929574855, azz_s=qeztshbbu]) [junit4] 2> 677241 INFO (qtp1685831004-2803) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64 (1594768998380077056)]} 0 77 [junit4] 2> 677242 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 212 = SolrInputDocument(fields: [id=212, aaa_i=-121065355, bbb_i=-1433471138, ccc_s=mwmtd, ddd_s=, eee_s=, fff_s=w, ggg_s=ixn, hhh_s=ozho, geo_1_srpt=POINT( 42 62 ), geo_2_srpt=POINT( 42 23 ), next_2_ids_ss=[213, 214], axx_i=-403428656, ayy_i=-370270838, azz_s=llilqcxjxl]) [junit4] 2> 677314 INFO (qtp1685831004-2799) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[212 (1594768998506954752)]} 0 51 [junit4] 2> 677367 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 19 = SolrInputDocument(fields: [id=19, aaa_i=-1739944540, bbb_i=-63194232, ccc_s=rnphldauat, ddd_s=, eee_s=fdgypwmq, fff_s=thnyc, ggg_s=uxbsqo, hhh_s=ht, geo_1_srpt=POINT( 42 -16 ), geo_2_srpt=POINT( 42 23 ), next_2_ids_ss=[20, 21], axx_i=-96820521, ayy_i=183928147, azz_s=jgf]) [junit4] 2> 677456 INFO (qtp943035213-2787) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19 (1594768998591889408)]} 0 78 [junit4] 2> 677459 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud UPD: numDocsThisIter=5 numDocsThisIterThatExist=0, docIds=[7, 197, 22, 2, 16] [junit4] 2> 677459 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 7 = SolrInputDocument(fields: [id=7, aaa_i=-728661000, bbb_i=1693080803, ccc_s=hgftfg, ddd_s=, eee_s=jktc, fff_s=dtooetfv, ggg_s=, hhh_s=aq, geo_1_srpt=POINT( 42 -29 ), geo_2_srpt=POINT( 42 -29 ), next_2_ids_ss=[8, 9], axx_i=-1371097430, ayy_i=-318782211, azz_s=fgnfglbc]) [junit4] 2> 677471 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1594768998688358400)]} 0 11 [junit4] 2> 677471 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 197 = SolrInputDocument(fields: [id=197, aaa_i=-2023604387, bbb_i=-257500098, ccc_s=nt, ddd_s=dpdabtnqa, eee_s=m, fff_s=q, ggg_s=cku, hhh_s=nurxdmlh, geo_1_srpt=POINT( 42 -29 ), geo_2_srpt=POINT( 42 23 ), next_2_ids_ss=[198, 199], axx_i=-172997185, ayy_i=-1545265766, azz_s=rqelqvwbte]) [junit4] 2> 677566 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/&wt=javabin&version=2}{add=[197 (1594768998753370112)]} 0 34 [junit4] 2> 677567 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 95 [junit4] 2> 677574 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 22 = SolrInputDocument(fields: [id=22, aaa_i=-1610306270, bbb_i=-990649613, ccc_s=uznqrlf, ddd_s=f, eee_s=y, fff_s=, ggg_s=ria, hhh_s=dwzh, geo_1_srpt=POINT( 42 -29 ), geo_2_srpt=POINT( 42 -42 ), next_2_ids_ss=[23, 24], axx_i=-1663454279, ayy_i=-2020825415, azz_s=acqole]) [junit4] 2> 677611 INFO (qtp1930049981-2806) [n:127.0.0.1:36970_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 677658 INFO (qtp1685831004-2799) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/&wt=javabin&version=2}{add=[22 (1594768998884442112)]} 0 1 [junit4] 2> 677659 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 3 [junit4] 2> 677684 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 2 = SolrInputDocument(fields: [id=2, aaa_i=1357631739, bbb_i=-30079287, ccc_s=bo, ddd_s=guexzwm, eee_s=chb, fff_s=ckqg, ggg_s=k, hhh_s=, geo_1_srpt=POINT( 42 -3 ), geo_2_srpt=POINT( 42 36 ), next_2_ids_ss=[3, 4], axx_i=139013357, ayy_i=-23901748, azz_s=jzurzdb]) [junit4] 2> 677686 INFO (qtp943035213-2787) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1594768998913802240)]} 0 1 [junit4] 2> 677686 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud ADD: 16 = SolrInputDocument(fields: [id=16, aaa_i=1618465881, bbb_i=-738626744, ccc_s=yb, ddd_s=, eee_s=xfldgb, fff_s=dp, ggg_s=fa, hhh_s=hv, geo_1_srpt=POINT( 42 62 ), geo_2_srpt=POINT( 42 36 ), next_2_ids_ss=[17, 18], axx_i=1030168766, ayy_i=1492915235, azz_s=uipc]) [junit4] 2> 677722 INFO (qtp1685831004-2801) [n:127.0.0.1:37581_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard1 r:core_node3 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16 (1594768998951550976)]} 0 1 [junit4] 2> 677723 INFO (TEST-TestRandomFlRTGCloud.testRandomizedUpdatesAndRTGs-seed#[9AE3B2F8A0EB66C1]) [ ] o.a.s.c.TestRandomFlRTGCloud RTG: numDocsThisIter=4 numDocsThisIterThatExist=1, docIds=[192, 86, 212, 149] [junit4] 2> 677913 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/ [junit4] 2> 677913 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:42811/solr/org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2/ [junit4] 2> 677913 INFO (qtp943035213-2785) [n:127.0.0.1:42811_solr c:org.apache.solr.cloud.TestRandomFlRTGCloud_collection s:shard2 r:core_node4 x:org.apache.solr.cloud.TestRandomFlRTGCloud_collection_shard2_replica_n2] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:37581/solr/org.apache.s [...truncated too long message...] unit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000) [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063) [junit4] 2> 22) Thread[id=16230, name=TEST-BasicDistributedZkTest.test-seed#[9AE3B2F8A0EB66C1]-SendThread(127.0.0.1:36010), state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest] [junit4] 2> at java.lang.Thread.sleep(Native Method) [junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105) [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000) [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063) [junit4] 2> 23) Thread[id=16272, name=commitScheduler-4302-thread-1, state=WAITING, group=TGRP-BasicDistributedZkTest] [junit4] 2> at sun.misc.Unsafe.park(Native Method) [junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081) [junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 24) Thread[id=16206, name=qtp1785639090-16206, state=RUNNABLE, group=TGRP-BasicDistributedZkTest] [junit4] 2> at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) [junit4] 2> at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) [junit4] 2> at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) [junit4] 2> at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) [junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) [junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) [junit4] 2> at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:375) [junit4] 2> at org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:304) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:179) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {regex_dup_A_s=PostingsFormat(name=Memory), regex_dup_B_s=PostingsFormat(name=LuceneVarGapFixedInterval), SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=PostingsFormat(name=Memory), genre_s=PostingsFormat(name=Memory), author_t=PostingsFormat(name=Memory), series_t=PostingsFormat(name=Memory), rnd_b=Lucene50(blocksize=128), oddField_s=PostingsFormat(name=Memory), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), cat=PostingsFormat(name=LuceneVarGapFixedInterval), foo_b=PostingsFormat(name=Memory), name=Lucene50(blocksize=128), inStock=PostingsFormat(name=Memory), id=Lucene50(blocksize=128), text=PostingsFormat(name=Memory)}, docValues:{regex_dup_A_s=DocValuesFormat(name=Memory), other_tl1=DocValuesFormat(name=Lucene70), regex_dup_B_s=DocValuesFormat(name=Lucene70), range_facet_l_dv=DocValuesFormat(name=Asserting), SubjectTerms_mfacet=DocValuesFormat(name=Lucene70), n_l1=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Lucene70), genre_s=DocValuesFormat(name=Memory), n_td1=DocValuesFormat(name=Lucene70), n_d1=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Lucene70), n_f1=DocValuesFormat(name=Lucene70), series_t=DocValuesFormat(name=Lucene70), n_tl1=DocValuesFormat(name=Lucene70), oddField_s=DocValuesFormat(name=Memory), n_tf1=DocValuesFormat(name=Lucene70), price=DocValuesFormat(name=Memory), a_t=DocValuesFormat(name=Lucene70), cat=DocValuesFormat(name=Lucene70), sequence_i=DocValuesFormat(name=Memory), inStock=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Asserting), text=DocValuesFormat(name=Memory), intDvoDefault=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Lucene70), foo_i=DocValuesFormat(name=Lucene70), val_i=DocValuesFormat(name=Asserting), n_dt1=DocValuesFormat(name=Lucene70), author_t=DocValuesFormat(name=Lucene70), a_i1=DocValuesFormat(name=Lucene70), n_ti1=DocValuesFormat(name=Asserting), rnd_b=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene70), n_tdt1=DocValuesFormat(name=Lucene70), foo_b=DocValuesFormat(name=Memory), name=DocValuesFormat(name=Asserting), id_i1=DocValuesFormat(name=Lucene70), foo_d=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene70), foo_f=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1849, maxMBSortInHeap=6.104455259248984, sim=RandomSimilarity(queryNorm=true): {}, locale=pt-BR, timezone=Asia/Pontianak [junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=39,free=338004016,total=528482304 [junit4] 2> NOTE: All tests run in this JVM: [TestTriggerIntegration, TriLevelCompositeIdRoutingTest, TestWriterPerf, TestNoOpRegenerator, IndexBasedSpellCheckerTest, TestQuerySenderNoQuery, TestSolrFieldCacheBean, SimpleFacetsTest, TestHighFrequencyDictionaryFactory, SolrRequestParserTest, TestPhraseSuggestions, TestHashQParserPlugin, TestCloudInspectUtil, TestLeaderInitiatedRecoveryThread, TestTolerantUpdateProcessorRandomCloud, OpenCloseCoreStressTest, TestReloadDeadlock, TestConfigsApi, FullSolrCloudDistribCmdsTest, MetricTriggerTest, TestConfig, ScheduledTriggerTest, HdfsDirectoryFactoryTest, DebugComponentTest, TestOnReconnectListenerSupport, ExternalFileFieldSortTest, SSLMigrationTest, MissingSegmentRecoveryTest, TestCloudNestedDocsSort, AutoscalingHistoryHandlerTest, TestConfigSetsAPIZkFailure, TestComplexPhraseLeadingWildcard, TestExactStatsCache, TestRestManager, TestClusterProperties, TestSortByMinMaxFunction, TestZkAclsWithHadoopAuth, TestExclusionRuleCollectionAccess, TestComponentsName, TestHighlightDedupGrouping, OutOfBoxZkACLAndCredentialsProvidersTest, CurrencyFieldTypeTest, CollectionTooManyReplicasTest, RAMDirectoryFactoryTest, BadCopyFieldTest, ExecutePlanActionTest, OpenExchangeRatesOrgProviderTest, TestSegmentSorting, TestSkipOverseerOperations, CloneFieldUpdateProcessorFactoryTest, TestMaxScoreQueryParser, TestGenericDistributedQueue, CdcrRequestHandlerTest, TestBinaryField, TestInfoStreamLogging, TokenizerChainTest, SpellingQueryConverterTest, TestRebalanceLeaders, TestUseDocValuesAsStored, TestFastOutputStream, DocValuesNotIndexedTest, TestIBSimilarityFactory, DistributedTermsComponentTest, HdfsBasicDistributedZk2Test, MBeansHandlerTest, ConnectionReuseTest, EchoParamsTest, TestCollectionsAPIViaSolrCloudCluster, SolrJmxReporterTest, TestExportWriter, TestSchemaVersionResource, TestChildDocTransformer, TestSubQueryTransformerDistrib, TestFieldSortValues, SolrIndexSplitterTest, DocumentAnalysisRequestHandlerTest, SmileWriterTest, TestHashPartitioner, CursorPagingTest, OverseerTaskQueueTest, RuleEngineTest, ShardRoutingTest, TestStressLiveNodes, CoreSorterTest, ReplicationFactorTest, TestCoreBackup, TestPseudoReturnFields, TestBlendedInfixSuggestions, TestIndexSearcher, TestCollationField, UninvertDocValuesMergePolicyTest, TestFieldCacheSort, TestScoreJoinQPScore, TestInPlaceUpdatesStandalone, TestSystemCollAutoCreate, TestRTimerTree, HdfsWriteToMultipleCollectionsTest, SolrGangliaReporterTest, OverseerModifyCollectionTest, TestNumericTerms64, TestSolrConfigHandler, SolrPluginUtilsTest, TestRemoteStreaming, TestIndexingPerformance, ZkSolrClientTest, DistributedFacetPivotLargeTest, TestLegacyField, ParsingFieldUpdateProcessorsTest, UtilsToolTest, StatsReloadRaceTest, CollectionStateFormat2Test, DeleteNodeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestComputePlanAction, TestPullReplicaErrorHandling, TestConfigOverlay, TestJoin, FieldAnalysisRequestHandlerTest, DistributedQueryComponentOptimizationTest, PluginInfoTest, TestSolrCloudWithSecureImpersonation, TestJavabinTupleStreamParser, TestLuceneMatchVersion, SolrShardReporterTest, ChaosMonkeyNothingIsSafeTest, ZkStateReaderTest, InfixSuggestersTest, TestExtendedDismaxParser, BasicDistributedZkTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZkTest -Dtests.seed=9AE3B2F8A0EB66C1 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=pt-BR -Dtests.timezone=Asia/Pontianak -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | BasicDistributedZkTest (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). [junit4] > at __randomizedtesting.SeedInfo.seed([9AE3B2F8A0EB66C1]:0) [junit4] Completed [783/783 (4!)] on J0 in 7283.68s, 1 test, 2 errors <<< FAILURES! [...truncated 49542 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org