Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8283/
Java: 64bit/jdk-11.0.4 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.NodeMarkersRegistrationTest.testNodeMarkersRegistration
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at
__randomizedtesting.SeedInfo.seed([FCA1F9AE2EF58322:E41B71A220C04ECD]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at
org.apache.solr.cloud.autoscaling.NodeMarkersRegistrationTest.testNodeMarkersRegistration(NodeMarkersRegistrationTest.java:114)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:951)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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
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.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 13169 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.NodeMarkersRegistrationTest
[junit4] 2> 390240 INFO
(SUITE-NodeMarkersRegistrationTest-seed#[FCA1F9AE2EF58322]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 390241 INFO
(SUITE-NodeMarkersRegistrationTest-seed#[FCA1F9AE2EF58322]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.NodeMarkersRegistrationTest_FCA1F9AE2EF58322-001\data-dir-34-001
[junit4] 2> 390241 WARN
(SUITE-NodeMarkersRegistrationTest-seed#[FCA1F9AE2EF58322]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 390241 INFO
(SUITE-NodeMarkersRegistrationTest-seed#[FCA1F9AE2EF58322]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 390243 INFO
(SUITE-NodeMarkersRegistrationTest-seed#[FCA1F9AE2EF58322]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 390245 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeMarkersRegistration
[junit4] 2> 390245 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.NodeMarkersRegistrationTest_FCA1F9AE2EF58322-001\tempDir-001
[junit4] 2> 390245 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 390246 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 390246 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 390346 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer start zk server on port:53587
[junit4] 2> 390346 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:53587
[junit4] 2> 390346 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53587
[junit4] 2> 390346 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53587
[junit4] 2> 390349 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390355 INFO (zkConnectionManagerCallback-1284-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390355 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390358 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390360 INFO (zkConnectionManagerCallback-1286-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390360 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390362 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390364 INFO (zkConnectionManagerCallback-1288-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390364 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390736 WARN (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 390737 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 390737 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 390737 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git:
afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11
[junit4] 2> 390737 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 390737 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 390737 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 390738 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@66d3e467{/solr,null,AVAILABLE}
[junit4] 2> 390740 WARN (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git:
afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 390740 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@59d6d128{/solr,null,AVAILABLE}
[junit4] 2> 390786 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@21df3ba9{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:53600}
[junit4] 2> 390786 INFO (jetty-launcher-1289-thread-1) [ ]
o.e.j.s.Server Started @390826ms
[junit4] 2> 390786 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=53600}
[junit4] 2> 390787 ERROR (jetty-launcher-1289-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-12-13T17:51:23.578234400Z
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@22a4e9cd{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:53603}
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-2) [ ]
o.e.j.s.Server Started @390830ms
[junit4] 2> 390787 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=53603}
[junit4] 2> 390790 ERROR (jetty-launcher-1289-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 390790 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 390790 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 390790 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 390790 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 390790 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-12-13T17:51:23.581372300Z
[junit4] 2> 390791 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390796 INFO (zkConnectionManagerCallback-1291-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390796 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390796 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 390801 INFO (zkConnectionManagerCallback-1293-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390801 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390801 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 391145 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 391253 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 391263 WARN (jetty-launcher-1289-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@1617de4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391263 WARN (jetty-launcher-1289-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@1617de4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391267 WARN (jetty-launcher-1289-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@6d694a4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391267 WARN (jetty-launcher-1289-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@6d694a4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391272 WARN (jetty-launcher-1289-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@5dd8e74f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391272 WARN (jetty-launcher-1289-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@5dd8e74f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391273 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53587/solr
[junit4] 2> 391273 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 391275 WARN (jetty-launcher-1289-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@67a203de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391275 WARN (jetty-launcher-1289-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@67a203de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 391276 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53587/solr
[junit4] 2> 391277 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 391283 INFO (zkConnectionManagerCallback-1304-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391283 INFO (jetty-launcher-1289-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 391285 INFO (zkConnectionManagerCallback-1307-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391285 INFO (jetty-launcher-1289-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 391387 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 391395 INFO (zkConnectionManagerCallback-1309-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391397 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 391492 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 391507 INFO (zkConnectionManagerCallback-1311-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391508 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 391523 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:53603_solr
[junit4] 2> 391524 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.Overseer Overseer
(id=72067064455364616-127.0.0.1:53603_solr-n_0000000000) starting
[junit4] 2> 391529 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53603_solr
[junit4] 2> 391531 WARN (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 391546 INFO
(OverseerStateUpdate-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[n:127.0.0.1:53603_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:53603_solr
[junit4] 2> 391555 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 391580 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53603.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 391586 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53603.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 391586 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53603.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 391587 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 391590 INFO (jetty-launcher-1289-thread-2)
[n:127.0.0.1:53603_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.NodeMarkersRegistrationTest_FCA1F9AE2EF58322-001\tempDir-001\node1\.
[junit4] 2> 391590 INFO
(OverseerStateUpdate-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[n:127.0.0.1:53603_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 391660 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 391663 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with
znode version 1
[junit4] 2> 391665 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 0
[junit4] 2> 391665 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 391669 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 391670 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:53603_solr]
[junit4] 2> 391671 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 391672 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 391673 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:53600_solr as DOWN
[junit4] 2> 391674 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 391674 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53600_solr
[junit4] 2> 391677 INFO (zkCallback-1310-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 391677 WARN (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 391700 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 391896 INFO (zkCallback-1308-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 391896 DEBUG (ScheduledTrigger-1333-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 1
[junit4] 2> 391903 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53600.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 391911 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53600.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 391911 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53600.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 391911 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 391913 INFO (jetty-launcher-1289-thread-1)
[n:127.0.0.1:53600_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.NodeMarkersRegistrationTest_FCA1F9AE2EF58322-001\tempDir-001\node2\.
[junit4] 2> 391933 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 391935 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 391937 INFO (zkConnectionManagerCallback-1324-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391937 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 391939 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 391940 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:53587/solr ready
[junit4] 2> 391947 INFO (qtp790155322-4136) [n:127.0.0.1:53600_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 391956 INFO (qtp790155322-4140) [n:127.0.0.1:53600_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=4
[junit4] 2> 391963 INFO (qtp744686129-4146) [n:127.0.0.1:53603_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=4
[junit4] 2> 391963 DEBUG (qtp790155322-4138) [n:127.0.0.1:53600_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 391963 INFO (qtp790155322-4138) [n:127.0.0.1:53600_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 391967 INFO (qtp790155322-4141) [n:127.0.0.1:53600_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 391967 DEBUG (zkCallback-1310-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 391971 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 391973 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 391973 DEBUG
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 391975 INFO (qtp790155322-4141) [n:127.0.0.1:53600_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 391982 WARN
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 391982 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding
port=0)
[junit4] 2> 391982 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number
1 ...
[junit4] 2> 391982 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.Server jetty-9.4.19.v20190610; built:
2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm
11.0.4+11
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4a9cf8ef{/solr,null,AVAILABLE}
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@242b6d77{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:53639}
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.s.Server Started @392025ms
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=53639}
[junit4] 2> 391983 ERROR
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 9.0.0
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 391983 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-12-13T17:51:24.774333100Z
[junit4] 2> 391986 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 391987 INFO (zkConnectionManagerCallback-1326-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391987 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 391987 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 392173 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 392175 WARN
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.u.s.S.config Trusting all certificates configured for
Client@1866f590[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 392175 WARN
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm
configured for Client@1866f590[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 392179 WARN
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.u.s.S.config Trusting all certificates configured for
Client@2d9819e9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 392179 WARN
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm
configured for Client@2d9819e9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 392179 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53587/solr
[junit4] 2> 392180 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 392185 INFO (zkConnectionManagerCallback-1333-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 392185 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 392303 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.c.ConnectionManager Waiting for client
to connect to ZooKeeper
[junit4] 2> 392307 INFO (zkConnectionManagerCallback-1335-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 392307 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 392313 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 392314 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:53639_solr as DOWN
[junit4] 2> 392314 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 392314 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53639_solr
[junit4] 2> 392326 INFO (zkCallback-1310-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 392326 INFO (zkCallback-1308-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 392327 WARN
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.CoreContainer Not all security plugins
configured! authentication=disabled authorization=disabled. Solr is only as
secure as you make it. Consider configuring authentication/authorization before
exposing Solr to users internal or external. See
https://s.apache.org/solrsecurity for more info
[junit4] 2> 392351 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 392375 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53639.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 392382 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53639.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 392382 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53639.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@312ddf64
[junit4] 2> 392382 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 392386 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[n:127.0.0.1:53639_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.NodeMarkersRegistrationTest_FCA1F9AE2EF58322-001\tempDir-001\node3\.
[junit4] 2> 392423 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 392425 INFO (zkCallback-1323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 392533 INFO (zkCallback-1334-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 392534 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1751515291
[junit4] 2> 392534 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:53603_solr
[junit4] 2> 392534 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 392535 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:53603_solr as DOWN
[junit4] 2> 392535 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=383296681
[junit4] 2> 392535 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:53600_solr
[junit4] 2> 392535 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 392535 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:53600_solr as DOWN
[junit4] 2> 392539 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=390658037
[junit4] 2> 392539 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:53639_solr
[junit4] 2> 392541 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 392541 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 392542 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4e0fe8d1: rootName =
solr_53603, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@3c27aa0f
[junit4] 2> 392551 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 392551 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@58d1d8ef: rootName =
solr_53600, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@30af6d72
[junit4] 2> 392551 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 392551 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:53639_solr as DOWN
[junit4] 2> 392570 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 392570 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2a99cb21: rootName =
solr_53603, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@28e55387
[junit4] 2> 392571 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 392571 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5d5da577: rootName =
solr_53603, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@c35a81b
[junit4] 2> 392572 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 392605 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 392605 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6bb0a2cf: rootName =
solr_53600, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@28e55387
[junit4] 2> 392608 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 392608 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4d271b14: rootName =
solr_53600, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@c35a81b
[junit4] 2> 392608 INFO (jetty-closer-1340-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 392611 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 392612 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6f463581: rootName =
solr_53639, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@9c1ff9c
[junit4] 2> 392617 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 392617 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@22120709: rootName =
solr_53639, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@28e55387
[junit4] 2> 392617 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 392617 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1e215efb: rootName =
solr_53639, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@c35a81b
[junit4] 2> 392617 INFO (jetty-closer-1340-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 392620 INFO (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72067064455364616-127.0.0.1:53603_solr-n_0000000000) closing
[junit4] 2> 392621 INFO
(OverseerStateUpdate-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[n:127.0.0.1:53603_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:53603_solr
[junit4] 2> 392621 INFO
(OverseerAutoScalingTriggerThread-72067064455364616-127.0.0.1:53603_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 392624 DEBUG (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 392624 DEBUG (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 392624 DEBUG (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 392624 DEBUG (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 392625 DEBUG (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 392625 DEBUG (closeThreadPool-1350-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 392626 INFO (closeThreadPool-1350-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72067064455364616-127.0.0.1:53603_solr-n_0000000000) closing
[junit4] 2> 392710 INFO (zkCallback-1334-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 392711 INFO (jetty-closer-1340-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@21df3ba9{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:0}
[junit4] 2> 392712 INFO (jetty-closer-1340-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@66d3e467{/solr,null,UNAVAILABLE}
[junit4] 2> 392712 INFO (jetty-closer-1340-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 392817 INFO (jetty-closer-1340-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72067064455364616-127.0.0.1:53603_solr-n_0000000000) closing
[junit4] 2> 392872 INFO (jetty-closer-1340-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@22a4e9cd{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:0}
[junit4] 2> 392872 INFO (jetty-closer-1340-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@59d6d128{/solr,null,UNAVAILABLE}
[junit4] 2> 392872 INFO (jetty-closer-1340-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 392920 INFO (jetty-closer-1340-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@242b6d77{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:0}
[junit4] 2> 392920 INFO (jetty-closer-1340-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4a9cf8ef{/solr,null,UNAVAILABLE}
[junit4] 2> 392921 INFO (jetty-closer-1340-thread-3) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 392922 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 393124 WARN (ZkTestServer Run Thread) [ ]
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/clusterprops.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 8 /solr/overseer/queue
[junit4] 2> 4 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 393129 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:53587
[junit4] 2> 393129 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53587
[junit4] 2> 393129 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53587
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=NodeMarkersRegistrationTest
-Dtests.method=testNodeMarkersRegistration -Dtests.seed=FCA1F9AE2EF58322
-Dtests.slow=true -Dtests.locale=lag -Dtests.timezone=Atlantic/South_Georgia
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 4.89s J0 |
NodeMarkersRegistrationTest.testNodeMarkersRegistration <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but
was:<0>
[junit4] > at
__randomizedtesting.SeedInfo.seed([FCA1F9AE2EF58322:E41B71A220C04ECD]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.NodeMarkersRegistrationTest.testNodeMarkersRegistration(NodeMarkersRegistrationTest.java:114)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.NodeMarkersRegistrationTest_FCA1F9AE2EF58322-001
[junit4] 2> 395131 INFO
(TEST-NodeMarkersRegistrationTest.testNodeMarkersRegistration-seed#[FCA1F9AE2EF58322])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeMarkersRegistration
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {},
docValues:{}, maxPointsInLeafNode=1506, maxMBSortInHeap=7.143659162040659,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5f9f625c),
locale=lag, timezone=Atlantic/South_Georgia
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 11.0.4
(64-bit)/cpus=3,threads=1,free=133424496,total=305135616
[junit4] 2> NOTE: All tests run in this JVM: [DirectoryFactoryTest,
ConfigureRecoveryStrategyTest, TestSchemaSimilarityResource,
TestSimNodeLostTrigger, TestManagedSynonymGraphFilterFactory,
SolrSlf4jReporterTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
PeerSyncWithLeaderAndIndexFingerprintCachingTest, CdcrOpsAndBoundariesTest,
TestJsonRequest, TestComplexPhraseLeadingWildcard,
TlogReplayBufferedWhileIndexingTest, TestShortCircuitedRequests,
TestPivotHelperCode, CleanupOldIndexTest, TestScoreJoinQPScore,
QueryEqualityTest, TestSweetSpotSimilarityFactory,
DistributedFacetPivotSmallTest, TestCaffeineCache, TestSystemIdResolver,
ReplaceNodeNoTargetTest, TestConfigSetsAPIZkFailure,
TestAuthenticationFramework, TestHashPartitioner, SystemInfoHandlerTest,
DistributedQueryComponentOptimizationTest, TestSubQueryTransformer,
TestTolerantSearch, TestSolrIndexConfig, TestDynamicFieldNamesIndexCorrectly,
TestRTimerTree, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest,
NestedShardedAtomicUpdateTest, ForceLeaderTest, AtomicUpdateRemovalJavabinTest,
TestSolrCLIRunExample, TestReload, SchemaApiFailureTest,
IndexSchemaRuntimeFieldTest, NodeMarkersRegistrationTest]
[junit4] Completed [86/892 (1!)] on J0 in 4.99s, 1 test, 1 failure <<<
FAILURES!
[...truncated 55715 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8283/consoleText
[repro] Revision: e5df183a42967c0eb79b5c2c65cd3ab618318f23
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] NodeMarkersRegistrationTest
[repro] ant compile-test
[...truncated 3355 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.NodeMarkersRegistrationTest" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=FCA1F9AE2EF58322
-Dtests.slow=true -Dtests.locale=lag -Dtests.timezone=Atlantic/South_Georgia
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 73 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed:
org.apache.solr.cloud.autoscaling.NodeMarkersRegistrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
