Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2226/
4 tests failed.
FAILED: org.apache.solr.cloud.AssignBackwardCompatibilityTest.test
Error Message:
Stack Trace:
java.lang.NullPointerException
at
__randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:D4E60352B737D973]:0)
at
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at
org.apache.solr.cloud.AssignBackwardCompatibilityTest.test(AssignBackwardCompatibilityTest.java:92)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalMove
Error Message:
Could not load collection from ZK: MoveReplicaHDFSTest_coll_false
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK:
MoveReplicaHDFSTest_coll_false
at
__randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:FA6A8B2F9C2A7691]:0)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1108)
at
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647)
at
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:137)
at
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:117)
at
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
at org.apache.solr.cloud.MoveReplicaTest.test(MoveReplicaTest.java:214)
at
org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalMove(MoveReplicaHDFSTest.java:63)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for
/collections/MoveReplicaHDFSTest_coll_false/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
at
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1120)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1106)
... 45 more
FAILED:
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at
__randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:D4E60352B737D973]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.assertInvariants(TimeRoutedAliasUpdateProcessorTest.java:245)
at
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test(TimeRoutedAliasUpdateProcessorTest.java:123)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration
Error Message:
Path /autoscaling/nodeAdded/127.0.0.1:39913_solr wasn't created
Stack Trace:
java.lang.AssertionError: Path /autoscaling/nodeAdded/127.0.0.1:39913_solr
wasn't created
at
__randomizedtesting.SeedInfo.seed([5CB23C8819CBB48B:4408B48417FE7964]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration(TriggerIntegrationTest.java:932)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12069 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> 908665 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/init-core-data-001
[junit4] 2> 908668 WARN
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 908668 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 908702 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 908702 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001
[junit4] 2> 908702 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 908722 INFO (Thread-438) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 908722 INFO (Thread-438) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 908787 ERROR (Thread-438) [ ] 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> 908852 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:33262
[junit4] 2> 909059 INFO (zkConnectionManagerCallback-3058-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 909081 INFO (jetty-launcher-3055-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 909081 INFO (jetty-launcher-3055-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 909165 INFO (jetty-launcher-3055-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4255734a{/solr,null,AVAILABLE}
[junit4] 2> 909168 INFO (jetty-launcher-3055-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@342863a1{HTTP/1.1,[http/1.1]}{127.0.0.1:37692}
[junit4] 2> 909168 INFO (jetty-launcher-3055-thread-1) [ ]
o.e.j.s.Server Started @921113ms
[junit4] 2> 909168 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37692}
[junit4] 2> 909181 ERROR (jetty-launcher-3055-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 909181 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 909182 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 909182 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 909182 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:09.375Z
[junit4] 2> 909214 INFO (jetty-launcher-3055-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@78c64d9f{/solr,null,AVAILABLE}
[junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7dd07f35{HTTP/1.1,[http/1.1]}{127.0.0.1:39507}
[junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ]
o.e.j.s.Server Started @921160ms
[junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39507}
[junit4] 2> 909215 ERROR (jetty-launcher-3055-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 909215 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 909312 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:09.408Z
[junit4] 2> 909373 INFO (zkConnectionManagerCallback-3060-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 909377 INFO (zkConnectionManagerCallback-3062-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 909381 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 909381 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 909556 INFO (jetty-launcher-3055-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 909565 INFO (jetty-launcher-3055-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 909641 INFO (zkConnectionManagerCallback-3070-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 909649 INFO (zkConnectionManagerCallback-3067-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 909667 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x16076fef6870003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 909767 INFO
(zkConnectionManagerCallback-3074-thread-1-processing-n:127.0.0.1:37692_solr)
[n:127.0.0.1:37692_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 909792 INFO
(zkConnectionManagerCallback-3072-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 910321 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 910325 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:39507_solr
[junit4] 2> 910326 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.Overseer Overseer
(id=99210029231964165-127.0.0.1:39507_solr-n_0000000000) starting
[junit4] 2> 910437 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39507_solr
[junit4] 2> 910437 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 910545 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 910582 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: []
[junit4] 2> 910582 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 910582 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 910618 INFO
(OverseerStateUpdate-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 910760 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 910907 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 910912 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 911189 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39507.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 911264 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39507.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 911264 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39507.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 911301 INFO (jetty-launcher-3055-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node2/.
[junit4] 2> 911913 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 912870 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 912871 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 912929 DEBUG (ScheduledTrigger-1346-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 913039 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37692_solr
[junit4] 2> 913096 INFO
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 913148 INFO
(zkCallback-3073-thread-1-processing-n:127.0.0.1:37692_solr)
[n:127.0.0.1:37692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 913407 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37692.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 913501 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37692.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 913502 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37692.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 913536 INFO (jetty-launcher-3055-thread-1)
[n:127.0.0.1:37692_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node1/.
[junit4] 2> 913779 INFO (zkConnectionManagerCallback-3080-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 913808 INFO (zkConnectionManagerCallback-3084-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 913816 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 913817 INFO
(SUITE-TriggerIntegrationTest-seed#[5CB23C8819CBB48B]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33262/solr ready
[junit4] 2> 913929 DEBUG (ScheduledTrigger-1346-thread-2)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 914088 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger
[junit4] 2> 914101 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 914101 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 914101 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 914140 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 914141 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 914158 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 914200 INFO (qtp650767471-6983) [n:127.0.0.1:37692_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 914521 INFO (qtp650767471-6983) [n:127.0.0.1:37692_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=321
[junit4] 2> 914630 INFO (qtp650767471-6981) [n:127.0.0.1:37692_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=106
[junit4] 2> 914651 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 914651 DEBUG (qtp650767471-6984) [n:127.0.0.1:37692_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 914652 INFO (qtp650767471-6984) [n:127.0.0.1:37692_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=129
[junit4] 2> 914678 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 914699 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:37692_solr, 127.0.0.1:39507_solr]
[junit4] 2> 914699 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 914740 INFO
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 914740 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 914741 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 914743 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@342863a1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 914776 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1050644109
[junit4] 2> 914776 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 914777 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 2
[junit4] 2> 914777 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@292ae24: rootName =
solr_37692, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@425de3ae
[junit4] 2> 914795 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 914795 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1af5408b: rootName =
solr_37692, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@29cf17b2
[junit4] 2> 914852 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 914852 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@62f548e3: rootName =
solr_37692, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1843f368
[junit4] 2> 914854 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:37692_solr
[junit4] 2> 914855 INFO
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 914856 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 914878 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 914878 INFO
(zkCallback-3073-thread-1-processing-n:127.0.0.1:37692_solr)
[n:127.0.0.1:37692_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 914898 INFO (zkCallback-3083-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 914899 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4255734a{/solr,null,UNAVAILABLE}
[junit4] 2> 915786 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 915786 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node:
127.0.0.1:37692_solr
[junit4] 2> 916371 INFO
(OverseerCollectionConfigSetProcessor-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 916846 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 916847 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing
registered processor for lost nodes: [127.0.0.1:37692_solr]
[junit4] 2> 916847 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"31811aa2b380a9Td35vl06j8prvgrjd19w7z241p",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":13934225257627817,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934225257627817],
[junit4] 2> "nodeNames":["127.0.0.1:37692_solr"]}}
[junit4] 2> 916848 INFO (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 916868 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"31811aa2b380a9Td35vl06j8prvgrjd19w7z241p",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":13934225257627817,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934225257627817],
[junit4] 2> "_enqueue_time_":13934226318610591,
[junit4] 2> "nodeNames":["127.0.0.1:37692_solr"]}}
[junit4] 2> 916870 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2
ms for event id=31811aa2b380a9Td35vl06j8prvgrjd19w7z241p
[junit4] 2> 916893 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 916893 DEBUG (qtp1013721322-6991) [n:127.0.0.1:39507_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 916904 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 916904 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:39507_solr]
[junit4] 2> 916904 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 916904 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 916905 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 916905 INFO (qtp1013721322-6991) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=33
[junit4] 2> 917872 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 918883 DEBUG (ScheduledTrigger-1346-thread-4)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 918920 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[5CB23C8819CBB48B]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger
[junit4] 2> 918996 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testCooldown
[junit4] 2> 919050 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5
[junit4] 2> 919066 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 919066 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 919068 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 919068 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 919068 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 919069 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 919123 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@47f4e6fd{/solr,null,AVAILABLE}
[junit4] 2> 919124 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@30c790c3{HTTP/1.1,[http/1.1]}{127.0.0.1:40550}
[junit4] 2> 919124 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server Started @931069ms
[junit4] 2> 919124 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40550}
[junit4] 2> 919124 ERROR
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 919126 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 919126 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 919126 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 919126 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:19.319Z
[junit4] 2> 919200 INFO (zkConnectionManagerCallback-3086-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 919233 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 919380 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 919408 INFO (zkConnectionManagerCallback-3090-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 919432 INFO
(zkConnectionManagerCallback-3092-thread-1-processing-n:127.0.0.1:40550_solr)
[n:127.0.0.1:40550_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 919469 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 919470 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 919472 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40550_solr
[junit4] 2> 919542 INFO (zkCallback-3083-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 919542 INFO
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 919579 INFO
(zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr)
[n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 919945 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40550.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 920019 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40550.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 920020 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40550.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 920021 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:40550_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node3/.
[junit4] 2> 920333 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 920401 INFO (qtp1418590417-7051) [n:127.0.0.1:40550_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 920402 DEBUG (qtp1013721322-6990) [n:127.0.0.1:39507_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 920402 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 920420 INFO (qtp1013721322-6990) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=129
[junit4] 2> 920441 INFO (qtp1013721322-6996) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 920458 INFO (qtp1418590417-7054) [n:127.0.0.1:40550_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 920473 DEBUG (qtp1418590417-7053) [n:127.0.0.1:40550_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 920494 INFO (qtp1418590417-7053) [n:127.0.0.1:40550_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=69
[junit4] 2> 920530 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 920568 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:39507_solr, 127.0.0.1:40550_solr]
[junit4] 2> 920568 DEBUG
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger instantiated with properties: {event=nodeAdded,
waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 920568 DEBUG
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 920569 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1f9399ce{/solr,null,AVAILABLE}
[junit4] 2> 920569 DEBUG
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:39507_solr, 127.0.0.1:40550_solr]
[junit4] 2> 920569 DEBUG
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger instantiated with properties: {event=nodeAdded,
waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 920569 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 920569 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@219a333f{HTTP/1.1,[http/1.1]}{127.0.0.1:36236}
[junit4] 2> 920569 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server Started @932514ms
[junit4] 2> 920569 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36236}
[junit4] 2> 920569 ERROR
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 920569 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 920570 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 920570 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 920570 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:20.763Z
[junit4] 2> 920570 INFO
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 920604 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 920605 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 920605 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 920605 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 920640 INFO (zkConnectionManagerCallback-3096-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 920642 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 920787 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 920840 INFO (zkConnectionManagerCallback-3100-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 920949 INFO
(zkConnectionManagerCallback-3102-thread-1-processing-n:127.0.0.1:36236_solr)
[n:127.0.0.1:36236_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 920957 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 921007 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 921008 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36236_solr
[junit4] 2> 921010 INFO
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 921010 INFO (zkCallback-3083-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 921010 INFO
(zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr)
[n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 921080 INFO
(zkCallback-3101-thread-1-processing-n:127.0.0.1:36236_solr)
[n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 921462 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36236.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 921519 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36236.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 921520 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36236.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 921521 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:36236_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node4/.
[junit4] 2> 921606 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 921606 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 921606 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:36236_solr at time 13934231078001578
[junit4] 2> 922620 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 922621 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 922621 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger firing registered processor for nodes:
[127.0.0.1:36236_solr] added at times [13934231078001578], now=13934232092561058
[junit4] 2> 922621 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"31811bfd9f5baaTd35vl06j8prvgrjd19w7z241r",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934231078001578,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934231078001578],
[junit4] 2> "nodeNames":["127.0.0.1:36236_solr"]}}
[junit4] 2> 922629 INFO (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 922641 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"31811bfd9f5baaTd35vl06j8prvgrjd19w7z241r",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934231078001578,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934231078001578],
[junit4] 2> "_enqueue_time_":13934232092948207,
[junit4] 2> "nodeNames":["127.0.0.1:36236_solr"]}}
[junit4] 2> 922703 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 62
ms for event id=31811bfd9f5baaTd35vl06j8prvgrjd19w7z241r
[junit4] 2> 923654 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 923654 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 923654 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 923759 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7209c5fe{/solr,null,AVAILABLE}
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@60108384{HTTP/1.1,[http/1.1]}{127.0.0.1:44985}
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server Started @935705ms
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44985}
[junit4] 2> 923760 ERROR
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 923760 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:23.953Z
[junit4] 2> 923779 INFO (zkConnectionManagerCallback-3106-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 923796 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 923869 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 923926 INFO (zkConnectionManagerCallback-3110-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 923991 INFO
(zkConnectionManagerCallback-3112-thread-1-processing-n:127.0.0.1:44985_solr)
[n:127.0.0.1:44985_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 924003 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 924037 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 924073 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44985_solr
[junit4] 2> 924075 INFO
(zkCallback-3101-thread-1-processing-n:127.0.0.1:36236_solr)
[n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 924075 INFO (zkCallback-3083-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 924075 INFO
(zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr)
[n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 924075 INFO
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 924177 INFO
(zkCallback-3111-thread-1-processing-n:127.0.0.1:44985_solr)
[n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 924624 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44985.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 924657 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 924657 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 924657 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:44985_solr at time 13934234129249397
[junit4] 2> 924713 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44985.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 924714 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44985.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 924731 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:44985_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node5/.
[junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger firing registered processor for nodes:
[127.0.0.1:44985_solr] added at times [13934234129249397], now=13934235132904988
[junit4] 2> 925661 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -------- Cooldown
period - rejecting event: {
[junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241t",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934234129249397,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934234129249397],
[junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}}
[junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger firing registered processor for nodes:
[127.0.0.1:44985_solr] added at times [13934234129249397], now=13934236147628314
[junit4] 2> 926676 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -------- Cooldown
period - rejecting event: {
[junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241u",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934234129249397,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934234129249397],
[junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}}
[junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger firing registered processor for nodes:
[127.0.0.1:44985_solr] added at times [13934234129249397], now=13934237167260186
[junit4] 2> 927695 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -------- Cooldown
period - rejecting event: {
[junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241v",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934234129249397,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934234129249397],
[junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}}
[junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger firing registered processor for nodes:
[127.0.0.1:44985_solr] added at times [13934234129249397], now=13934238175203967
[junit4] 2> 928703 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241w",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934234129249397,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934234129249397],
[junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}}
[junit4] 2> 928704 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"31811cb37db475Td35vl06j8prvgrjd19w7z241w",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934234129249397,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934234129249397],
[junit4] 2> "_enqueue_time_":13934238175384210,
[junit4] 2> "nodeNames":["127.0.0.1:44985_solr"]}}
[junit4] 2> 928704 INFO
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.ActionThrottle The last action attempt
started 6063ms ago.
[junit4] 2> 928705 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0
ms for event id=31811cb37db475Td35vl06j8prvgrjd19w7z241w
[junit4] 2> 929706 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 929707 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 930712 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 930712 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 930895 INFO (qtp1602401251-7077) [n:127.0.0.1:36236_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 930897 INFO (qtp1013721322-6989) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 930938 INFO (qtp1418590417-7052) [n:127.0.0.1:40550_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 931030 INFO (qtp1567485535-7101) [n:127.0.0.1:44985_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 931030 DEBUG (qtp1013721322-6996) [n:127.0.0.1:39507_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 931031 DEBUG
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 931031 INFO (qtp1013721322-6996) [n:127.0.0.1:39507_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=309
[junit4] 2> 931033 DEBUG
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:39507_solr, 127.0.0.1:44985_solr, 127.0.0.1:40550_solr,
127.0.0.1:36236_solr]
[junit4] 2> 931098 DEBUG
(zkCallback-3071-thread-2-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger instantiated with properties: {event=nodeAdded,
waitFor=1, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 931044 INFO (qtp1567485535-7099) [n:127.0.0.1:44985_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 931102 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 931102 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers Changing value of
autoscaling property: triggerCooldownPeriodSeconds from: 5 to: 7
[junit4] 2> 931103 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 931103 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 931104 DEBUG
(OverseerAutoScalingTriggerThread-99210029231964165-127.0.0.1:39507_solr-n_0000000000)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 931167 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e028aec{/solr,null,AVAILABLE}
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@38f91e1{HTTP/1.1,[http/1.1]}{127.0.0.1:33807}
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server Started @943113ms
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33807}
[junit4] 2> 931168 ERROR
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 931168 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:31.361Z
[junit4] 2> 931305 INFO (zkConnectionManagerCallback-3116-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931306 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 931379 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 931395 INFO (zkConnectionManagerCallback-3120-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931448 INFO
(zkConnectionManagerCallback-3122-thread-1-processing-n:127.0.0.1:33807_solr)
[n:127.0.0.1:33807_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931455 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 931506 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 931507 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33807_solr
[junit4] 2> 931525 INFO (zkCallback-3083-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 931525 INFO
(zkCallback-3071-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 931544 INFO
(zkCallback-3101-thread-1-processing-n:127.0.0.1:36236_solr)
[n:127.0.0.1:36236_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 931545 INFO
(zkCallback-3091-thread-1-processing-n:127.0.0.1:40550_solr)
[n:127.0.0.1:40550_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 931545 INFO
(zkCallback-3111-thread-1-processing-n:127.0.0.1:44985_solr)
[n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 931619 INFO
(zkCallback-3121-thread-1-processing-n:127.0.0.1:33807_solr)
[n:127.0.0.1:33807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 931716 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 931716 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5
[junit4] 2> 931716 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:33807_solr at time 13934241187982705
[junit4] 2> 931895 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33807.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 931949 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33807.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 931949 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33807.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a3bde7f
[junit4] 2> 931950 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:33807_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_5CB23C8819CBB48B-001/tempDir-001/node6/.
[junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_cooldown_trigger
[junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5
[junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_cooldown_trigger firing registered processor for nodes:
[127.0.0.1:33807_solr] added at times [13934241187982705], now=13934242233246367
[junit4] 2> 932761 DEBUG (ScheduledTrigger-1346-thread-1)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"31811e58396d71Td35vl06j8prvgrjd19w7z241x",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934241187982705,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934241187982705],
[junit4] 2> "nodeNames":["127.0.0.1:33807_solr"]}}
[junit4] 2> 932770 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"31811e58396d71Td35vl06j8prvgrjd19w7z241x",
[junit4] 2> "source":"node_added_cooldown_trigger",
[junit4] 2> "eventTime":13934241187982705,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[13934241187982705],
[junit4] 2> "_enqueue_time_":13934242233448621,
[junit4] 2> "nodeNames":["127.0.0.1:33807_solr"]}}
[junit4] 2> 932771 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 932772 DEBUG
(AutoscalingActionExecutor-1347-thread-1-processing-n:127.0.0.1:39507_solr)
[n:127.0.0.1:39507_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=31811e58396d71Td35vl06j8prvgrjd19w7z241x
[junit4] 2> 932864 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@26633b9f{/solr,null,AVAILABLE}
[junit4] 2> 932864 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7d0b9b35{HTTP/1.1,[http/1.1]}{127.0.0.1:34545}
[junit4] 2> 932864 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.e.j.s.Server Started @944809ms
[junit4] 2> 932864 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34545}
[junit4] 2> 932864 ERROR
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 932864 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 932865 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 932865 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 932865 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-21T02:55:33.058Z
[junit4] 2> 932887 INFO (zkConnectionManagerCallback-3126-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 932896 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 933006 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33262/solr
[junit4] 2> 933050 INFO (zkConnectionManagerCallback-3130-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 933079 INFO
(zkConnectionManagerCallback-3132-thread-1-processing-n:127.0.0.1:34545_solr)
[n:127.0.0.1:34545_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 933082 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:34545_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 933100 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:34545_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 933102 INFO
(TEST-TriggerIntegrationTest.testCooldown-seed#[5CB23C8819CBB48B])
[n:127.0.0.1:34545_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34545_solr
[junit4] 2> 933124 INFO
(zkCallback-3111-thread-1-processing-n:127.0.0.1:44985_solr)
[n:127.0.0.1:44985_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5)
[...truncated too long message...]
7-10-23_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4fd6c3df: rootName =
solr_44218, domain = solr.core.myalias_2017-10-23.shard1.replica_n1, service
url = null, agent id = null] for registry
solr.core.myalias_2017-10-23.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@7c2cfefa
[junit4] 2> 7390271 INFO
(zkCallback-7718-thread-1-processing-n:127.0.0.1:44218_solr)
[n:127.0.0.1:44218_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 7390318 INFO (coreCloseExecutor-10323-thread-1)
[n:127.0.0.1:44218_solr c:myalias_2017-10-23 s:shard1 r:core_node2
x:myalias_2017-10-23_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for registry=solr.collection.myalias_2017-10-23.shard1.leader,
tag=699230150
[junit4] 2> 7390452 INFO (jetty-closer-7700-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=99210452971159558-127.0.0.1:44218_solr-n_0000000000) closing
[junit4] 2> 7390452 INFO
(OverseerStateUpdate-99210452971159558-127.0.0.1:44218_solr-n_0000000000)
[n:127.0.0.1:44218_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:44218_solr
[junit4] 2> 7390487 WARN
(OverseerAutoScalingTriggerThread-99210452971159558-127.0.0.1:44218_solr-n_0000000000)
[n:127.0.0.1:44218_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 7391994 INFO (jetty-closer-7700-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1e733f44{/solr,null,UNAVAILABLE}
[junit4] 2> 7391995 ERROR
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[5CB23C8819CBB48B]-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> 7391996 INFO
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[5CB23C8819CBB48B]-worker) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45078 45078
[junit4] 2> 7392050 INFO (Thread-7623) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:45078 45078
[junit4] 2> 7392070 WARN (Thread-7623) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/configs/timeConfig
[junit4] 2> 3 /solr/configs/timeConfig/managed-schema
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/collections/myalias_2017-10-23/state.json
[junit4] 2> 5 /solr/collections/timeConfig/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_5CB23C8819CBB48B-001
[junit4] 2> Dec 21, 2017 4:43:12 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are:
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
chunkSize=2, maxDocsPerChunk=913, blockSize=705),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST,
chunkSize=2, blockSize=705)),
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@68832b6e),
locale=zh, timezone=Australia/Lindeman
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=142366768,total=525860864
[junit4] 2> NOTE: All tests run in this JVM: [TestObjectReleaseTracker,
TestPullReplica, TestFastOutputStream, BigEndianAscendingWordDeserializerTest,
TestStressLiveNodes, CdcrVersionReplicationTest, PeerSyncTest,
DeleteReplicaTest, SolrCloudExampleTest, BaseCdcrDistributedZkTest,
URLClassifyProcessorTest, TestSerializedLuceneMatchVersion, VersionInfoTest,
TestFieldTypeCollectionResource, TestXmlQParser, AnalyticsQueryTest,
ClassificationUpdateProcessorTest, SpellCheckComponentTest, RulesTest,
TestMacros, CachingDirectoryFactoryTest, SyncSliceTest, TestSimpleTextCodec,
TestComponentsName, DocumentBuilderTest, DistributedFacetPivotLongTailTest,
TriggerIntegrationTest, PropertiesRequestHandlerTest, TestSearcherReuse,
ActionThrottleTest, UpdateParamsTest, BasicAuthIntegrationTest,
TestManagedSchema, HttpTriggerListenerTest, TestSolrJ, TestMergePolicyConfig,
SoftAutoCommitTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
FastVectorHighlighterTest, AssignBackwardCompatibilityTest,
SearchRateTriggerTest, RAMDirectoryFactoryTest, ExternalFileFieldSortTest,
AtomicUpdatesTest, TestInitQParser, TestPointFields, SearchHandlerTest,
SpellCheckCollatorTest, TestSolr4Spatial2, ManagedSchemaRoundRobinCloudTest,
DistributedMLTComponentTest, CleanupOldIndexTest,
TestSolrCloudWithHadoopAuthPlugin, MultiTermTest, PrimitiveFieldTypeTest,
DataDrivenBlockJoinTest, DistribCursorPagingTest, TestSubQueryTransformer,
HdfsChaosMonkeyNothingIsSafeTest, DirectUpdateHandlerOptimizeTest,
TestSolrIndexConfig, TestBadConfig, TestRequestForwarding, TestSegmentSorting,
TestFoldingMultitermQuery, ForceLeaderTest, BasicZkTest, TestRecovery,
HLLUtilTest, TestCrossCoreJoin, RecoveryAfterSoftCommitTest,
TestQueryWrapperFilter, TestSolrCloudSnapshots, DocValuesTest,
TermsComponentTest, StressHdfsTest,
TestOverriddenPrefixQueryForCustomFieldType, TestManagedSynonymFilterFactory,
ReplicationFactorTest, TestPostingsSolrHighlighter, TestCoreBackup,
TestSkipOverseerOperations, TestReRankQParserPlugin, TestBulkSchemaAPI,
QueryResultKeyTest, TestConfigsApi, TestLuceneIndexBackCompat,
NoCacheHeaderTest, TestRandomRequestDistribution, TestReqParamsAPI,
TestBinaryResponseWriter, TestAuthorizationFramework, TestPolicyCloud,
DeleteInactiveReplicaTest, TestStressVersions, TestNRTOpen, ZkStateWriterTest,
TestDocBasedVersionConstraints, LeaderInitiatedRecoveryOnCommitTest,
SpatialRPTFieldTypeTest, TestNumericTokenStream, TestScoreJoinQPNoScore,
TestExpandComponent, HdfsTlogReplayBufferedWhileIndexingTest,
TestLeaderInitiatedRecoveryThread, TestExactSharedStatsCache, GraphQueryTest,
TestConfigSetsAPIZkFailure, TestSolrDeletionPolicy1,
TestSolrCloudWithSecureImpersonation, TestGeoJSONResponseWriter,
DateMathParserTest, TestAddFieldRealTimeGet, LeaderElectionIntegrationTest,
MetricsConfigTest, TestMiniSolrCloudClusterSSL, LeaderElectionContextKeyTest,
JsonLoaderTest, BlockJoinFacetDistribTest, SolrPluginUtilsTest,
SpatialFilterTest, BlockDirectoryTest, SSLMigrationTest,
BasicFunctionalityTest, TestLargeCluster, TestRandomFaceting,
ShardRoutingCustomTest, TestDistributedSearch, ZkControllerTest,
TestStressReorder, TestReload, TestCoreContainer, TestSolr4Spatial,
SolrCmdDistributorTest, TestFiltering, TestIndexSearcher, HighlighterTest,
ShowFileRequestHandlerTest, SolrIndexSplitterTest, SimplePostToolTest,
TestCoreDiscovery, SuggesterTest, SuggesterWFSTTest, PolyFieldTest, TestUpdate,
TestWordDelimiterFilterFactory, SortByFunctionTest, TestRemoteStreaming,
DocValuesMultiTest, SolrInfoBeanTest, CacheHeaderTest,
DisMaxRequestHandlerTest, TestQueryUtils, TestWriterPerf,
FileBasedSpellCheckerTest, TestValueSourceCache, TermVectorComponentTest,
IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, ReturnFieldsTest,
MBeansHandlerTest, TestDocSet, TestSearchPerf, TestElisionMultitermQuery,
OutputWriterTest, NotRequiredUniqueKeyTest, TestCharFilters, TestCodecSupport,
TestLMDirichletSimilarityFactory, TestIBSimilarityFactory,
TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest,
TestUtils, ZkNodePropsTest, FileUtilsTest, SolrTestCaseJ4Test,
TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping,
TestSimpleTrackingShardHandler, AssignTest,
CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest,
DeleteLastCustomShardedReplicaTest, DeleteShardTest, DeleteStatusTest,
DistribDocExpirationUpdateProcessorTest, MoveReplicaHDFSTest,
TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCollectionAPI,
TestCollectionsAPIViaSolrCloudCluster, TestDeleteCollectionOnDownNodes,
TestDistribDocBasedVersion, TestHdfsCloudBackupRestore,
TestPullReplicaErrorHandling, TestReplicaProperties,
TestSizeLimitedDistributedMap, TestTolerantUpdateProcessorCloud,
ZkFailoverTest, SystemLogListenerTest, TestNodeLostTrigger,
TestTriggerIntegration, CdcrBootstrapTest, HdfsRecoverLeaseTest,
TestCorePropertiesReload, TestImplicitCoreProperties, TestInfoStreamLogging,
TestReloadAndDeleteDocs, RequestLoggingTest, SearchHandlerTest,
TestConfigReload, TestSQLHandlerNonCloud, TestSystemCollAutoCreate,
AutoscalingHistoryHandlerTest, CoreAdminCreateDiscoverTest,
CoreAdminRequestStatusTest, CoreMergeIndexesAdminHandlerTest,
SecurityConfHandlerTest, StatsReloadRaceTest, TestCoreAdminApis,
DistributedExpandComponentTest, DistributedFacetPivotLargeTest,
FacetPivotSmallTest, InfixSuggestersTest,
SuggestComponentContextFilterQueryTest,
TestDistributedStatsComponentCardinality, DateRangeFieldTest,
DocValuesMissingTest, TestCloudSchemaless, TestNestedDocsSort,
TestScoreJoinQPScore, CloudMLTQParserTest, TestDistribIDF,
BasicAuthStandaloneTest, PKIAuthenticationIntegrationTest,
TestRuleBasedAuthorizationPlugin, TestSha256AuthenticationProvider,
TestDelegationWithHadoopAuth, TestFreeTextSuggestions, BlockCacheTest,
TestDocTermOrds, TestFieldCacheReopen, TestFieldCacheSortRandom,
TestFieldCacheVsDocValues, TestHdfsUpdateLog,
TimeRoutedAliasUpdateProcessorTest]
[junit4] Completed [753/764 (4!)] on J0 in 18.69s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 48049 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
