You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/05/16 14:16:26 UTC
[JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 362 -
Failure
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/362/
1 tests failed.
FAILED: org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI
Error Message:
{} expected:<2> but was:<0>
Stack Trace:
java.lang.AssertionError: {} expected:<2> but was:<0>
at __randomizedtesting.SeedInfo.seed([F51810F748FFD28C:EACF8CDB3BF42BC7]: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.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:293)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 13563 lines...]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] 2> 2388337 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-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-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/init-core-data-001
[junit4] 2> 2388337 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2388338 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2388338 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001
[junit4] 2> 2388339 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2388339 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2388339 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2388439 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:37817
[junit4] 2> 2388439 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37817
[junit4] 2> 2388439 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37817
[junit4] 2> 2388477 INFO (zkConnectionManagerCallback-7169-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2388479 INFO (zkConnectionManagerCallback-7171-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2388480 INFO (zkConnectionManagerCallback-7173-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2388482 WARN (jetty-launcher-7174-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2388482 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2388482 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2388482 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 2388483 WARN (jetty-launcher-7174-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2388483 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2388483 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2388483 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 2388484 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2388484 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2388484 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2388484 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e31d3f6{/solr,null,AVAILABLE}
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@67d2b6bc{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43225}
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.e.j.s.Server Started @2388675ms
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43225}
[junit4] 2> 2388486 ERROR (jetty-launcher-7174-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2388486 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-16T12:41:06.510560Z
[junit4] 2> 2388517 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2388517 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2388517 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2388533 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6f401437{/solr,null,AVAILABLE}
[junit4] 2> 2388533 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7431bfb4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43967}
[junit4] 2> 2388533 INFO (jetty-launcher-7174-thread-2) [ ] o.e.j.s.Server Started @2388723ms
[junit4] 2> 2388533 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43967}
[junit4] 2> 2388534 ERROR (jetty-launcher-7174-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2388534 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2388534 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2388534 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2388534 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2388534 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-16T12:41:06.558230Z
[junit4] 2> 2388557 INFO (zkConnectionManagerCallback-7176-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2388558 INFO (zkConnectionManagerCallback-7178-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2388558 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2388558 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2388755 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2388797 WARN (jetty-launcher-7174-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7e6312ff[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2388799 WARN (jetty-launcher-7174-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2ab844a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2388800 INFO (jetty-launcher-7174-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37817/solr
[junit4] 2> 2388836 INFO (zkConnectionManagerCallback-7186-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2388849 INFO (zkConnectionManagerCallback-7188-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2389040 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43967_solr
[junit4] 2> 2389040 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.c.Overseer Overseer (id=74701831869366278-127.0.0.1:43967_solr-n_0000000000) starting
[junit4] 2> 2389088 INFO (zkConnectionManagerCallback-7195-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2389094 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37817/solr ready
[junit4] 2> 2389116 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43967_solr
[junit4] 2> 2389118 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43967_solr
[junit4] 2> 2389145 INFO (zkCallback-7194-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2389169 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2389218 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2389307 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43967.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2389341 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43967.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2389341 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43967.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2389342 INFO (jetty-launcher-7174-thread-2) [n:127.0.0.1:43967_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/.
[junit4] 2> 2389563 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2389583 WARN (jetty-launcher-7174-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@10e8a5a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2389616 WARN (jetty-launcher-7174-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5c02f640[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2389617 INFO (jetty-launcher-7174-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37817/solr
[junit4] 2> 2389635 INFO (zkConnectionManagerCallback-7202-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2389728 INFO (zkConnectionManagerCallback-7204-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2389815 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2389836 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:43225_solr as DOWN
[junit4] 2> 2389837 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2389837 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43225_solr
[junit4] 2> 2389838 INFO (zkCallback-7194-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2389838 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2389858 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2389886 INFO (zkConnectionManagerCallback-7211-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2389887 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2389888 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37817/solr ready
[junit4] 2> 2389935 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2389990 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43225.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2390057 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43225.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2390057 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43225.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2390058 INFO (jetty-launcher-7174-thread-1) [n:127.0.0.1:43225_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node1/.
[junit4] 2> 2390131 INFO (zkConnectionManagerCallback-7217-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2390132 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2390133 INFO (SUITE-AliasIntegrationTest-seed#[F51810F748FFD28C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37817/solr ready
[junit4] 2> 2390200 INFO (TEST-AliasIntegrationTest.testProperties-seed#[F51810F748FFD28C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProperties
[junit4] 2> 2390228 INFO (qtp820349897-18833) [n:127.0.0.1:43967_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2390228 INFO (qtp820349897-18833) [n:127.0.0.1:43967_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2390233 INFO (qtp159938623-18823) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2390267 INFO (OverseerThreadFactory-6022-thread-1-processing-n:127.0.0.1:43967_solr) [n:127.0.0.1:43967_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta
[junit4] 2> 2390397 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43225/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2390399 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1meta_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43967/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2390604 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2390611 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2390611 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2391622 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2391624 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2391659 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2391669 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2391669 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true
[junit4] 2> 2391670 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43225.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2391670 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node1/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node1/./collection1meta_shard1_replica_n1/data/]
[junit4] 2> 2391673 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n2] Schema name=minimal
[junit4] 2> 2391675 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2391675 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n2' using configuration from collection collection1meta, trusted=true
[junit4] 2> 2391675 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43967.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2391676 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/collection1meta_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/./collection1meta_shard2_replica_n2/data/]
[junit4] 2> 2391772 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2391772 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2391782 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2391782 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2391783 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f8ef72b[collection1meta_shard2_replica_n2] main]
[junit4] 2> 2391786 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2391786 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2391787 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2391787 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633692386392539136
[junit4] 2> 2391790 INFO (searcherExecutor-6035-thread-1-processing-n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] Registered new searcher Searcher@7f8ef72b[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2391802 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2391802 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2
[junit4] 2> 2391805 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2391805 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2391805 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43967/solr/collection1meta_shard2_replica_n2/
[junit4] 2> 2391806 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2391806 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:43967/solr/collection1meta_shard2_replica_n2/ has no replicas
[junit4] 2> 2391806 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/74701831869366278-core_node4-n_0000000000
[junit4] 2> 2391808 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43967/solr/collection1meta_shard2_replica_n2/ shard2
[junit4] 2> 2391814 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2391814 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2391816 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2391816 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2391817 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5045ccc7[collection1meta_shard1_replica_n1] main]
[junit4] 2> 2391830 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2391831 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2391831 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2391832 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633692386439725056
[junit4] 2> 2391834 INFO (searcherExecutor-6034-thread-1-processing-n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node3) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@5045ccc7[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2391955 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2391960 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2391962 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1350
[junit4] 2> 2391966 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 2391966 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1
[junit4] 2> 2391984 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2391984 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2391984 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43225/solr/collection1meta_shard1_replica_n1/
[junit4] 2> 2391984 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2391984 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:43225/solr/collection1meta_shard1_replica_n1/ has no replicas
[junit4] 2> 2391984 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/74701831869366281-core_node3-n_0000000000
[junit4] 2> 2391986 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43225/solr/collection1meta_shard1_replica_n1/ shard1
[junit4] 2> 2392098 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392099 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392099 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392099 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2392101 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1497
[junit4] 2> 2392109 INFO (qtp159938623-18823) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2392208 INFO (zkCallback-7203-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392208 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392208 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392208 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2392209 INFO (qtp159938623-18823) [n:127.0.0.1:43225_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1975
[junit4] 2> 2392210 INFO (qtp159938623-18826) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2392248 INFO (OverseerThreadFactory-6022-thread-2-processing-n:127.0.0.1:43967_solr) [n:127.0.0.1:43967_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta
[junit4] 2> 2392249 INFO (OverseerCollectionConfigSetProcessor-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_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> 2392493 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection2meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection2meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43967/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2392699 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2393709 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2393733 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.IndexSchema [collection2meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2393735 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2393735 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection2meta_shard1_replica_n1' using configuration from collection collection2meta, trusted=true
[junit4] 2> 2393736 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43967.solr.core.collection2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2393736 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [[collection2meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/collection2meta_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/./collection2meta_shard1_replica_n1/data/]
[junit4] 2> 2393846 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2393846 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2393860 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2393860 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2393861 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@326c031e[collection2meta_shard1_replica_n1] main]
[junit4] 2> 2393862 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2393862 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2393863 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2393863 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633692388569382912
[junit4] 2> 2393865 INFO (searcherExecutor-6044-thread-1-processing-n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1 c:collection2meta s:shard1 r:core_node2) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] Registered new searcher Searcher@326c031e[collection2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2393868 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2393868 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2meta/leaders/shard1
[junit4] 2> 2393870 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2393870 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2393870 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43967/solr/collection2meta_shard1_replica_n1/
[junit4] 2> 2393871 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2393871 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:43967/solr/collection2meta_shard1_replica_n1/ has no replicas
[junit4] 2> 2393871 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2meta/leaders/shard1/leader after winning as /collections/collection2meta/leader_elect/shard1/election/74701831869366278-core_node2-n_0000000000
[junit4] 2> 2393884 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43967/solr/collection2meta_shard1_replica_n1/ shard1
[junit4] 2> 2393985 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2393985 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2393986 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2393988 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1288
[junit4] 2> 2393998 INFO (qtp159938623-18826) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2394088 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394088 INFO (zkCallback-7187-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394088 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394088 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394088 INFO (qtp159938623-18826) [n:127.0.0.1:43225_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1877
[junit4] 2> 2394089 INFO (TEST-AliasIntegrationTest.testProperties-seed#[F51810F748FFD28C]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 2394089 INFO (watches-7214-thread-1) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 2394090 INFO (TEST-AliasIntegrationTest.testProperties-seed#[F51810F748FFD28C]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 2394090 INFO (watches-7214-thread-1) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 2394124 INFO (zkConnectionManagerCallback-7223-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2394127 INFO (TEST-AliasIntegrationTest.testProperties-seed#[F51810F748FFD28C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2394129 INFO (TEST-AliasIntegrationTest.testProperties-seed#[F51810F748FFD28C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProperties
[junit4] 2> 2394130 INFO (TEST-AliasIntegrationTest.testProperties-seed#[F51810F748FFD28C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2394145 INFO (qtp159938623-18822) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection2meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2394147 INFO (OverseerCollectionConfigSetProcessor-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2394147 INFO (OverseerThreadFactory-6022-thread-3-processing-n:127.0.0.1:43967_solr) [n:127.0.0.1:43967_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 2394160 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=null
[junit4] 2> 2394160 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@508059db: rootName = solr_43967, domain = solr.core.collection2meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection2meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@488eb2d0
[junit4] 2> 2394201 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@7dc61c53
[junit4] 2> 2394201 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=7dc61c53
[junit4] 2> 2394201 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection2meta.shard1.leader, tag=7dc61c53
[junit4] 2> 2394224 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 2394226 INFO (qtp820349897-18834) [n:127.0.0.1:43967_solr x:collection2meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection2meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=66
[junit4] 2> 2394362 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394362 INFO (zkCallback-7187-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394362 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394377 INFO (qtp159938623-18822) [n:127.0.0.1:43225_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection2meta&action=DELETE&wt=javabin&version=2} status=0 QTime=232
[junit4] 2> 2394378 INFO (qtp159938623-18824) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection1meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2394380 INFO (OverseerCollectionConfigSetProcessor-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2394380 INFO (OverseerThreadFactory-6022-thread-4-processing-n:127.0.0.1:43967_solr) [n:127.0.0.1:43967_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 2394381 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=null
[junit4] 2> 2394381 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=null
[junit4] 2> 2394381 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4eb2c391: rootName = solr_43967, domain = solr.core.collection1meta.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1meta.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@ea021e5
[junit4] 2> 2394381 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@315aad9f: rootName = solr_43225, domain = solr.core.collection1meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@514bb9f7
[junit4] 2> 2394438 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@32b31841
[junit4] 2> 2394439 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=32b31841
[junit4] 2> 2394439 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard1.leader, tag=32b31841
[junit4] 2> 2394439 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@718067b8
[junit4] 2> 2394439 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=718067b8
[junit4] 2> 2394439 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard2.leader, tag=718067b8
[junit4] 2> 2394483 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={}, version=1}
[junit4] 2> 2394496 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 2394513 INFO (qtp820349897-18835) [n:127.0.0.1:43967_solr x:collection1meta_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=131
[junit4] 2> 2394513 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=132
[junit4] 2> 2394615 INFO (zkCallback-7203-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394615 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394616 INFO (zkCallback-7187-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394616 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2394654 INFO (qtp159938623-18824) [n:127.0.0.1:43225_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection1meta&action=DELETE&wt=javabin&version=2} status=0 QTime=276
[junit4] 2> 2394765 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[F51810F748FFD28C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testModifyPropertiesV2
[junit4] 2> 2394800 INFO (qtp159938623-18826) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2394800 INFO (qtp159938623-18826) [n:127.0.0.1:43225_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2394802 INFO (qtp159938623-18822) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2394840 INFO (OverseerCollectionConfigSetProcessor-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2394840 INFO (OverseerThreadFactory-6022-thread-5-processing-n:127.0.0.1:43967_solr) [n:127.0.0.1:43967_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta
[junit4] 2> 2395068 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43967/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2395083 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1meta_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43225/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2395277 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr x:collection1meta_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2395277 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr x:collection1meta_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2396304 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2396309 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2396345 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2396346 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n3] Schema name=minimal
[junit4] 2> 2396347 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2396347 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true
[junit4] 2> 2396348 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43967.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2396348 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node2/./collection1meta_shard1_replica_n1/data/]
[junit4] 2> 2396364 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2396364 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n3' using configuration from collection collection1meta, trusted=true
[junit4] 2> 2396365 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43225.solr.core.collection1meta.shard2.replica_n3' (registry 'solr.core.collection1meta.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@60f8e8c9
[junit4] 2> 2396365 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node1/collection1meta_shard2_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_F51810F748FFD28C-001/tempDir-001/node1/./collection1meta_shard2_replica_n3/data/]
[junit4] 2> 2396800 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2396800 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2396802 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2396802 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2396803 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6017b2d3[collection1meta_shard1_replica_n1] main]
[junit4] 2> 2396816 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2396817 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2396817 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2396817 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633692391666876416
[junit4] 2> 2396825 INFO (searcherExecutor-6049-thread-1-processing-n:127.0.0.1:43967_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node2) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@6017b2d3[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397004 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2397004 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1
[junit4] 2> 2397006 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2397006 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2397006 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43967/solr/collection1meta_shard1_replica_n1/
[junit4] 2> 2397006 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2397007 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:43967/solr/collection1meta_shard1_replica_n1/ has no replicas
[junit4] 2> 2397007 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/74701831869366278-core_node2-n_0000000000
[junit4] 2> 2397036 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43967/solr/collection1meta_shard1_replica_n1/ shard1
[junit4] 2> 2397060 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2397060 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2397061 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2397061 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2397062 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ae73181[collection1meta_shard2_replica_n3] main]
[junit4] 2> 2397080 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2397080 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2397081 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2397081 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633692391943700480
[junit4] 2> 2397084 INFO (searcherExecutor-6050-thread-1-processing-n:127.0.0.1:43225_solr x:collection1meta_shard2_replica_n3 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SolrCore [collection1meta_shard2_replica_n3] Registered new searcher Searcher@7ae73181[collection1meta_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2397098 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2397098 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2
[junit4] 2> 2397133 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2397133 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2397133 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43225/solr/collection1meta_shard2_replica_n3/
[junit4] 2> 2397133 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2397133 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.SyncStrategy http://127.0.0.1:43225/solr/collection1meta_shard2_replica_n3/ has no replicas
[junit4] 2> 2397133 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/74701831869366281-core_node4-n_0000000000
[junit4] 2> 2397134 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43225/solr/collection1meta_shard2_replica_n3/ shard2
[junit4] 2> 2397193 INFO (zkCallback-7203-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397193 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397197 INFO (zkCallback-7187-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397213 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397291 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2397300 INFO (qtp820349897-18836) [n:127.0.0.1:43967_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2022
[junit4] 2> 2397501 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397501 INFO (zkCallback-7187-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397501 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397505 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397505 INFO (zkCallback-7203-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397514 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2397516 INFO (qtp159938623-18825) [n:127.0.0.1:43225_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2238
[junit4] 2> 2397551 INFO (qtp159938623-18822) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2397644 INFO (OverseerCollectionConfigSetProcessor-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2397772 INFO (zkCallback-7187-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397773 INFO (zkCallback-7187-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397777 INFO (zkCallback-7203-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397789 INFO (zkCallback-7187-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397789 INFO (zkCallback-7203-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397789 INFO (qtp159938623-18822) [n:127.0.0.1:43225_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2987
[junit4] 2> 2397801 INFO (zkCallback-7203-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2397810 INFO (qtp159938623-18824) [n:127.0.0.1:43225_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2397951 INFO (OverseerThreadFactory-6022-thread-5-processing-n:127.0.0.1:43967_solr) [n:127.0.0.1:43967_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta
[junit4] 2> 2398214 INFO (OverseerStateUpdate-74701831869366278-127.0.0.1:43967_solr-n_0000000000) [n:127.0.0.1:43967_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection2meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection2meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43967/solr",
[junit4] 2> "type":"NRT"
[...truncated too long message...]
Test, RemoteQueryErrorTest, TestSolrCloudWithDelegationTokens, PhrasesIdentificationComponentTest, TestJmxIntegration, TestSolrQueryResponse, TestRTimerTree, RestoreTriggerStateTest, AliasIntegrationTest]
[junit4] Completed [323/857 (1!)] on J1 in 68.22s, 9 tests, 1 failure <<< FAILURES!
[...truncated 48557 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: /tmp/ecj520641911
[ecj-lint] Compiling 1273 source files to /tmp/ecj520641911
[ecj-lint] Processing annotations
[ecj-lint] Annotations processed
[ecj-lint] Processing annotations
[ecj-lint] No elements to process
[ecj-lint] invalid Class-Path header in manifest of jar file: /home/jenkins/.ivy2/cache/org.restlet.jee/org.restlet/jars/org.restlet-2.3.0.jar
[ecj-lint] invalid Class-Path header in manifest of jar file: /home/jenkins/.ivy2/cache/org.restlet.jee/org.restlet.ext.servlet/jars/org.restlet.ext.servlet-2.3.0.jar
[ecj-lint] ----------
[ecj-lint] 1. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/client/solrj/embedded/EmbeddedSolrServer.java (at line 219)
[ecj-lint] return (NamedList<Object>) new JavaBinCodec(resolver).unmarshal(in);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 2. ERROR in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/cloud/autoscaling/AutoScalingHandler.java (at line 74)
[ecj-lint] import static org.apache.solr.common.util.Utils.fromJSON;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.solr.common.util.Utils.fromJSON is never used
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 3. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/cloud/autoscaling/sim/SimCloudManager.java (at line 788)
[ecj-lint] throw new UnsupportedOperationException("must add at least 1 node first");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'queryRequest' is not closed at this location
[ecj-lint] ----------
[ecj-lint] 4. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/cloud/autoscaling/sim/SimCloudManager.java (at line 794)
[ecj-lint] throw new UnsupportedOperationException("must add at least 1 node first");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'queryRequest' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 5. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/admin/SegmentsInfoRequestHandler.java (at line 215)
[ecj-lint] leafReader = ((FilterLeafReader)leafReader).getDelegate();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'leafReader' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 6. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/component/HttpShardHandler.java (at line 142)
[ecj-lint] return new JavaBinCodec(null, stringCache).setReadStringAsCharSeq(true);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 7. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/handler/loader/JavabinLoader.java (at line 137)
[ecj-lint] new JavaBinCodec() {
[ecj-lint] SolrParams params;
[ecj-lint] AddUpdateCommand addCmd = null;
[ecj-lint]
[ecj-lint] @Override
[ecj-lint] public List<Object> readIterator(DataInputInputStream fis) throws IOException {
[ecj-lint] while (true) {
[ecj-lint] Object o = readVal(fis);
[ecj-lint] if (o == END_OBJ) break;
[ecj-lint] if (o instanceof NamedList) {
[ecj-lint] params = ((NamedList) o).toSolrParams();
[ecj-lint] } else {
[ecj-lint] try {
[ecj-lint] if (o instanceof byte[]) {
[ecj-lint] if (params != null) req.setParams(params);
[ecj-lint] byte[] buf = (byte[]) o;
[ecj-lint] contentStreamLoader.load(req, rsp, new ContentStreamBase.ByteArrayStream(buf, null), processor);
[ecj-lint] } else {
[ecj-lint] throw new RuntimeException("unsupported type ");
[ecj-lint] }
[ecj-lint] } catch (Exception e) {
[ecj-lint] throw new RuntimeException(e);
[ecj-lint] } finally {
[ecj-lint] params = null;
[ecj-lint] req.setParams(old);
[ecj-lint] }
[ecj-lint] }
[ecj-lint] }
[ecj-lint] return Collections.emptyList();
[ecj-lint] }
[ecj-lint]
[ecj-lint] }.unmarshal(in);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 8. INFO in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/logging/log4j2/Log4j2Watcher.java (at line 187)
[ecj-lint] if (logger == root || root.equals(logger) || isRootLogger(name) || "".equals(name)) {
[ecj-lint] ^^^^^^
[ecj-lint] Unlikely argument type for equals(): Map.Entry<String,LoggerConfig> seems to be unrelated to Logger
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 9. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/update/SolrIndexSplitter.java (at line 258)
[ecj-lint] HardlinkCopyDirectoryWrapper hardLinkedDir = new HardlinkCopyDirectoryWrapper(splitDir);
[ecj-lint] ^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'hardLinkedDir' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 10. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 50)
[ecj-lint] in = new FileInputStream(src).getChannel();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 11. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 51)
[ecj-lint] out = new FileOutputStream(destination).getChannel();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 12. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/SolrCLI.java (at line 1143)
[ecj-lint] SnapshotCloudManager snapshotCloudManager = new SnapshotCloudManager(simCloudManager, config);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'snapshotCloudManager' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 13. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/core/src/java/org/apache/solr/util/TestInjection.java (at line 263)
[ecj-lint] timers.remove(this);
[ecj-lint] ^^^^
[ecj-lint] Unlikely argument type new TimerTask(){} for remove(Object) on a Collection<Timer>
[ecj-lint] ----------
[ecj-lint] 13 problems (1 error, 11 warnings, 1 info)
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/build.xml:643: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/build.xml:101: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build.xml:681: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/common-build.xml:2010: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/common-build.xml:2049: Compile failed; see the compiler error output for details.
Total time: 232 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 363 - Still
unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/363/
1 tests failed.
FAILED: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation
Error Message:
took over 10 seconds after collection creation to update aliases
Stack Trace:
java.lang.AssertionError: took over 10 seconds after collection creation to update aliases
at __randomizedtesting.SeedInfo.seed([767EA449A171EBB9:1B1421E29913068D]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.update.processor.RoutedAliasUpdateProcessorTest.waitColAndAlias(RoutedAliasUpdateProcessorTest.java:77)
at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation(TimeRoutedAliasUpdateProcessorTest.java:374)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 12578 lines...]
[junit4] Suite: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/init-core-data-001
[junit4] 2> 177788 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[767EA449A171EBB9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 177802 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[767EA449A171EBB9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 177802 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[767EA449A171EBB9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 177858 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testParse
[junit4] 2> 177858 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-001
[junit4] 2> 177858 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 177874 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 177874 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 177983 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer start zk server on port:38115
[junit4] 2> 177983 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38115
[junit4] 2> 177983 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38115
[junit4] 2> 178049 INFO (zkConnectionManagerCallback-467-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178105 INFO (zkConnectionManagerCallback-469-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178121 INFO (zkConnectionManagerCallback-471-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178141 WARN (jetty-launcher-472-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 178142 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 178142 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 178142 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 178158 WARN (jetty-launcher-472-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 178158 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 178158 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 178158 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 178158 WARN (jetty-launcher-472-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 178159 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 178159 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 178159 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 178214 WARN (jetty-launcher-472-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 178215 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 178215 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 178215 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 178303 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 178303 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 178303 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 178304 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@473f78ad{/solr,null,AVAILABLE}
[junit4] 2> 178312 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c1cceac{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46843}
[junit4] 2> 178312 INFO (jetty-launcher-472-thread-4) [ ] o.e.j.s.Server Started @178548ms
[junit4] 2> 178312 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46843}
[junit4] 2> 178313 ERROR (jetty-launcher-472-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 178313 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 178313 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 178313 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 178313 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 178313 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:15.209485Z
[junit4] 2> 178457 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 178457 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 178457 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 178473 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d682647{/solr,null,AVAILABLE}
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1880d0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46734}
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.e.j.s.Server Started @178710ms
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46734}
[junit4] 2> 178475 ERROR (jetty-launcher-472-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 178475 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 178476 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:15.371998Z
[junit4] 2> 178566 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 178566 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 178566 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 178583 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10b5364{/solr,null,AVAILABLE}
[junit4] 2> 178583 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f937006{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37041}
[junit4] 2> 178583 INFO (jetty-launcher-472-thread-2) [ ] o.e.j.s.Server Started @178819ms
[junit4] 2> 178583 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37041}
[junit4] 2> 178584 ERROR (jetty-launcher-472-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 178584 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 178584 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 178584 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 178584 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 178584 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:15.480436Z
[junit4] 2> 178667 INFO (zkConnectionManagerCallback-474-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178668 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178692 INFO (zkConnectionManagerCallback-476-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178704 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178728 INFO (zkConnectionManagerCallback-478-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 178741 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 178831 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 178831 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 178831 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 178886 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1742f04f{/solr,null,AVAILABLE}
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c49daec{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42123}
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.e.j.s.Server Started @179231ms
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42123}
[junit4] 2> 178996 ERROR (jetty-launcher-472-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 178996 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:15.892908Z
[junit4] 2> 179052 INFO (zkConnectionManagerCallback-483-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 179053 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 180389 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 180391 WARN (jetty-launcher-472-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@45455717[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 180447 WARN (jetty-launcher-472-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7dfe7954[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 180448 INFO (jetty-launcher-472-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38115/solr
[junit4] 2> 180473 INFO (zkConnectionManagerCallback-490-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 180644 INFO (zkConnectionManagerCallback-492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181085 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 181155 WARN (jetty-launcher-472-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@677cff3f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181207 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 181241 WARN (jetty-launcher-472-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@53cac0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181242 INFO (jetty-launcher-472-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38115/solr
[junit4] 2> 181243 WARN (jetty-launcher-472-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@70b31d86[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181315 WARN (jetty-launcher-472-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@10f80873[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181316 INFO (jetty-launcher-472-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38115/solr
[junit4] 2> 181328 INFO (zkConnectionManagerCallback-501-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181426 INFO (zkConnectionManagerCallback-506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181446 INFO (zkConnectionManagerCallback-508-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181463 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee0814000a, likely client has closed socket
[junit4] 2> 181572 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46734_solr
[junit4] 2> 181573 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.Overseer Overseer (id=74706240375357448-127.0.0.1:46734_solr-n_0000000000) starting
[junit4] 2> 181587 INFO (zkConnectionManagerCallback-512-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181645 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46843_solr
[junit4] 2> 181681 INFO (zkCallback-511-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 181702 INFO (zkCallback-507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 181774 INFO (zkCallback-491-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 181775 INFO (zkConnectionManagerCallback-519-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181791 INFO (zkConnectionManagerCallback-524-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 181811 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37041_solr as DOWN
[junit4] 2> 181863 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 181863 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 181863 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37041_solr
[junit4] 2> 181920 INFO (zkCallback-507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 181920 INFO (zkCallback-511-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 181920 INFO (zkCallback-491-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 181936 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38115/solr ready
[junit4] 2> 181936 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 181957 INFO (zkCallback-523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 182074 INFO (zkConnectionManagerCallback-529-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 182119 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38115/solr ready
[junit4] 2> 182187 INFO (OverseerStateUpdate-74706240375357448-127.0.0.1:46734_solr-n_0000000000) [n:127.0.0.1:46734_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46734_solr
[junit4] 2> 182240 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46734_solr as DOWN
[junit4] 2> 182263 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 182313 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 182313 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46734_solr
[junit4] 2> 182333 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 182370 INFO (zkCallback-507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 182370 INFO (zkCallback-511-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 182370 INFO (zkCallback-523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 182394 INFO (zkCallback-491-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 182406 INFO (zkCallback-518-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 182406 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38115/solr ready
[junit4] 2> 182422 INFO (zkCallback-528-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 182612 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 182699 WARN (jetty-launcher-472-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@34f63ab4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 182704 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46843.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 182849 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46843.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 182849 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46843.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 182850 INFO (jetty-launcher-472-thread-4) [n:127.0.0.1:46843_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-001/node4/.
[junit4] 2> 182864 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 183176 WARN (jetty-launcher-472-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4f2d1b3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 183177 INFO (jetty-launcher-472-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38115/solr
[junit4] 2> 183342 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 183396 INFO (zkConnectionManagerCallback-537-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 183723 INFO (zkConnectionManagerCallback-540-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 183923 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 183960 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42123_solr as DOWN
[junit4] 2> 183977 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46734.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 183977 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 183977 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42123_solr
[junit4] 2> 184013 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37041.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 184047 INFO (zkCallback-528-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184047 INFO (zkCallback-518-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184047 INFO (zkCallback-511-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184048 INFO (zkCallback-507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184048 INFO (zkCallback-523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184048 INFO (zkCallback-491-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184055 INFO (zkCallback-539-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 184128 INFO (zkConnectionManagerCallback-547-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 184201 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 184231 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46734.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 184231 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46734.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 184232 INFO (jetty-launcher-472-thread-3) [n:127.0.0.1:46734_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-001/node3/.
[junit4] 2> 184232 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37041.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 184232 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37041.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 184262 INFO (jetty-launcher-472-thread-2) [n:127.0.0.1:37041_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-001/node2/.
[junit4] 2> 184290 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38115/solr ready
[junit4] 2> 184577 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 184866 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42123.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 185105 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42123.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 185105 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42123.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 185107 INFO (jetty-launcher-472-thread-1) [n:127.0.0.1:42123_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-001/node1/.
[junit4] 2> 185850 INFO (zkConnectionManagerCallback-553-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 185851 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 185852 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38115/solr ready
[junit4] 2> 186014 INFO (qtp1691589549-1349) [n:127.0.0.1:46843_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 186084 INFO (qtp1691589549-1349) [n:127.0.0.1:46843_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=70
[junit4] 2> 186105 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.u.p.TimeRoutedAliasUpdateProcessorTest SolrClient: org.apache.solr.client.solrj.impl.CloudSolrClient@414433da
[junit4] 2> 186105 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.u.p.TimeRoutedAliasUpdateProcessorTest ClusterStateProvider org.apache.solr.client.solrj.impl.HttpClusterStateProvider@4117a2ea
[junit4] 2> 186186 INFO (jetty-closer-555-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1433419942
[junit4] 2> 186186 INFO (jetty-closer-555-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46843_solr
[junit4] 2> 186187 INFO (jetty-closer-555-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=227338849
[junit4] 2> 186187 INFO (jetty-closer-555-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46734_solr
[junit4] 2> 186194 INFO (jetty-closer-555-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1673494538
[junit4] 2> 186194 INFO (jetty-closer-555-thread-3) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37041_solr
[junit4] 2> 186206 INFO (jetty-closer-555-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 186206 INFO (jetty-closer-555-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:46734_solr as DOWN
[junit4] 2> 186214 INFO (jetty-closer-555-thread-3) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 186214 INFO (jetty-closer-555-thread-3) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37041_solr as DOWN
[junit4] 2> 186218 INFO (jetty-closer-555-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 186218 INFO (jetty-closer-555-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:46843_solr as DOWN
[junit4] 2> 186223 INFO (jetty-closer-555-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=69763554
[junit4] 2> 186223 INFO (jetty-closer-555-thread-4) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42123_solr
[junit4] 2> 186258 INFO (jetty-closer-555-thread-4) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 186258 INFO (jetty-closer-555-thread-4) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:42123_solr as DOWN
[junit4] 2> 186409 INFO (jetty-closer-555-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 186421 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 186433 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 186465 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 186502 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 186542 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@34ca6066: rootName = solr_37041, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@61d4eef
[junit4] 2> 186562 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35f7a0a9: rootName = solr_42123, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2aaad86a
[junit4] 2> 186578 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@11c5d60a: rootName = solr_46843, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4ec5ebf0
[junit4] 2> 186599 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@bc5ef35: rootName = solr_46734, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7ab48e7a
[junit4] 2> 186616 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 186616 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f40fda5: rootName = solr_46734, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5638ed62
[junit4] 2> 186618 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 186618 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@31bbc9ec: rootName = solr_46734, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@77031a77
[junit4] 2> 186652 INFO (jetty-closer-555-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 186671 INFO (closeThreadPool-568-thread-2) [ ] o.a.s.c.Overseer Overseer (id=74706240375357448-127.0.0.1:46734_solr-n_0000000000) closing
[junit4] 2> 186672 INFO (OverseerAutoScalingTriggerThread-74706240375357448-127.0.0.1:46734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 186676 INFO (OverseerStateUpdate-74706240375357448-127.0.0.1:46734_solr-n_0000000000) [n:127.0.0.1:46734_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:46734_solr
[junit4] 2> 186806 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 186806 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58704341: rootName = solr_42123, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5638ed62
[junit4] 2> 186809 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 186809 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@281d9faa: rootName = solr_42123, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@77031a77
[junit4] 2> 186827 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 186827 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6206aa0d: rootName = solr_37041, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5638ed62
[junit4] 2> 186843 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 186843 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@74c01b1b: rootName = solr_46843, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5638ed62
[junit4] 2> 186845 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 186845 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b0b79a7: rootName = solr_46843, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@77031a77
[junit4] 2> 186846 INFO (jetty-closer-555-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 186938 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 186938 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48e457c9: rootName = solr_37041, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@77031a77
[junit4] 2> 186938 INFO (jetty-closer-555-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 186972 INFO (jetty-closer-555-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 186976 INFO (closeThreadPool-568-thread-1) [ ] o.a.s.c.Overseer Overseer (id=74706240375357448-127.0.0.1:46734_solr-n_0000000000) closing
[junit4] 2> 187027 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee0814000d, likely client has closed socket
[junit4] 2> 187276 INFO (jetty-closer-555-thread-2) [ ] o.a.s.c.Overseer Overseer (id=74706240375357448-127.0.0.1:46734_solr-n_0000000000) closing
[junit4] 2> 187303 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee0814000f, likely client has closed socket
[junit4] 2> 187402 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee08140008, likely client has closed socket
[junit4] 2> 187403 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee0814000b, likely client has closed socket
[junit4] 2> 187403 INFO (jetty-closer-555-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1880d0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 187403 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee0814000e, likely client has closed socket
[junit4] 2> 187476 INFO (jetty-closer-555-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d682647{/solr,null,UNAVAILABLE}
[junit4] 2> 187478 INFO (jetty-closer-555-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 187528 INFO (jetty-closer-555-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1c49daec{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 187589 INFO (jetty-closer-555-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1742f04f{/solr,null,UNAVAILABLE}
[junit4] 2> 187590 INFO (jetty-closer-555-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 187648 INFO (jetty-closer-555-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3c1cceac{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 187684 INFO (jetty-closer-555-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@473f78ad{/solr,null,UNAVAILABLE}
[junit4] 2> 187685 INFO (jetty-closer-555-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 187705 INFO (jetty-closer-555-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@f937006{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 187705 INFO (jetty-closer-555-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10b5364{/solr,null,UNAVAILABLE}
[junit4] 2> 187705 INFO (jetty-closer-555-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 187777 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 187815 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> 9 /solr/aliases.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/live_nodes
[junit4] 2> 9 /solr/collections
[junit4] 2>
[junit4] 2> 187823 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38115
[junit4] 2> 187823 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38115
[junit4] 2> 187824 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[767EA449A171EBB9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testParse
[junit4] 2> 187897 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPreemptiveCreation
[junit4] 2> 187897 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-002
[junit4] 2> 187898 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 187903 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 187903 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 188012 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer start zk server on port:34032
[junit4] 2> 188012 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34032
[junit4] 2> 188012 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34032
[junit4] 2> 188267 INFO (zkConnectionManagerCallback-573-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188423 INFO (zkConnectionManagerCallback-575-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188502 INFO (zkConnectionManagerCallback-577-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188531 WARN (jetty-launcher-578-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 188532 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 188532 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 188532 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 188532 WARN (jetty-launcher-578-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 188533 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 188533 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 188533 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 188540 WARN (jetty-launcher-578-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 188541 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 188541 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 188541 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 188576 WARN (jetty-launcher-578-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 188576 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 188576 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 188577 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 188685 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 188685 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 188685 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 188686 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@69c00dc9{/solr,null,AVAILABLE}
[junit4] 2> 188697 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@642bcc12{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40524}
[junit4] 2> 188697 INFO (jetty-launcher-578-thread-4) [ ] o.e.j.s.Server Started @188933ms
[junit4] 2> 188735 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 188735 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 188735 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 188751 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 188751 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 188751 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 188752 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60187b8d{/solr,null,AVAILABLE}
[junit4] 2> 188697 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40524}
[junit4] 2> 188786 ERROR (jetty-launcher-578-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 188786 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 188786 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 188786 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 188786 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 188787 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:25.682972Z
[junit4] 2> 188787 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 188787 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 188787 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 188787 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@619f24bd{/solr,null,AVAILABLE}
[junit4] 2> 188787 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2b7f53b3{/solr,null,AVAILABLE}
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2232b4b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:33997}
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1d593cf5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46396}
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.e.j.s.Server Started @189024ms
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.e.j.s.Server Started @189024ms
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33997}
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46396}
[junit4] 2> 188788 ERROR (jetty-launcher-578-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 188788 ERROR (jetty-launcher-578-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:25.684946Z
[junit4] 2> 188788 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:25.684959Z
[junit4] 2> 188794 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@49beb242{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:34681}
[junit4] 2> 188794 INFO (jetty-launcher-578-thread-2) [ ] o.e.j.s.Server Started @189030ms
[junit4] 2> 188794 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34681}
[junit4] 2> 188795 ERROR (jetty-launcher-578-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 188795 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 188795 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 188795 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 188795 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 188795 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-17T07:22:25.691286Z
[junit4] 2> 188916 INFO (zkConnectionManagerCallback-584-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188920 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 188936 INFO (zkConnectionManagerCallback-586-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188940 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 188996 INFO (zkConnectionManagerCallback-582-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 189288 INFO (zkConnectionManagerCallback-580-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 189468 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 189468 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 190298 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 190350 WARN (jetty-launcher-578-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@79873772[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 190387 WARN (jetty-launcher-578-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6057772b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 190388 INFO (jetty-launcher-578-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34032/solr
[junit4] 2> 190439 INFO (zkConnectionManagerCallback-596-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 190589 INFO (zkConnectionManagerCallback-598-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 190768 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 190769 WARN (jetty-launcher-578-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@350e42b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 190865 WARN (jetty-launcher-578-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6be5e6c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 190866 INFO (jetty-launcher-578-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34032/solr
[junit4] 2> 190939 INFO (zkConnectionManagerCallback-606-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 191156 INFO (zkConnectionManagerCallback-608-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 191268 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33997_solr
[junit4] 2> 191269 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.c.Overseer Overseer (id=74706241030193162-127.0.0.1:33997_solr-n_0000000000) starting
[junit4] 2> 191447 INFO (zkConnectionManagerCallback-615-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 191451 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34032/solr ready
[junit4] 2> 191484 INFO (OverseerStateUpdate-74706241030193162-127.0.0.1:33997_solr-n_0000000000) [n:127.0.0.1:33997_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33997_solr
[junit4] 2> 191521 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33997_solr
[junit4] 2> 191557 INFO (zkCallback-607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 191557 INFO (zkCallback-614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 191755 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 192134 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33997.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 192249 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 192285 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46396_solr as DOWN
[junit4] 2> 192300 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33997.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 192300 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33997.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 192301 INFO (jetty-launcher-578-thread-3) [n:127.0.0.1:33997_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-002/node3/.
[junit4] 2> 192322 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 192322 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46396_solr
[junit4] 2> 192357 INFO (zkCallback-607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 192391 INFO (zkCallback-614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 192513 INFO (zkCallback-597-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 192513 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 192517 INFO (zkConnectionManagerCallback-621-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 192522 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 192523 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34032/solr ready
[junit4] 2> 192550 WARN (jetty-launcher-578-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@42739f37[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 192567 WARN (jetty-launcher-578-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@44413aeb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 192568 INFO (jetty-launcher-578-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34032/solr
[junit4] 2> 192626 INFO (zkConnectionManagerCallback-627-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 192930 INFO (zkConnectionManagerCallback-629-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 192954 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 193046 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46396.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 193217 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 193232 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34681_solr as DOWN
[junit4] 2> 193233 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 193233 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34681_solr
[junit4] 2> 193252 INFO (zkCallback-597-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 193268 INFO (zkCallback-620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 193269 INFO (zkCallback-628-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 193269 INFO (zkConnectionManagerCallback-637-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 193282 INFO (zkCallback-607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 193270 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 193288 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34032/solr ready
[junit4] 2> 193303 INFO (zkCallback-614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 193319 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46396.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 193319 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46396.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 193320 INFO (jetty-launcher-578-thread-1) [n:127.0.0.1:46396_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-002/node1/.
[junit4] 2> 193362 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 193408 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 193413 WARN (jetty-launcher-578-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5ea9bc8e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 193436 WARN (jetty-launcher-578-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@20f8a1d6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 193474 INFO (jetty-launcher-578-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34032/solr
[junit4] 2> 193492 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34681.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 193506 INFO (zkConnectionManagerCallback-644-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 193507 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10968ee2f1c0010, likely client has closed socket
[junit4] 2> 193625 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34681.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 193625 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34681.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 193652 INFO (zkConnectionManagerCallback-646-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 193688 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 193705 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40524_solr as DOWN
[junit4] 2> 193705 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 193706 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40524_solr
[junit4] 2> 193732 INFO (jetty-launcher-578-thread-2) [n:127.0.0.1:34681_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-002/node2/.
[junit4] 2> 193733 INFO (zkCallback-620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193733 INFO (zkCallback-614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193736 INFO (zkCallback-628-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193744 INFO (zkCallback-597-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193772 INFO (zkCallback-607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193781 INFO (zkCallback-645-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193784 INFO (zkCallback-636-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 193839 INFO (zkConnectionManagerCallback-653-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 193841 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 193854 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34032/solr ready
[junit4] 2> 194024 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 194116 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40524.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 194244 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40524.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 194244 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40524.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29e280d9
[junit4] 2> 194245 INFO (jetty-launcher-578-thread-4) [n:127.0.0.1:40524_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_767EA449A171EBB9-001/tempDir-002/node4/.
[junit4] 2> 195045 INFO (zkConnectionManagerCallback-659-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 195046 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 195047 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34032/solr ready
[junit4] 2> 195062 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.u.p.TimeRoutedAliasUpdateProcessorTest SolrClient: org.apache.solr.client.solrj.impl.CloudSolrClient@40f3fb77
[junit4] 2> 195062 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.u.p.TimeRoutedAliasUpdateProcessorTest ClusterStateProvider 127.0.0.1:34032/solr
[junit4] 2> 195138 INFO (zkConnectionManagerCallback-664-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 195139 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 195140 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testPreemptiveCreation-seed#[767EA449A171EBB9]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34032/solr ready
[junit4] 2> 195168 INFO (qtp571445568-1551) [n:127.0.0.1:34681_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=_default&name=testPreemptiveCreation&action=CREATE&wt=javabin&version=2
[junit4] 2> 195191 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , {
[junit4] 2> "name":"testPreemptiveCreation",
[junit4] 2> "baseConfigSet":"_default",
[junit4] 2> "immutable":"false",
[junit4] 2> "operation":"configsets:create"}
[junit4] 2> 195193 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testPreemptiveCreation/protwords.txt
[junit4] 2> 195211 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testPreemptiveCreation/managed-schema
[junit4] 2> 195213 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testPreemptiveCreation/solrconfig.xml
[junit4] 2> 195214 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testPreemptiveCreation/synonyms.txt
[junit4] 2> 195248 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testPreemptiveCreation/stopwords.txt
[junit4] 2> 195249 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testPreemptiveCreation/params.json
[junit4] 2> 195285 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testPreemptiveCreation/lang/contractions_it.txt
[junit4] 2> 195286 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testPreemptiveCreation/lang/stopwords_no.txt
[junit4] 2> 195300 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testPreemptiveCreation/lang/stopwords_hy.txt
[junit4] 2> 195302 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testPreemptiveCreation/lang/stemdict_nl.txt
[junit4] 2> 195303 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testPreemptiveCreation/lang/contractions_ca.txt
[junit4] 2> 195337 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testPreemptiveCreation/lang/stopwords_id.txt
[junit4] 2> 195339 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testPreemptiveCreation/lang/stopwords_ga.txt
[junit4] 2> 195372 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testPreemptiveCreation/lang/stopwords_da.txt
[junit4] 2> 195374 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testPreemptiveCreation/lang/stopwords_hi.txt
[junit4] 2> 195375 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testPreemptiveCreation/lang/stopwords_ja.txt
[junit4] 2> 195425 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testPreemptiveCreation/lang/stopwords_pt.txt
[junit4] 2> 195427 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testPreemptiveCreation/lang/stopwords_hu.txt
[junit4] 2> 195428 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testPreemptiveCreation/lang/stopwords_el.txt
[junit4] 2> 195462 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testPreemptiveCreation/lang/stopwords_ru.txt
[junit4] 2> 195465 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testPreemptiveCreation/lang/stopwords_tr.txt
[junit4] 2> 195572 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testPreemptiveCreation/lang/stopwords_ar.txt
[junit4] 2> 195573 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testPreemptiveCreation/lang/userdict_ja.txt
[junit4] 2> 195574 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testPreemptiveCreation/lang/stopwords_it.txt
[junit4] 2> 195592 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testPreemptiveCreation/lang/stopwords_eu.txt
[junit4] 2> 195594 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testPreemptiveCreation/lang/stopwords_cz.txt
[junit4] 2> 195628 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testPreemptiveCreation/lang/stoptags_ja.txt
[junit4] 2> 195630 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testPreemptiveCreation/lang/contractions_fr.txt
[junit4] 2> 195631 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testPreemptiveCreation/lang/stopwords_fa.txt
[junit4] 2> 195665 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testPreemptiveCreation/lang/stopwords_de.txt
[junit4] 2> 195666 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testPreemptiveCreation/lang/contractions_ga.txt
[junit4] 2> 195700 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testPreemptiveCreation/lang/stopwords_ca.txt
[junit4] 2> 195702 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testPreemptiveCreation/lang/stopwords_nl.txt
[junit4] 2> 195704 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testPreemptiveCreation/lang/stopwords_bg.txt
[junit4] 2> 195738 INFO (OverseerThreadFactory-445-thread-1-processing-n:127.0.0.1:33997_solr) [n:127.0.0.1:33997_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /
[...truncated too long message...]
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null1027502748
[copy] Copying 240 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null1027502748
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null1027502748
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: #;working@lucene2-us-west.apache.org
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.54 in public
[ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public
[ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 34ms :: artifacts dl 5ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 9 | 0 | 0 | 0 || 9 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 253 minutes 42 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)