Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/880/
Java: 64bit/jdk1.8.0_172 -XX:-UseCompressedOops -XX:+UseSerialGC
4 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling
Error Message:
Both triggers should have fired by now
Stack Trace:
java.lang.AssertionError: Both triggers should have fired by now
at
__randomizedtesting.SeedInfo.seed([629440E21F62B7B8:99B6E8C7CDC8542A]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:222)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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.testTriggerThrottling
Error Message:
Both triggers should have fired by now
Stack Trace:
java.lang.AssertionError: Both triggers should have fired by now
at
__randomizedtesting.SeedInfo.seed([629440E21F62B7B8:99B6E8C7CDC8542A]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:270)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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.testTriggerThrottling
Error Message:
Both triggers should have fired by now
Stack Trace:
java.lang.AssertionError: Both triggers should have fired by now
at
__randomizedtesting.SeedInfo.seed([629440E21F62B7B8:99B6E8C7CDC8542A]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:222)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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.testTriggerThrottling
Error Message:
Both triggers should have fired by now
Stack Trace:
java.lang.AssertionError: Both triggers should have fired by now
at
__randomizedtesting.SeedInfo.seed([629440E21F62B7B8:99B6E8C7CDC8542A]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:222)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
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:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
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 14539 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> 2939346 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-001\init-core-data-001
[junit4] 2> 2939349 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2939350 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2939353 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-001\tempDir-001
[junit4] 2> 2939353 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2939353 INFO (Thread-4660) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2939353 INFO (Thread-4660) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2939359 ERROR (Thread-4660) [ ] 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> 2939454 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:61783
[junit4] 2> 2939463 INFO (zkConnectionManagerCallback-8856-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939474 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2939475 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@672af777{/solr,null,AVAILABLE}
[junit4] 2> 2939476 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1f799144{/solr,null,AVAILABLE}
[junit4] 2> 2939478 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3f79abb7{SSL,[ssl,
http/1.1]}{127.0.0.1:61788}
[junit4] 2> 2939478 INFO (jetty-launcher-8853-thread-2) [ ]
o.e.j.s.Server Started @2911702ms
[junit4] 2> 2939478 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=61788}
[junit4] 2> 2939478 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5f83d9dc{SSL,[ssl,
http/1.1]}{127.0.0.1:61787}
[junit4] 2> 2939478 INFO (jetty-launcher-8853-thread-1) [ ]
o.e.j.s.Server Started @2911702ms
[junit4] 2> 2939478 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=61787}
[junit4] 2> 2939482 ERROR (jetty-launcher-8853-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2939482 ERROR (jetty-launcher-8853-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.6.0
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.6.0
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-09T16:21:25.689Z
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2939482 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-09T16:21:25.689Z
[junit4] 2> 2939486 INFO (zkConnectionManagerCallback-8860-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939486 INFO (zkConnectionManagerCallback-8858-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939487 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2939487 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2939500 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ece9aed0001, likely client has closed socket
[junit4] 2> 2939653 INFO (jetty-launcher-8853-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61783/solr
[junit4] 2> 2939655 INFO (zkConnectionManagerCallback-8864-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939656 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ece9aed0003, likely client has closed socket
[junit4] 2> 2939658 INFO (zkConnectionManagerCallback-8866-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939765 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:61788_solr
[junit4] 2> 2939765 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.c.Overseer Overseer
(id=72126651121336324-127.0.0.1:61788_solr-n_0000000000) starting
[junit4] 2> 2939772 INFO (zkConnectionManagerCallback-8873-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939774 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:61783/solr ready
[junit4] 2> 2939775 INFO
(OverseerStateUpdate-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[n:127.0.0.1:61788_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:61788_solr
[junit4] 2> 2939775 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:61788_solr
[junit4] 2> 2939776 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2939777 INFO
(OverseerStateUpdate-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[n:127.0.0.1:61788_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2939777 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 2939777 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 2939777 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 2939783 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: []
[junit4] 2> 2939783 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2939783 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 2939784 INFO (zkCallback-8872-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2939785 DEBUG (ScheduledTrigger-11620-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2939831 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2939870 INFO (jetty-launcher-8853-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61783/solr
[junit4] 2> 2939872 INFO (zkConnectionManagerCallback-8878-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939875 INFO (zkConnectionManagerCallback-8880-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939883 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2939888 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2939888 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:61787_solr
[junit4] 2> 2939894 INFO (zkCallback-8865-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2939894 INFO (zkCallback-8872-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2939895 INFO (zkCallback-8879-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2939895 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61788.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2939903 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61788.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2939903 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61788.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2939905 INFO (jetty-launcher-8853-thread-2)
[n:127.0.0.1:61788_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-001\tempDir-001\node2\.
[junit4] 2> 2939966 INFO (zkConnectionManagerCallback-8887-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2939967 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2939969 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:61783/solr ready
[junit4] 2> 2939969 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2939991 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61787.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2939999 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61787.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2940000 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61787.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2940001 INFO (jetty-launcher-8853-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-001\tempDir-001\node1\.
[junit4] 2> 2940034 INFO (zkConnectionManagerCallback-8890-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940041 INFO (zkConnectionManagerCallback-8895-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940042 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2940043 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61783/solr ready
[junit4] 2> 2940090 INFO (qtp1662953285-29849) [n:127.0.0.1:61787_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 2940099 INFO (qtp1795108213-29852) [n:127.0.0.1:61788_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=3
[junit4] 2> 2940100 DEBUG (qtp1795108213-29856) [n:127.0.0.1:61788_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2940100 INFO (qtp1795108213-29856) [n:127.0.0.1:61788_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=29
[junit4] 2> 2940100 DEBUG (zkCallback-8872-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 2940101 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 2940112 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2940114 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 2940123 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testContinueTriggersOnOverseerRestart
[junit4] 2> 2940126 INFO (zkConnectionManagerCallback-8897-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2940131 INFO (qtp1662953285-29849) [n:127.0.0.1:61787_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2940136 INFO (qtp1662953285-29849) [n:127.0.0.1:61787_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 2940138 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 2940140 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 3
[junit4] 2> 2940140 DEBUG (zkCallback-8872-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 2940140 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 2940140 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2940141 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2940141 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2940142 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 2940144 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3f79abb7{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2940145 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=553397022
[junit4] 2> 2940145 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 2940145 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@294fedb9: rootName =
solr_61788, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@11b5a2ef
[junit4] 2> 2940155 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 2940155 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@70e6f6e0: rootName =
solr_61788, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@6483c2a7
[junit4] 2> 2940161 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 2940161 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@270b12d9: rootName =
solr_61788, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@265bc61
[junit4] 2> 2940163 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:61788_solr
[junit4] 2> 2940164 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 2940164 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2940165 INFO (zkCallback-8879-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2940165 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.Overseer Overseer
(id=72126651121336324-127.0.0.1:61788_solr-n_0000000000) closing
[junit4] 2> 2940165 INFO (zkCallback-8865-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2940165 INFO (zkCallback-8886-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2940165 INFO
(OverseerStateUpdate-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[n:127.0.0.1:61788_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:61788_solr
[junit4] 2> 2940165 INFO (zkCallback-8872-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2940165 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 2940165 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 2940165 INFO (zkCallback-8894-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2940166 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 2940166 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 2940166 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 2940166 WARN
(OverseerAutoScalingTriggerThread-72126651121336324-127.0.0.1:61788_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 2940166 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2940167 INFO (zkCallback-8894-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2940168 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ece9aed0004, likely client has closed socket
[junit4] 2> 2940168 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1f799144{/solr,null,UNAVAILABLE}
[junit4] 2> 2940168 INFO (zkCallback-8879-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61787_solr
[junit4] 2> 2940169 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2940172 INFO (zkCallback-8879-thread-1)
[n:127.0.0.1:61787_solr ] o.a.s.c.Overseer Overseer
(id=72126651121336327-127.0.0.1:61787_solr-n_0000000001) starting
[junit4] 2> 2940177 INFO
(OverseerStateUpdate-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[n:127.0.0.1:61787_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:61787_solr
[junit4] 2> 2940177 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2940178 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 4
[junit4] 2> 2940178 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion -1
[junit4] 2> 2940178 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 4
[junit4] 2> 2940182 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:61787_solr]
[junit4] 2> 2940182 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2940182 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion 4
[junit4] 2> 2940182 DEBUG (ScheduledTrigger-11636-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2941186 DEBUG (ScheduledTrigger-11636-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2942188 DEBUG (ScheduledTrigger-11636-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2943189 DEBUG (ScheduledTrigger-11636-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2944191 DEBUG (ScheduledTrigger-11636-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2945174 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z;
git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2945175 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2945175 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2945175 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2945175 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7b652574{/solr,null,AVAILABLE}
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@de53d28{SSL,[ssl,
http/1.1]}{127.0.0.1:61830}
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.Server Started @2917401ms
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=61830}
[junit4] 2> 2945177 ERROR
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 7.6.0
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2945177 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-09T16:21:31.384Z
[junit4] 2> 2945182 INFO (zkConnectionManagerCallback-8901-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2945182 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2945192 DEBUG (ScheduledTrigger-11636-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2945336 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61783/solr
[junit4] 2> 2945339 INFO (zkConnectionManagerCallback-8905-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2945342 INFO (zkConnectionManagerCallback-8907-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2945354 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2945359 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2945359 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:61830_solr
[junit4] 2> 2945362 INFO (zkCallback-8879-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2945362 INFO (zkCallback-8894-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2945362 INFO (zkCallback-8894-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2945363 INFO (zkCallback-8906-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2945374 INFO (zkCallback-8886-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2945496 INFO (zkConnectionManagerCallback-8914-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2945501 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2945502 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:61783/solr ready
[junit4] 2> 2945503 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2945556 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61830.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2945567 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61830.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2945567 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_61830.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2945570 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[n:127.0.0.1:61830_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-001\tempDir-001\node3\.
[junit4] 2> 2945750 INFO (qtp1662953285-29847) [n:127.0.0.1:61787_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2945792 INFO (qtp1662953285-29847) [n:127.0.0.1:61787_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=42
[junit4] 2> 2945806 INFO (qtp1662953285-29847) [n:127.0.0.1:61787_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=4
[junit4] 2> 2945830 INFO (qtp317538307-29940) [n:127.0.0.1:61830_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 2945831 DEBUG (qtp1662953285-29849) [n:127.0.0.1:61787_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2945846 INFO (qtp1662953285-29849) [n:127.0.0.1:61787_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=52
[junit4] 2> 2945849 DEBUG (zkCallback-8886-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 2945860 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 2945879 INFO
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 2945879 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR -
Initial livenodes: [127.0.0.1:61830_solr, 127.0.0.1:61787_solr]
[junit4] 2> 2945879 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR
instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 2945880 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5f83d9dc{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2945896 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=106475347
[junit4] 2> 2945896 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 2945896 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@45e313c5: rootName =
solr_61787, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@41c1a983
[junit4] 2> 2945900 DEBUG (ScheduledTrigger-11636-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2945901 DEBUG (ScheduledTrigger-11636-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 2945902 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion 5
[junit4] 2> 2945908 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 2945908 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@640d8a22: rootName =
solr_61787, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@6483c2a7
[junit4] 2> 2945916 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 2945916 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@76792bca: rootName =
solr_61787, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@265bc61
[junit4] 2> 2945963 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:61787_solr
[junit4] 2> 2945975 INFO (zkCallback-8906-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2945975 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 2945975 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.Overseer Overseer
(id=72126651121336327-127.0.0.1:61787_solr-n_0000000001) closing
[junit4] 2> 2945979 INFO
(OverseerStateUpdate-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[n:127.0.0.1:61787_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:61787_solr
[junit4] 2> 2945979 INFO (zkCallback-8886-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2945979 INFO (zkCallback-8894-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2945979 INFO (zkCallback-8894-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2945982 INFO (zkCallback-8879-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2945984 WARN
(OverseerAutoScalingTriggerThread-72126651121336327-127.0.0.1:61787_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 2945985 INFO (zkCallback-8913-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2945988 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 2945988 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 2945988 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 2945988 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 2945989 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 2945989 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2945991 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@672af777{/solr,null,UNAVAILABLE}
[junit4] 2> 2945991 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2945991 INFO (zkCallback-8906-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61830_solr
[junit4] 2> 2946006 INFO (zkCallback-8906-thread-1)
[n:127.0.0.1:61830_solr ] o.a.s.c.Overseer Overseer
(id=72126651121336334-127.0.0.1:61830_solr-n_0000000002) starting
[junit4] 2> 2946037 INFO
(OverseerStateUpdate-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[n:127.0.0.1:61830_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:61830_solr
[junit4] 2> 2946038 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 5
[junit4] 2> 2946038 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion -1
[junit4] 2> 2946038 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 2946043 INFO
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 2946043 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR -
Initial livenodes: [127.0.0.1:61830_solr]
[junit4] 2> 2946043 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR
instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 2946049 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:61830_solr]
[junit4] 2> 2946050 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path:
127.0.0.1:61787_solr
[junit4] 2> 2946051 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2946051 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2946052 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion 5
[junit4] 2> 2946057 DEBUG (ScheduledTrigger-11650-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2947055 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2947055 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2947061 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2948057 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2948057 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2948063 DEBUG (ScheduledTrigger-11650-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2949057 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2949057 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2949065 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2950058 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2950058 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2950065 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2951059 DEBUG (ScheduledTrigger-11650-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2951059 DEBUG (ScheduledTrigger-11650-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2951075 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2952104 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2952106 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2952115 DEBUG (ScheduledTrigger-11650-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2953218 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2953218 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2953218 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2954319 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2954319 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2954319 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2955419 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2955419 DEBUG (ScheduledTrigger-11650-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2955419 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2956424 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z;
git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 2956424 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2956424 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2956424 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2956425 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4b26f699{/solr,null,AVAILABLE}
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@503cdf58{SSL,[ssl,
http/1.1]}{127.0.0.1:61892}
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.e.j.s.Server Started @2928221ms
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=61892}
[junit4] 2> 2956426 ERROR
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 7.6.0
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2956426 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-09T16:21:42.633Z
[junit4] 2> 2956432 INFO (zkConnectionManagerCallback-8917-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2956433 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2956474 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ece9aed0010, likely client has closed socket
[junit4] 2> 2956519 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 2956519 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2956519 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 2956573 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61783/solr
[junit4] 2> 2956583 INFO (zkConnectionManagerCallback-8921-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2956583 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ece9aed0011, likely client has closed socket
[junit4] 2> 2956587 INFO (zkConnectionManagerCallback-8923-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2956623 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2956631 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2956632 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:61892_solr
[junit4] 2> 2956641 INFO (zkCallback-8894-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2956641 INFO (zkCallback-8894-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2956641 INFO (zkCallback-8906-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2956641 INFO (zkCallback-8922-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2956657 INFO (zkCallback-8913-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2956735 INFO (zkConnectionManagerCallback-8930-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2956774 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2956778 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:61783/solr ready
[junit4] 2> 2956778 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 2956823 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61892.solr.node'
(registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2956834 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61892.solr.jvm'
(registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2956835 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61892.solr.jetty'
(registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a500d0d
[junit4] 2> 2956838 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-001\tempDir-001\node4\.
[junit4] 2> 2957620 DEBUG (ScheduledTrigger-11650-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 2957620 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2957620 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 2957620 DEBUG (ScheduledTrigger-11650-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:61892_solr at time
1053745192792499
[junit4] 2> 2958739 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 2958739 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 2958739 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR firing
registered processor for nodes: [127.0.0.1:61892_solr] added at times
[1053745192792499], now=1053746213910600
[junit4] 2> 2958739 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"3be602be671b3T4ylrneu7h3rh0dgt70zc4daj2",
[junit4] 2> "source":"node_added_triggerCTOOR",
[junit4] 2> "eventTime":1053745192792499,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1053745192792499],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:61892_solr"]}}
[junit4] 2> 2958744 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
.scheduled_maintenance, node_added_triggerCTOOR]
[junit4] 2> 2958761 INFO (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 2958761 DEBUG (ScheduledTrigger-11650-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR - removing
marker path: /autoscaling/nodeAdded/127.0.0.1:61892_solr
[junit4] 2> 2958762 DEBUG (AutoscalingActionExecutor-11651-thread-1) [
] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"3be602be671b3T4ylrneu7h3rh0dgt70zc4daj2",
[junit4] 2> "source":"node_added_triggerCTOOR",
[junit4] 2> "eventTime":1053745192792499,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1053745192792499],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1053746214724600,
[junit4] 2> "nodeNames":["127.0.0.1:61892_solr"]}}
[junit4] 2> 2958763 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[629440E21F62B7B8])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testContinueTriggersOnOverseerRestart
[junit4] 2> 2958764 DEBUG (AutoscalingActionExecutor-11651-thread-1) [
] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 5000ms
[junit4] 2> 2958764 DEBUG (AutoscalingActionExecutor-11651-thread-1) [
] o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after
5000ms
[junit4] 2> 2958764 DEBUG (AutoscalingActionExecutor-11651-thread-1) [
] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_triggerCTOOR after
5000ms
[junit4] 2> 2958764 DEBUG (AutoscalingActionExecutor-11651-thread-1) [
] o.a.s.c.a.ScheduledTriggers -- processing took 1 ms for event
id=3be602be671b3T4ylrneu7h3rh0dgt70zc4daj2
[junit4] 2> 2959192 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 2959222 INFO (zkConnectionManagerCallback-8933-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2959279 INFO (qtp317538307-29940) [n:127.0.0.1:61830_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2959379 INFO (qtp317538307-29940) [n:127.0.0.1:61830_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=90
[junit4] 2> 2959382 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 2959389 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers -- removing trigger: .scheduled_maintenance
[junit4] 2> 2959389 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers -- removing trigger: node_added_triggerCTOOR
[junit4] 2> 2959390 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 6
[junit4] 2> 2959392 DEBUG (zkCallback-8913-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 2959392 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 6
[junit4] 2> 2959392 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2959392 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2959397 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.e.j.s.AbstractConnector Stopped ServerConnector@de53d28{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2959397 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1899558776
[junit4] 2> 2959401 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2959401 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2959401 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5ba41bd2: rootName =
solr_61830, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@2cfc3573
[junit4] 2> 2959402 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 6
[junit4] 2> 2959409 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2959409 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@40306f5a: rootName =
solr_61830, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@6483c2a7
[junit4] 2> 2959416 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2959416 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@79b714b6: rootName =
solr_61830, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@265bc61
[junit4] 2> 2959428 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:61830_solr
[junit4] 2> 2959429 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 2959430 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.Overseer Overseer
(id=72126651121336334-127.0.0.1:61830_solr-n_0000000002) closing
[junit4] 2> 2959430 INFO
(OverseerStateUpdate-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[n:127.0.0.1:61830_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:61830_solr
[junit4] 2> 2959431 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 2959431 WARN
(OverseerAutoScalingTriggerThread-72126651121336334-127.0.0.1:61830_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 2959431 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 2959431 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 2959431 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 2959432 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 2959432 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 2959441 INFO (zkCallback-8906-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959444 INFO (zkCallback-8894-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959471 INFO (zkCallback-8894-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959471 INFO (zkCallback-8894-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959471 INFO (zkCallback-8913-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959471 INFO (zkCallback-8922-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959441 INFO (zkCallback-8929-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2959515 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ece9aed000e, likely client has closed socket
[junit4] 2> 2959516 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7b652574{/solr,null,UNAVAILABLE}
[junit4] 2> 2959516 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[629440E21F62B7B8]) [
] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2959516 INFO (zkCallback-8922-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61892_solr
[junit4] 2> 2959519 INFO (zkCallback-8922-thread-1)
[n:127.0.0.1:61892_solr ] o.a.s.c.Overseer Overseer
(id=72126651121336338-127.0.0.1:61892_solr-n_0000000003) starting
[junit4] 2> 2959525 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336338-127.0.0.1:61892_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 2959526 INFO
(OverseerStateUpdate-72126651121336338-127.0.0.1:61892_solr-n_0000000003)
[n:127.0.0.1:61892_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:61892_solr
[junit4] 2> 2959526 DEBUG
(OverseerAutoScalingTriggerThread-72126651121336338-127.0.0.1:61892_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 7
[junit4] 2> 2959527
[...truncated too long message...]
ser-408-thread-1) [ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:59077_solr
[junit4] 2> 257869 INFO (jetty-closer-408-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 257869 INFO (jetty-closer-408-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 257869 INFO (jetty-closer-408-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7881e147: rootName =
solr_59095, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@632cc6d4
[junit4] 2> 257869 INFO (zkCallback-584-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 257869 INFO (jetty-closer-408-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72126825653665837-127.0.0.1:59077_solr-n_0000000009) closing
[junit4] 2> 257869 WARN
(OverseerAutoScalingTriggerThread-72126825653665837-127.0.0.1:59077_solr-n_0000000009)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 257869 INFO
(OverseerStateUpdate-72126825653665837-127.0.0.1:59077_solr-n_0000000009)
[n:127.0.0.1:59077_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:59077_solr
[junit4] 2> 257869 INFO (zkCallback-600-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 257869 INFO (zkCallback-607-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 257869 INFO (zkCallback-591-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 257869 DEBUG (jetty-closer-408-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 257873 DEBUG (jetty-closer-408-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 257873 DEBUG (jetty-closer-408-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 257874 DEBUG (jetty-closer-408-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 257874 DEBUG (jetty-closer-408-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 257874 DEBUG (jetty-closer-408-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 257874 INFO (jetty-closer-408-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:59095_solr
[junit4] 2> 257875 INFO (jetty-closer-408-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 257877 INFO (zkCallback-600-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59095_solr
[junit4] 2> 257877 INFO (jetty-closer-408-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5d0db899{/solr,null,UNAVAILABLE}
[junit4] 2> 257878 INFO (zkCallback-607-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 257878 INFO (jetty-closer-408-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 257878 INFO (zkCallback-600-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 257880 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1003ef73ddd0031, likely client has closed socket
[junit4] 2> 259382 INFO (jetty-closer-408-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5751934{/solr,null,UNAVAILABLE}
[junit4] 2> 259382 INFO (jetty-closer-408-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 259383 ERROR
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 259383 INFO
(SUITE-TriggerIntegrationTest-seed#[629440E21F62B7B8]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:58907 58907
[junit4] 2> 259384 INFO (Thread-240) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:58907 58907
[junit4] 2> 259385 WARN (Thread-240) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/aliases.json
[junit4] 2> 11 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/clusterprops.json
[junit4] 2> 27 /solr/clusterstate.json
[junit4] 2> 10 /solr/aliases.json
[junit4] 2> 7 /solr/autoscaling.json
[junit4] 2> 3
/solr/overseer_elect/election/72126825653665824-127.0.0.1:59026_solr-n_0000000006
[junit4] 2> 2
/solr/overseer_elect/election/72126825653665815-127.0.0.1:58990_solr-n_0000000004
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 72 /solr/live_nodes
[junit4] 2> 27 /solr/collections
[junit4] 2> 7 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 7 /solr/overseer/queue
[junit4] 2> 7 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/overseer/queue-work
[junit4] 2> 2 /solr/autoscaling/events/node_added_triggerCTOOR
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_629440E21F62B7B8-002
[junit4] 2> Nov 09, 2018 5:07:42 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=679, maxMBSortInHeap=6.2208742317457215,
sim=RandomSimilarity(queryNorm=true): {}, locale=zh-HK, timezone=CET
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_172
(64-bit)/cpus=3,threads=1,free=29038152,total=97583104
[junit4] 2> NOTE: All tests run in this JVM: [TriggerIntegrationTest,
TriggerIntegrationTest, TriggerIntegrationTest]
[junit4] Completed [5/5 (3!)] on J0 in 86.86s, 4 tests, 1 failure <<<
FAILURES!
[junit4]
[junit4]
[junit4] Tests with failures [seed: 629440E21F62B7B8]:
[junit4] -
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling
[junit4] -
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling
[junit4] -
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling
[junit4]
[junit4]
[junit4] JVM J0: 1.04 .. 260.52 = 259.48s
[junit4] JVM J1: 0.99 .. 179.21 = 178.23s
[junit4] Execution time total: 4 minutes 20 seconds
[junit4] Tests summary: 5 suites, 20 tests, 3 failures
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1567:
The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:1092:
There were test failures: 5 suites, 20 tests, 3 failures [seed:
629440E21F62B7B8]
Total time: 4 minutes 22 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 3/5 failed: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build move
/y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build move /y
solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST lucene\build.orig
move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>IF EXIST solr\build.orig
move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows>exit 0
Archiving artifacts
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=480eec7980b24dd1d800546928b315f0a74edd13,
workspace=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows
[WARNINGS] Computing warning deltas based on reference build #879
Recording test results
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
