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: [email protected]
For additional commands, e-mail: [email protected]
