Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1323/ Java: 32bit/jdk1.8.0_162 -server -XX:+UseG1GC
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation Error Message: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: 1) Thread[id=30542, name=jetty-launcher-8519-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) 2) Thread[id=30554, name=jetty-launcher-8519-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: 1) Thread[id=30542, name=jetty-launcher-8519-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) 2) Thread[id=30554, name=jetty-launcher-8519-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) at __randomizedtesting.SeedInfo.seed([75CBBAD444CD9B6E]:0) FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalFailedMove Error Message: Collection not found: MoveReplicaHDFSTest_failed_coll_false Stack Trace: org.apache.solr.common.SolrException: Collection not found: MoveReplicaHDFSTest_failed_coll_false at __randomizedtesting.SeedInfo.seed([75CBBAD444CD9B6E:52C0A59C688F0577]:0) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:851) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138) at org.apache.solr.cloud.MoveReplicaTest.addDocs(MoveReplicaTest.java:390) at org.apache.solr.cloud.MoveReplicaTest.testFailedMove(MoveReplicaTest.java:263) at org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalFailedMove(MoveReplicaHDFSTest.java:67) 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) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeAddedTriggerRestoreState Error Message: The trigger did not fire at all Stack Trace: java.lang.AssertionError: The trigger did not fire at all at __randomizedtesting.SeedInfo.seed([75CBBAD444CD9B6E:FDF633AB7E0D7AC3]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeAddedTriggerRestoreState(TriggerIntegrationTest.java:426) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13078 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> 896192 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_75CBBAD444CD9B6E-001/init-core-data-001 [junit4] 2> 896192 WARN (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14 [junit4] 2> 896192 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 896194 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 896194 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_75CBBAD444CD9B6E-001/tempDir-001 [junit4] 2> 896195 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 896197 INFO (Thread-2271) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 896197 INFO (Thread-2271) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 896201 ERROR (Thread-2271) [ ] 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> 896297 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43479 [junit4] 2> 896299 INFO (zkConnectionManagerCallback-1833-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896301 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T03:27:37+06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 896305 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T03:27:37+06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 896320 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 896320 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 896320 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 896321 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16fa809{/solr,null,AVAILABLE} [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f483ec{HTTP/1.1,[http/1.1]}{127.0.0.1:46449} [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.e.j.s.Server Started @897691ms [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46449} [junit4] 2> 896322 ERROR (jetty-launcher-1830-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 896322 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-09T12:56:11.100Z [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1df7b44{/solr,null,AVAILABLE} [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@988925{HTTP/1.1,[http/1.1]}{127.0.0.1:42917} [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.e.j.s.Server Started @897695ms [junit4] 2> 896325 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42917} [junit4] 2> 896326 ERROR (jetty-launcher-1830-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 896326 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 896326 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 896326 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 896326 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-09T12:56:11.104Z [junit4] 2> 896329 INFO (zkConnectionManagerCallback-1835-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896329 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 896336 INFO (zkConnectionManagerCallback-1837-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896337 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 896341 INFO (jetty-launcher-1830-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43479/solr [junit4] 2> 896343 INFO (zkConnectionManagerCallback-1841-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896345 INFO (jetty-launcher-1830-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43479/solr [junit4] 2> 896351 INFO (zkConnectionManagerCallback-1844-thread-1-processing-n:127.0.0.1:46449_solr) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896351 INFO (zkConnectionManagerCallback-1847-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896366 INFO (zkConnectionManagerCallback-1849-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896381 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 896381 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42917_solr [junit4] 2> 896382 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.Overseer Overseer (id=72119468634406918-127.0.0.1:42917_solr-n_0000000000) starting [junit4] 2> 896401 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42917_solr [junit4] 2> 896404 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 896416 INFO (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 896417 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 896417 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:42917_solr] [junit4] 2> 896417 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 896417 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 896441 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 896441 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 896444 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 896445 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 896447 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46449_solr [junit4] 2> 896447 INFO (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 896454 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 896474 INFO (zkCallback-1843-thread-1-processing-n:127.0.0.1:46449_solr) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 896592 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46449.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896601 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46449.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896601 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46449.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896602 INFO (jetty-launcher-1830-thread-2) [n:127.0.0.1:46449_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_75CBBAD444CD9B6E-001/tempDir-001/node2/. [junit4] 2> 896622 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42917.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896631 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42917.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896632 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42917.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896633 INFO (jetty-launcher-1830-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_75CBBAD444CD9B6E-001/tempDir-001/node1/. [junit4] 2> 896665 INFO (zkConnectionManagerCallback-1855-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896668 INFO (zkConnectionManagerCallback-1859-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896668 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 896669 INFO (SUITE-TriggerIntegrationTest-seed#[75CBBAD444CD9B6E]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43479/solr ready [junit4] 2> 896679 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling [junit4] 2> 896680 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2 [junit4] 2> 896680 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 896680 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 896681 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 896681 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 896681 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 896686 INFO (qtp24105637-8541) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 896687 INFO (qtp5432808-8600) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 896688 DEBUG (qtp5432808-8538) [n:127.0.0.1:46449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 896688 INFO (qtp5432808-8538) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 896688 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 896691 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr] [junit4] 2> 896691 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 896692 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 896692 INFO (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 896693 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 896693 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 896693 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 896694 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 896697 INFO (qtp24105637-8553) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 896698 INFO (qtp5432808-8546) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 896698 DEBUG (qtp5432808-8544) [n:127.0.0.1:46449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 896699 INFO (qtp5432808-8544) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 896699 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 896699 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr] [junit4] 2> 896699 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 896699 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr] [junit4] 2> 896699 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 896699 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 896700 INFO (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 896700 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 896701 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 896701 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 896701 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 896701 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T03:27:37+06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 896707 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 896707 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 896707 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 896707 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d90e0f{/solr,null,AVAILABLE} [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b9e0f2{HTTP/1.1,[http/1.1]}{127.0.0.1:44911} [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.Server Started @898077ms [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44911} [junit4] 2> 896708 ERROR (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 896708 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-09T12:56:11.486Z [junit4] 2> 896715 INFO (zkConnectionManagerCallback-1861-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896715 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 896724 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43479/solr [junit4] 2> 896727 INFO (zkConnectionManagerCallback-1865-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896732 INFO (zkConnectionManagerCallback-1867-thread-1-processing-n:127.0.0.1:44911_solr) [n:127.0.0.1:44911_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 896735 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 896736 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 896737 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44911_solr [junit4] 2> 896738 INFO (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 896738 INFO (zkCallback-1843-thread-1-processing-n:127.0.0.1:46449_solr) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 896738 INFO (zkCallback-1858-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 896740 INFO (zkCallback-1866-thread-1-processing-n:127.0.0.1:44911_solr) [n:127.0.0.1:44911_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 896854 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44911.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896862 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44911.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896862 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44911.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 896863 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:44911_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_75CBBAD444CD9B6E-001/tempDir-001/node3/. [junit4] 2> 897695 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 897695 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 897695 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:44911_solr at time 944132910968614 [junit4] 2> 897695 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:44911_solr] added at times [944132910968614], now=944132911165373 [junit4] 2> 897695 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"35aaf12c76f26T40uah7cem4v3qna98zyiep1uc", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":944132910968614, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944132910968614], [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 897696 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger2, node_added_trigger1] [junit4] 2> 897696 INFO (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 897697 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"35aaf12c76f26T40uah7cem4v3qna98zyiep1uc", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":944132910968614, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944132910968614], [junit4] 2> "_enqueue_time_":944132911820350, [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 897697 INFO (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger1 [junit4] 2> 897698 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 897698 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 897698 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=35aaf12c76f26T40uah7cem4v3qna98zyiep1uc [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:44911_solr at time 944137914240217 [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing registered processor for nodes: [127.0.0.1:44911_solr] added at times [944137914240217], now=944137914282583 [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"35ab03cff4cd9T40uah7cem4v3qna98zyiep1ue", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":944137914240217, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944137914240217], [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 902698 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger2, node_added_trigger1] [junit4] 2> 902699 INFO (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 902699 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"35ab03cff4cd9T40uah7cem4v3qna98zyiep1ue", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":944137914240217, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944137914240217], [junit4] 2> "_enqueue_time_":944137914441143, [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 902699 INFO (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest last action at 1518180972475000000 current time = 1518180977477000000 [junit4] 2> real diff: 5002000000 [junit4] 2> min diff: 4998000000 [junit4] 2> 902699 INFO (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger2 [junit4] 2> 902700 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 902700 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 902700 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=35ab03cff4cd9T40uah7cem4v3qna98zyiep1ue [junit4] 2> 902701 INFO (qtp24105637-8632) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 902702 INFO (qtp4973687-8606) [n:127.0.0.1:44911_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 902703 INFO (qtp5432808-8544) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 902703 DEBUG (qtp24105637-8553) [n:127.0.0.1:42917_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 902704 INFO (qtp24105637-8553) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 902704 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 902704 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902704 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 902704 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902705 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902705 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 902705 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 902705 INFO (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 902705 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 902705 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 3 [junit4] 2> 902707 INFO (qtp24105637-8555) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 902708 INFO (qtp4973687-8612) [n:127.0.0.1:44911_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 902710 INFO (qtp5432808-8545) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 902711 DEBUG (qtp5432808-8599) [n:127.0.0.1:46449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 902711 INFO (qtp5432808-8599) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=5 [junit4] 2> 902711 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 902711 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902711 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 902712 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902712 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902712 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 902712 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:44911_solr] [junit4] 2> 902712 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 902713 INFO (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 902713 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 902713 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with currently live nodes: 3 [junit4] 2> 902717 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@b9e0f2{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 902717 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=232277 [junit4] 2> 902717 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 902717 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14caa6e: rootName = solr_44911, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1eac424 [junit4] 2> 902723 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 902723 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f78928: rootName = solr_44911, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1017a8a [junit4] 2> 902727 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 902727 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@94fae9: rootName = solr_44911, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@162ffc7 [junit4] 2> 902728 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44911_solr [junit4] 2> 902728 INFO (zkCallback-1858-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 902728 INFO (zkCallback-1843-thread-1-processing-n:127.0.0.1:46449_solr) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 902728 INFO (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 902728 INFO (zkCallback-1866-thread-1-processing-n:127.0.0.1:44911_solr) [n:127.0.0.1:44911_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 902728 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 902729 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 902729 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@d90e0f{/solr,null,UNAVAILABLE} [junit4] 2> 902729 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 903706 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 2 [junit4] 2> 903706 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:44911_solr [junit4] 2> 903706 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:44911_solr] [junit4] 2> 903707 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"35ab07915cc6bT40uah7cem4v3qna98zyiep1ug", [junit4] 2> "source":"node_lost_trigger1", [junit4] 2> "eventTime":944138922347627, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944138922347627], [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 903707 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2, node_added_trigger2, node_lost_trigger1, node_added_trigger1] [junit4] 2> 903707 INFO (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 903707 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"35ab07915cc6bT40uah7cem4v3qna98zyiep1ug", [junit4] 2> "source":"node_lost_trigger1", [junit4] 2> "eventTime":944138922347627, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944138922347627], [junit4] 2> "_enqueue_time_":944138922557760, [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 903707 INFO (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_lost_trigger1 [junit4] 2> 903708 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 5000ms [junit4] 2> 903708 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 903708 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 5000ms [junit4] 2> 903708 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 903708 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=35ab07915cc6bT40uah7cem4v3qna98zyiep1ug [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with currently live nodes: 2 [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:44911_solr [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:44911_solr] [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-3) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 2 [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"35ab1a332738cT40uah7cem4v3qna98zyiep1ui", [junit4] 2> "source":"node_lost_trigger2", [junit4] 2> "eventTime":944143923835788, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944143923835788], [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 908708 DEBUG (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2, node_added_trigger2, node_lost_trigger1, node_added_trigger1] [junit4] 2> 908708 INFO (ScheduledTrigger-3875-thread-1) [n:127.0.0.1:42917_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 908709 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"35ab1a332738cT40uah7cem4v3qna98zyiep1ui", [junit4] 2> "source":"node_lost_trigger2", [junit4] 2> "eventTime":944143923835788, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944143923835788], [junit4] 2> "_enqueue_time_":944143924093900, [junit4] 2> "nodeNames":["127.0.0.1:44911_solr"]}} [junit4] 2> 908709 INFO (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest last action at 1518180978485000000 current time = 1518180983487000000 [junit4] 2> real diff: 5002000000 [junit4] 2> min diff: 4998000000 [junit4] 2> 908709 INFO (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_lost_trigger2 [junit4] 2> 908709 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerThrottling [junit4] 2> 908709 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 5000ms [junit4] 2> 908709 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 908709 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 5000ms [junit4] 2> 908709 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 908709 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=35ab1a332738cT40uah7cem4v3qna98zyiep1ui [junit4] 2> 908720 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTriggerRestoreState [junit4] 2> 908720 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 7 [junit4] 2> 908720 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 908720 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 908722 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 908722 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 908722 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 908722 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T03:27:37+06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 908723 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 908723 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 908723 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 908723 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7312b7{/solr,null,AVAILABLE} [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37af54{HTTP/1.1,[http/1.1]}{127.0.0.1:41435} [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.Server Started @910093ms [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41435} [junit4] 2> 908724 ERROR (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 908724 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-09T12:56:23.502Z [junit4] 2> 908725 INFO (zkConnectionManagerCallback-1871-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 908726 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 908733 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43479/solr [junit4] 2> 908734 INFO (zkConnectionManagerCallback-1875-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 908736 INFO (zkConnectionManagerCallback-1877-thread-1-processing-n:127.0.0.1:41435_solr) [n:127.0.0.1:41435_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 908739 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 908740 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 908740 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41435_solr [junit4] 2> 908741 INFO (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 908741 INFO (zkCallback-1843-thread-1-processing-n:127.0.0.1:46449_solr) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 908741 INFO (zkCallback-1858-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 908741 INFO (zkCallback-1876-thread-1-processing-n:127.0.0.1:41435_solr) [n:127.0.0.1:41435_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 908828 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41435.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 908840 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41435.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 908840 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41435.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@a3fab2 [junit4] 2> 908842 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [n:127.0.0.1:41435_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_75CBBAD444CD9B6E-001/tempDir-001/node4/. [junit4] 2> 908877 INFO (qtp26105209-8663) [n:127.0.0.1:41435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 908878 INFO (qtp24105637-8555) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 908878 INFO (qtp5432808-8599) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 908879 DEBUG (qtp26105209-8638) [n:127.0.0.1:41435_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 908879 INFO (qtp26105209-8638) [n:127.0.0.1:41435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 908879 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 908879 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr, 127.0.0.1:41435_solr] [junit4] 2> 908879 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 908880 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@37af54{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 908880 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=22418579 [junit4] 2> 908880 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 908880 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d04a20: rootName = solr_41435, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@12f9c0e [junit4] 2> 908880 INFO (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 908880 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 908880 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 908880 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger with currently live nodes: 3 [junit4] 2> 908885 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 908885 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14350a5: rootName = solr_41435, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1017a8a [junit4] 2> 908889 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 908889 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7686e0: rootName = solr_41435, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@162ffc7 [junit4] 2> 908890 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41435_solr [junit4] 2> 908890 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 908890 INFO (zkCallback-1858-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 908890 INFO (zkCallback-1843-thread-1-processing-n:127.0.0.1:46449_solr) [n:127.0.0.1:46449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 908890 INFO (zkCallback-1876-thread-1-processing-n:127.0.0.1:41435_solr) [n:127.0.0.1:41435_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 908890 INFO (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 908890 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 908892 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7312b7{/solr,null,UNAVAILABLE} [junit4] 2> 908892 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 909881 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger with currently live nodes: 2 [junit4] 2> 909881 DEBUG (ScheduledTrigger-3875-thread-4) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:41435_solr [junit4] 2> 910396 INFO (qtp24105637-8541) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 910398 INFO (qtp5432808-8546) [n:127.0.0.1:46449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 910398 DEBUG (qtp24105637-8554) [n:127.0.0.1:42917_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 910398 INFO (qtp24105637-8554) [n:127.0.0.1:42917_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=5 [junit4] 2> 910399 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9 [junit4] 2> 910399 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:46449_solr, 127.0.0.1:42917_solr] [junit4] 2> 910399 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9 [junit4] 2> 910399 INFO (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 910399 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:41435_solr [junit4] 2> 910400 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 910400 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger with currently live nodes: 2 [junit4] 2> 910400 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:41435_solr] [junit4] 2> 910400 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9 [junit4] 2> 910400 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"35ab20805dce7T40uah7cem4v3qna98zyiep1uk", [junit4] 2> "source":"node_lost_restore_trigger", [junit4] 2> "eventTime":944145615412455, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944145615412455], [junit4] 2> "nodeNames":["127.0.0.1:41435_solr"]}} [junit4] 2> 910400 DEBUG (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_restore_trigger] [junit4] 2> 910400 INFO (ScheduledTrigger-3875-thread-2) [n:127.0.0.1:42917_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 910400 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"35ab20805dce7T40uah7cem4v3qna98zyiep1uk", [junit4] 2> "source":"node_lost_restore_trigger", [junit4] 2> "eventTime":944145615412455, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[944145615412455], [junit4] 2> "_enqueue_time_":944145615930498, [junit4] 2> "nodeNames":["127.0.0.1:41435_solr"]}} [junit4] 2> 910401 INFO (TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTriggerRestoreState [junit4] 2> 910401 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_restore_trigger after 5000ms [junit4] 2> 910401 DEBUG (AutoscalingActionExecutor-3876-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=35ab20805dce7T40uah7cem4v3qna98zyiep1uk [junit4] 2> 910410 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMetricTrigger [junit4] 2> 910410 INFO (TEST-TriggerIntegrationTest.testMetricTrigger-seed#[75CBBAD444CD9B6E]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 10 [junit4] 2> 910410 DEBUG (zkCallback-1848-thread-1-processing-n:127.0.0.1:42917_solr) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 10 [junit4] 2> 910410 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 10 [junit4] 2> 910411 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 910411 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 910411 DEBUG (OverseerAutoScalingTriggerThread-72119468634406918-127.0.0.1:42917_solr-n_0000000000) [n:127.0.0.1:42917_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10, lastZnodeVersion 10 [junit4 [...truncated too long message...] StatsReloadRaceTest, TestQueryUtils, DOMUtilTest, TriLevelCompositeIdRoutingTest, TestLegacyFieldCache, BasicZkTest, PrimUtilsTest, TestSQLHandlerNonCloud, TestSchemaVersionResource, ZkShardTermsTest, TestNamedUpdateProcessors, TestCloudSearcherWarming, SpatialHeatmapFacetsTest, PeerSyncTest, TestDynamicFieldCollectionResource, TestReqParamsAPI, SyncSliceTest, AutoAddReplicasPlanActionTest, TestRuleBasedAuthorizationPlugin, TestPartialUpdateDeduplication, TestBM25SimilarityFactory, DirectoryFactoryTest, HighlighterMaxOffsetTest, DirectSolrConnectionTest, ScriptEngineTest, TermsComponentTest, DistributedDebugComponentTest, TestReload, ResourceLoaderTest, TestReplicationHandler, TestImpersonationWithHadoopAuth, TestCursorMarkWithoutUniqueKey, TestFuzzyAnalyzedSuggestions, PrimitiveFieldTypeTest, TestSolrFieldCacheBean, SolrMetricManagerTest, TestZkChroot, TestRTGBase, DistributedMLTComponentTest, OverseerRolesTest, AsyncCallRequestStatusResponseTest, TestFileDictionaryLookup, TestSkipOverseerOperations, TestSolrCloudSnapshots, AddReplicaTest, SampleTest, JvmMetricsTest, DirectUpdateHandlerOptimizeTest, SmileWriterTest, RequiredFieldsTest, LargeFieldTest, TestPostingsSolrHighlighter, SoftAutoCommitTest, TestCoreBackup, TestMaxScoreQueryParser, HighlighterTest, BlockJoinFacetSimpleTest, TestElisionMultitermQuery, DistributedIntervalFacetingTest, CdcrUpdateLogTest, SOLR749Test, TestLeaderInitiatedRecoveryThread, ZkStateWriterTest, ZkControllerTest, TestManagedStopFilterFactory, TestReplicaProperties, DocumentBuilderTest, TestConfigsApi, TestConfigSetImmutable, TestCorePropertiesReload, TestLegacyField, BitVectorTest, CdcrVersionReplicationTest, SolrGangliaReporterTest, TestSearchPerf, TestPerFieldSimilarity, TestFieldCache, HdfsTlogReplayBufferedWhileIndexingTest, HLLSerializationTest, CoreMergeIndexesAdminHandlerTest, AnalyticsQueryTest, EnumFieldTest, DistributedFacetPivotLargeTest, OverseerTest, CollectionTooManyReplicasTest, TriggerIntegrationTest, TestMacroExpander, TestJavabinTupleStreamParser, RestartWhileUpdatingTest, PropertiesRequestHandlerTest, TestStressLiveNodes, TestOnReconnectListenerSupport, TestDistribIDF, CollectionStateFormat2Test, TestCloudDeleteByQuery, SliceStateTest, BasicFunctionalityTest, SchemaApiFailureTest, TestFieldResource, MultiThreadedOCPTest, TestSolrJ, TestSolr4Spatial, SolrIndexMetricsTest, OverriddenZkACLAndCredentialsProvidersTest, ConcurrentDeleteAndCreateCollectionTest, UpdateLogTest, SortSpecParsingTest, TestReloadDeadlock, TestNoOpRegenerator, TestNestedDocsSort, SolrInfoBeanTest, TestPerFieldSimilarityWithDefaultOverride, TestBulkSchemaConcurrent, LeaderFailoverAfterPartitionTest, CachingDirectoryFactoryTest, TestDistributedGrouping, UUIDUpdateProcessorFallbackTest, TestSolrIndexConfig, TestDistribStateManager, LoggingHandlerTest, FieldMutatingUpdateProcessorTest, ClassificationUpdateProcessorIntegrationTest, CdcrReplicationDistributedZkTest, TestCharFilters, SuggesterTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, ShardRoutingCustomTest, TestFaceting, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, TestJoin, TestStressVersions, DistributedTermsComponentTest, SimpleFacetsTest, SolrCoreTest, QueryElevationComponentTest, ConvertedLegacyTest, TestFunctionQuery, TestIndexSearcher, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, SimplePostToolTest, TestCoreDiscovery, SuggesterFSTTest, TestStressLucene, TestTrie, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, QueryEqualityTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestWriterPerf, TestReversedWildcardFilterFactory, TestValueSourceCache, TermVectorComponentTest, FastVectorHighlighterTest, IndexSchemaTest, TestCollationField, ReturnFieldsTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestComponentsName, HighlighterConfigTest, TestQuerySenderListener, AlternateDirectoryTest, ResponseLogComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, MultiTermTest, MinimalSchemaTest, ExternalFileFieldSortTest, TestSolrCoreProperties, TestCodecSupport, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, PluginInfoTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CloudExitableDirectoryReaderTest, CollectionsAPISolrJTest, CreateRoutedAliasTest, DeleteNodeTest, DeleteShardTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, DistributedVersionInfoTest, LIRRollingUpdatesTest, LeaderElectionContextKeyTest, LeaderInitiatedRecoveryOnShardRestartTest, LegacyCloudClusterPropTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, MoveReplicaTest, OverseerStatusTest, OverseerTaskQueueTest, RecoveryAfterSoftCommitTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, SolrCloudExampleTest, TestCloudRecovery, TestDistribDocBasedVersion, TestMiniSolrCloudClusterSSL, TestPullReplicaErrorHandling, TestSegmentSorting, TestSolrCloudWithSecureImpersonation] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithSecureImpersonation -Dtests.seed=75CBBAD444CD9B6E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-CH -Dtests.timezone=Europe/Ulyanovsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: [junit4] > 1) Thread[id=30542, name=jetty-launcher-8519-thread-1-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] > at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) [junit4] > at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] > at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) [junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) [junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) [junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) [junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) [junit4] > at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) [junit4] > at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) [junit4] > 2) Thread[id=30554, name=jetty-launcher-8519-thread-2-EventThread, state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328) [junit4] > at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277) [junit4] > at org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323) [junit4] > at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105) [junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288) [junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279) [junit4] > at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41) [junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244) [junit4] > at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44) [junit4] > at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61) [junit4] > at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506) [junit4] > at __randomizedtesting.SeedInfo.seed([75CBBAD444CD9B6E]:0) [junit4] Completed [568/774 (3!)] on J1 in 40.55s, 12 tests, 1 error <<< FAILURES! [...truncated 44726 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org