You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/12/28 05:08:17 UTC
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_144) - Build #
21158 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21158/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.TestCollectionAPI.test
Error Message:
Property 'preferredleader' with value null not set correctly for collection/replica pair: testcollection/core_node7 property map is {core=testcollection_shard2_replica_n3, base_url=https://127.0.0.1:40747, node_name=127.0.0.1:40747_, state=active, type=NRT, leader=true}.
Stack Trace:
java.lang.AssertionError: Property 'preferredleader' with value null not set correctly for collection/replica pair: testcollection/core_node7 property map is {core=testcollection_shard2_replica_n3, base_url=https://127.0.0.1:40747, node_name=127.0.0.1:40747_, state=active, type=NRT, leader=true}.
at __randomizedtesting.SeedInfo.seed([8257B112E10D42BA:A038EC84FF12F42]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.ReplicaPropertiesBase.verifyPropertyVal(ReplicaPropertiesBase.java:95)
at org.apache.solr.cloud.TestCollectionAPI.replicaPropTest(TestCollectionAPI.java:428)
at org.apache.solr.cloud.TestCollectionAPI.test(TestCollectionAPI.java:92)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at __randomizedtesting.SeedInfo.seed([8257B112E10D42BA:4BE2F3BCE86A844F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:711)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12273 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> 657931 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/init-core-data-001
[junit4] 2> 657932 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 657933 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 657934 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/tempDir-001
[junit4] 2> 657934 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 657934 INFO (Thread-1210) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 657934 INFO (Thread-1210) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 657935 ERROR (Thread-1210) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 658034 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35093
[junit4] 2> 658036 INFO (zkConnectionManagerCallback-1578-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658049 INFO (jetty-launcher-1575-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 658050 INFO (jetty-launcher-1575-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 658067 INFO (jetty-launcher-1575-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@49ebd2{/solr,null,AVAILABLE}
[junit4] 2> 658067 INFO (jetty-launcher-1575-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ea6a02{/solr,null,AVAILABLE}
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@afe07f{SSL,[ssl, http/1.1]}{127.0.0.1:40483}
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.e.j.s.Server Started @659097ms
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40483}
[junit4] 2> 658079 ERROR (jetty-launcher-1575-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 658079 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T04:11:16.296Z
[junit4] 2> 658080 INFO (jetty-launcher-1575-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a8d00a{SSL,[ssl, http/1.1]}{127.0.0.1:33193}
[junit4] 2> 658080 INFO (jetty-launcher-1575-thread-2) [ ] o.e.j.s.Server Started @659098ms
[junit4] 2> 658080 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33193}
[junit4] 2> 658080 ERROR (jetty-launcher-1575-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 658080 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 658080 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 658080 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 658081 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T04:11:16.298Z
[junit4] 2> 658087 INFO (zkConnectionManagerCallback-1580-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658087 INFO (zkConnectionManagerCallback-1582-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658088 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 658090 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 658111 INFO (jetty-launcher-1575-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35093/solr
[junit4] 2> 658111 INFO (jetty-launcher-1575-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35093/solr
[junit4] 2> 658115 INFO (zkConnectionManagerCallback-1588-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658115 INFO (zkConnectionManagerCallback-1590-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658116 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1609b512b380003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 658119 INFO (zkConnectionManagerCallback-1594-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658119 INFO (zkConnectionManagerCallback-1593-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658199 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 658199 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33193_solr
[junit4] 2> 658200 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.Overseer Overseer (id=99249964721897477-127.0.0.1:33193_solr-n_0000000000) starting
[junit4] 2> 658208 INFO (jetty-launcher-1575-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 658209 INFO (jetty-launcher-1575-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40483_solr
[junit4] 2> 658219 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 658223 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 658227 INFO (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 658229 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33193_solr
[junit4] 2> 658230 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 658230 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 658237 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:40483_solr]
[junit4] 2> 658238 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 658238 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 658241 INFO (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 658241 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 658242 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 658242 DEBUG (ScheduledTrigger-2148-thread-1) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 658518 INFO (jetty-launcher-1575-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40483.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 658529 INFO (jetty-launcher-1575-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40483.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 658529 INFO (jetty-launcher-1575-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40483.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 658530 INFO (jetty-launcher-1575-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/tempDir-001/node1/.
[junit4] 2> 658545 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33193.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 658563 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33193.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 658563 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33193.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 658565 INFO (jetty-launcher-1575-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/tempDir-001/node2/.
[junit4] 2> 658592 INFO (zkConnectionManagerCallback-1600-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658596 INFO (zkConnectionManagerCallback-1604-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658597 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 658598 INFO (SUITE-TriggerIntegrationTest-seed#[8257B112E10D42BA]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35093/solr ready
[junit4] 2> 658612 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEventQueue
[junit4] 2> 658613 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 658613 DEBUG (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 658613 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 658616 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 658616 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 658616 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 658675 INFO (qtp16149775-5885) [n:127.0.0.1:33193_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 658681 INFO (qtp16149775-5885) [n:127.0.0.1:33193_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 658688 INFO (qtp16149775-5885) [n:127.0.0.1:33193_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 658730 INFO (qtp26461558-5900) [n:127.0.0.1:40483_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 658731 DEBUG (qtp16149775-5893) [n:127.0.0.1:33193_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 658731 INFO (qtp16149775-5893) [n:127.0.0.1:33193_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=47
[junit4] 2> 658731 DEBUG (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 658732 INFO (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventQueueAction instantiated
[junit4] 2> 658733 DEBUG (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:40483_solr, 127.0.0.1:33193_solr]
[junit4] 2> 658733 DEBUG (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}], enabled=true}
[junit4] 2> 658733 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 658734 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 658734 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 658734 DEBUG (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 658734 DEBUG (ScheduledTrigger-2148-thread-1) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 658734 DEBUG (ScheduledTrigger-2148-thread-1) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 658736 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 658737 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1142e6{/solr,null,AVAILABLE}
[junit4] 2> 658738 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@d1ad9a{SSL,[ssl, http/1.1]}{127.0.0.1:37475}
[junit4] 2> 658738 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.e.j.s.Server Started @659756ms
[junit4] 2> 658738 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37475}
[junit4] 2> 658738 ERROR (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 658738 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 658739 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 658739 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 658739 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T04:11:16.956Z
[junit4] 2> 658742 INFO (zkConnectionManagerCallback-1606-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658743 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 658759 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35093/solr
[junit4] 2> 658762 INFO (zkConnectionManagerCallback-1610-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658764 INFO (zkConnectionManagerCallback-1612-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 658772 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 658773 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 658775 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37475_solr
[junit4] 2> 658777 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 658777 INFO (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 658778 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 658779 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 659027 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37475.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 659040 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37475.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 659040 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37475.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 659042 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [n:127.0.0.1:37475_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/tempDir-001/node3/.
[junit4] 2> 659736 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 659736 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 659736 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:37475_solr at time 2135419925353853
[junit4] 2> 660680 INFO (OverseerCollectionConfigSetProcessor-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_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> 660737 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 660737 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 660737 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:37475_solr] added at times [2135419925353853], now=2135420926384604
[junit4] 2> 660737 DEBUG (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"796273218d57dT8s7uzzb43u62n6bqvaatpj3z7",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2135419925353853,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2135419925353853],
[junit4] 2> "nodeNames":["127.0.0.1:37475_solr"]}}
[junit4] 2> 660738 INFO (ScheduledTrigger-2148-thread-2) [n:127.0.0.1:33193_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 660738 DEBUG (AutoscalingActionExecutor-2149-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"796273218d57dT8s7uzzb43u62n6bqvaatpj3z7",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2135419925353853,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2135419925353853],
[junit4] 2> "_enqueue_time_":2135420926591871,
[junit4] 2> "nodeNames":["127.0.0.1:37475_solr"]}}
[junit4] 2> 660738 INFO (AutoscalingActionExecutor-2149-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.TriggerIntegrationTest -- event: {
[junit4] 2> "id":"796273218d57dT8s7uzzb43u62n6bqvaatpj3z7",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2135419925353853,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2135419925353853],
[junit4] 2> "_enqueue_time_":2135420926591871,
[junit4] 2> "nodeNames":["127.0.0.1:37475_solr"]}}
[junit4] 2> 660739 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1a8d00a{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 660740 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=10161137
[junit4] 2> 660740 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 660740 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@451527: rootName = solr_33193, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@504c82
[junit4] 2> 660744 DEBUG (AutoscalingActionExecutor-2149-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 5 ms for event id=796273218d57dT8s7uzzb43u62n6bqvaatpj3z7
[junit4] 2> 660749 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 660750 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48b2e1: rootName = solr_33193, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1c58a8b
[junit4] 2> 660757 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 660758 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12d53de: rootName = solr_33193, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1fa076b
[junit4] 2> 660759 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33193_solr
[junit4] 2> 660759 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 660760 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 660760 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 660760 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 660760 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.Overseer Overseer (id=99249964721897477-127.0.0.1:33193_solr-n_0000000000) closing
[junit4] 2> 660760 INFO (zkCallback-1592-thread-1-processing-n:127.0.0.1:33193_solr) [n:127.0.0.1:33193_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 660760 INFO (OverseerStateUpdate-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:33193_solr
[junit4] 2> 660760 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 660760 WARN (OverseerAutoScalingTriggerThread-99249964721897477-127.0.0.1:33193_solr-n_0000000000) [n:127.0.0.1:33193_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 660761 DEBUG (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 660762 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@49ebd2{/solr,null,UNAVAILABLE}
[junit4] 2> 660762 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40483_solr
[junit4] 2> 660763 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.Overseer Overseer (id=99249964721897478-127.0.0.1:40483_solr-n_0000000001) starting
[junit4] 2> 660774 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 660775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 660775 INFO (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventQueueAction instantiated
[junit4] 2> 660775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:40483_solr]
[junit4] 2> 660775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}], enabled=true}
[junit4] 2> 660775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:40483_solr]
[junit4] 2> 660775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion -1
[junit4] 2> 660775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 660779 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 660782 DEBUG (ScheduledTrigger-2163-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 660782 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 660783 DEBUG (ScheduledTrigger-2163-thread-2) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 660783 DEBUG (ScheduledTrigger-2163-thread-2) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 661783 DEBUG (ScheduledTrigger-2163-thread-1) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 661784 DEBUG (ScheduledTrigger-2163-thread-3) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 661784 DEBUG (ScheduledTrigger-2163-thread-3) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 662785 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 662785 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 662785 DEBUG (ScheduledTrigger-2163-thread-2) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 663785 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 663785 DEBUG (ScheduledTrigger-2163-thread-3) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 663785 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 664786 DEBUG (ScheduledTrigger-2163-thread-2) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 664786 DEBUG (ScheduledTrigger-2163-thread-3) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 664786 DEBUG (ScheduledTrigger-2163-thread-2) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 665786 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 665786 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 665786 DEBUG (ScheduledTrigger-2163-thread-3) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 666786 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 666786 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 666787 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 667787 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 667787 DEBUG (ScheduledTrigger-2163-thread-3) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 667787 DEBUG (ScheduledTrigger-2163-thread-4) [n:127.0.0.1:40483_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 668763 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[8257B112E10D42BA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEventQueue
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TriggerIntegrationTest -Dtests.method=testEventQueue -Dtests.seed=8257B112E10D42BA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nn-NO -Dtests.timezone=America/Bogota -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 10.2s J1 | TriggerIntegrationTest.testEventQueue <<<
[junit4] > Throwable #1: java.lang.AssertionError: action wasn't interrupted
[junit4] > at __randomizedtesting.SeedInfo.seed([8257B112E10D42BA:4BE2F3BCE86A844F]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:711)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 668775 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeMarkersRegistration
[junit4] 2> 668775 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5
[junit4] 2> 668775 DEBUG (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 668775 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 668777 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 668777 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 668777 DEBUG (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 668822 INFO (qtp18805272-5954) [n:127.0.0.1:37475_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 668828 INFO (qtp18805272-5954) [n:127.0.0.1:37475_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 668831 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 668832 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@acaab5{/solr,null,AVAILABLE}
[junit4] 2> 668832 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14662c8{SSL,[ssl, http/1.1]}{127.0.0.1:38319}
[junit4] 2> 668832 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.Server Started @669850ms
[junit4] 2> 668832 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38319}
[junit4] 2> 668833 ERROR (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 668833 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 668833 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 668833 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 668833 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T04:11:27.050Z
[junit4] 2> 668834 INFO (zkConnectionManagerCallback-1616-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 668835 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 668845 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35093/solr
[junit4] 2> 668846 INFO (zkConnectionManagerCallback-1620-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 668848 INFO (zkConnectionManagerCallback-1622-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 668852 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 668853 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 668856 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38319_solr
[junit4] 2> 668858 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668858 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668858 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 668858 INFO (zkCallback-1621-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 669057 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38319.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 669070 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38319.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 669070 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38319.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 669072 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:38319_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/tempDir-001/node4/.
[junit4] 2> 669117 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.TriggerIntegrationTest ====== KILL OVERSEER 1
[junit4] 2> 669118 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@afe07f{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 669118 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15849993
[junit4] 2> 669118 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 669118 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4fd268: rootName = solr_40483, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@de040a
[junit4] 2> 669128 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 669128 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@161e528: rootName = solr_40483, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1c58a8b
[junit4] 2> 669136 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 669136 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c8a8e: rootName = solr_40483, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1fa076b
[junit4] 2> 669137 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:40483_solr
[junit4] 2> 669138 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 669138 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 669138 INFO (zkCallback-1591-thread-1-processing-n:127.0.0.1:40483_solr) [n:127.0.0.1:40483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 669138 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 669138 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 669138 INFO (zkCallback-1621-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 669138 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.Overseer Overseer (id=99249964721897478-127.0.0.1:40483_solr-n_0000000001) closing
[junit4] 2> 669138 INFO (OverseerStateUpdate-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:40483_solr
[junit4] 2> 669138 WARN (OverseerAutoScalingTriggerThread-99249964721897478-127.0.0.1:40483_solr-n_0000000001) [n:127.0.0.1:40483_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 669139 DEBUG (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 669147 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37475_solr
[junit4] 2> 669147 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1ea6a02{/solr,null,UNAVAILABLE}
[junit4] 2> 669148 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.Overseer Overseer (id=99249964721897483-127.0.0.1:37475_solr-n_0000000002) starting
[junit4] 2> 669155 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 669156 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 669157 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:38319_solr]
[junit4] 2> 669157 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion -1
[junit4] 2> 669157 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 669158 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 669158 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 669158 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 670159 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 671160 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 672160 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 673161 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 674148 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.TriggerIntegrationTest ====== ADD TRIGGERS
[junit4] 2> 674161 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 674212 INFO (qtp18805272-5949) [n:127.0.0.1:37475_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 674217 INFO (qtp12193200-5993) [n:127.0.0.1:38319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 674217 DEBUG (qtp12193200-5986) [n:127.0.0.1:38319_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 674218 INFO (qtp12193200-5986) [n:127.0.0.1:38319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 674218 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 674225 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:38319_solr]
[junit4] 2> 674226 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:38319_solr]
[junit4] 2> 674226 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventMarkerAction}], enabled=true}
[junit4] 2> 674226 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 674227 INFO (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventMarkerAction init
[junit4] 2> 674228 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 674228 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 674228 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 674233 INFO (qtp18805272-5949) [n:127.0.0.1:37475_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 674238 INFO (qtp12193200-5992) [n:127.0.0.1:38319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 674239 DEBUG (qtp18805272-5955) [n:127.0.0.1:37475_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 674239 INFO (qtp18805272-5955) [n:127.0.0.1:37475_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 674240 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8
[junit4] 2> 674240 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:38319_solr]
[junit4] 2> 674240 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:38319_solr]
[junit4] 2> 674241 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:37475_solr, 127.0.0.1:38319_solr]
[junit4] 2> 674241 DEBUG (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventMarkerAction}], enabled=true}
[junit4] 2> 674241 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 674242 INFO (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventMarkerAction init
[junit4] 2> 674242 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 2
[junit4] 2> 674242 DEBUG (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 674243 INFO (qtp18805272-5950) [n:127.0.0.1:37475_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 674252 INFO (qtp18805272-5950) [n:127.0.0.1:37475_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 674254 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.TriggerIntegrationTest ====== ADD NODE 1
[junit4] 2> 674260 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 674265 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@620990{/solr,null,AVAILABLE}
[junit4] 2> 674266 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1264aeb{SSL,[ssl, http/1.1]}{127.0.0.1:35091}
[junit4] 2> 674266 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.Server Started @675284ms
[junit4] 2> 674266 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35091}
[junit4] 2> 674266 ERROR (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 674266 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 674266 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 674266 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 674267 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T04:11:32.484Z
[junit4] 2> 674270 INFO (zkConnectionManagerCallback-1626-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 674271 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 674289 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35093/solr
[junit4] 2> 674293 INFO (zkConnectionManagerCallback-1630-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 674302 INFO (zkConnectionManagerCallback-1632-thread-1-processing-n:127.0.0.1:35091_solr) [n:127.0.0.1:35091_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 674307 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 674308 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 674310 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35091_solr
[junit4] 2> 674315 INFO (zkCallback-1621-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 674315 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 674315 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 674316 INFO (zkCallback-1631-thread-1-processing-n:127.0.0.1:35091_solr) [n:127.0.0.1:35091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 674611 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35091.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 674635 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35091.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 674635 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35091.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ada017
[junit4] 2> 674638 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [n:127.0.0.1:35091_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.TriggerIntegrationTest_8257B112E10D42BA-001/tempDir-001/node5/.
[junit4] 2> 675161 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 675229 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 675230 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 675230 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:35091_solr at time 2135435419162869
[junit4] 2> 675244 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3
[junit4] 2> 676163 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 676230 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 676230 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 676230 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:35091_solr] added at times [2135435419162869], now=2135436420038904
[junit4] 2> 676231 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"7962acd9998f5T8s7uzzb43u62n6bqvaatpj3z9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2135435419162869,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2135435419162869],
[junit4] 2> "nodeNames":["127.0.0.1:35091_solr"]}}
[junit4] 2> 676231 INFO (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 676232 DEBUG (AutoscalingActionExecutor-2175-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"7962acd9998f5T8s7uzzb43u62n6bqvaatpj3z9",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":2135435419162869,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2135435419162869],
[junit4] 2> "_enqueue_time_":2135436420213685,
[junit4] 2> "nodeNames":["127.0.0.1:35091_solr"]}}
[junit4] 2> 676232 DEBUG (AutoscalingActionExecutor-2175-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0 ms for event id=7962acd9998f5T8s7uzzb43u62n6bqvaatpj3z9
[junit4] 2> 676245 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3
[junit4] 2> 676250 INFO (OverseerCollectionConfigSetProcessor-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_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> 677163 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 677232 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 677233 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 677246 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3
[junit4] 2> 678164 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 678233 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 678233 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 678246 DEBUG (ScheduledTrigger-2174-thread-1) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3
[junit4] 2> 679164 DEBUG (ScheduledTrigger-2174-thread-2) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 679233 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 679233 DEBUG (ScheduledTrigger-2174-thread-4) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 679246 DEBUG (ScheduledTrigger-2174-thread-3) [n:127.0.0.1:37475_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 3
[junit4] 2> 679699 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.TriggerIntegrationTest ====== KILL OVERSEER 2
[junit4] 2> 679700 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@d1ad9a{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 679700 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2604898
[junit4] 2> 679700 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 679700 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f4f97b: rootName = solr_37475, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@10d1908
[junit4] 2> 679707 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 679707 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b1771f: rootName = solr_37475, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1c58a8b
[junit4] 2> 679712 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 679712 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@abe68c: rootName = solr_37475, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1fa076b
[junit4] 2> 679713 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37475_solr
[junit4] 2> 679714 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 679714 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 679714 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.Overseer Overseer (id=99249964721897483-127.0.0.1:37475_solr-n_0000000002) closing
[junit4] 2> 679714 INFO (OverseerStateUpdate-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37475_solr
[junit4] 2> 679714 WARN (OverseerAutoScalingTriggerThread-99249964721897483-127.0.0.1:37475_solr-n_0000000002) [n:127.0.0.1:37475_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 679715 INFO (zkCallback-1621-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 679715 INFO (zkCallback-1603-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 679716 INFO (zkCallback-1631-thread-1-processing-n:127.0.0.1:35091_solr) [n:127.0.0.1:35091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 679716 DEBUG (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 679716 INFO (zkCallback-1611-thread-1-processing-n:127.0.0.1:37475_solr) [n:127.0.0.1:37475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 679717 INFO (zkCallback-1621-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38319_solr
[junit4] 2> 679717 INFO (TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[8257B112E10D42BA]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1142e6{/solr,null,UNAVAILABLE}
[junit4] 2> 679717 INFO (zkCallback-1621-thread-1-processing-n:127.0.0.1:38319_solr) [n:127.0.0.1:38319_solr ] o.a.s.c.Overseer Overseer (id=99249964721897486-127.0.0.1:38319_solr-n_0000000003) starting
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:35091_solr, 127.0.0.1:38319_solr]
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:35091_solr, 127.0.0.1:38319_solr]
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:35091_solr, 127.0.0.1:38319_solr]
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventMarkerAction}], enabled=true}
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion -1
[junit4] 2> 679721 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 679722 INFO (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventMarkerAction init
[junit4] 2> 679722 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path: 127.0.0.1:37475_solr
[junit4] 2> 679723 INFO (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventMarkerAction init
[junit4] 2> 679723 DEBUG (ScheduledTrigger-2185-thread-1) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 2
[junit4] 2> 679723 DEBUG (ScheduledTrigger-2185-thread-1) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:37475_solr
[junit4] 2> 679723 DEBUG (OverseerAutoScalingTriggerThread-99249964721897486-127.0.0.1:38319_solr-n_0000000003) [n:127.0.0.1:38319_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 679724 DEBUG (ScheduledTrigger-2185-thread-2) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 679724 DEBUG (ScheduledTrigger-2185-thread-2) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 679724 DEBUG (ScheduledTrigger-2185-thread-3) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 679724 DEBUG (ScheduledTrigger-2185-thread-3) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:37475_solr
[junit4] 2> 680724 DEBUG (ScheduledTrigger-2185-thread-1) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with currently live nodes: 2
[junit4] 2> 680724 DEBUG (ScheduledTrigger-2185-thread-1) [n:127.0.0.1:38319_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:37475_solr]
[junit4] 2> 680724 DEBUG (ScheduledTrigger-2185-thread-1) [n:127.0.0.1:38319_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"7962bd96fba70T8s7uzzb43u62n6bqvaatpj3zb",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":2135439912712816,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2135439912712816],
[junit4] 2> "nodeNames"
[...truncated too long message...]
27.0.0.1:39335_ c:testcollection s:shard1 x:testcollection_shard1_replica_p2] o.a.s.h.ReplicationHandler Index fetch failed :org.apache.solr.common.SolrException: No registered leader was found after waiting for 4000ms , collection: testcollection slice: shard1 saw state=DocCollection(testcollection//collections/testcollection/state.json/6)={
[junit4] 2> "pullReplicas":"1",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "shards":{
[junit4] 2> "shard1":{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node5":{
[junit4] 2> "core":"testcollection_shard1_replica_p2",
[junit4] 2> "base_url":"https://127.0.0.1:39335",
[junit4] 2> "node_name":"127.0.0.1:39335_",
[junit4] 2> "state":"active",
[junit4] 2> "type":"PULL"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"testcollection_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:36847",
[junit4] 2> "node_name":"127.0.0.1:36847_",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "property.preferredleader":"true"}}},
[junit4] 2> "shard2":{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node7":{
[junit4] 2> "core":"testcollection_shard2_replica_n3",
[junit4] 2> "base_url":"https://127.0.0.1:40747",
[junit4] 2> "node_name":"127.0.0.1:40747_",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node8":{
[junit4] 2> "core":"testcollection_shard2_replica_p4",
[junit4] 2> "base_url":"https://127.0.0.1:36847",
[junit4] 2> "node_name":"127.0.0.1:36847_",
[junit4] 2> "state":"active",
[junit4] 2> "type":"PULL"}}}},
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0"} with live_nodes=[]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:771)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:755)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.getLeaderReplica(IndexFetcher.java:684)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:380)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:345)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:420)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$12(ReplicationHandler.java:1159)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1373674 INFO (Thread-2693) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44001 44001
[junit4] 2> 1373675 WARN (Thread-2693) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/aliases.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf1
[junit4] 2> 2 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/clusterstate.json
[junit4] 2> 15 /solr/clusterprops.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 3 /solr/collections/testcollection/state.json
[junit4] 2> 2 /solr/overseer_elect/election/99250008276992013-127.0.0.1:39335_-n_0000000002
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/live_nodes
[junit4] 2> 15 /solr/collections
[junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCollectionAPI -Dtests.method=test -Dtests.seed=8257B112E10D42BA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ca -Dtests.timezone=Europe/Kiev -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 51.1s J2 | TestCollectionAPI.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Property 'preferredleader' with value null not set correctly for collection/replica pair: testcollection/core_node7 property map is {core=testcollection_shard2_replica_n3, base_url=https://127.0.0.1:40747, node_name=127.0.0.1:40747_, state=active, type=NRT, leader=true}.
[junit4] > at __randomizedtesting.SeedInfo.seed([8257B112E10D42BA:A038EC84FF12F42]:0)
[junit4] > at org.apache.solr.cloud.ReplicaPropertiesBase.verifyPropertyVal(ReplicaPropertiesBase.java:95)
[junit4] > at org.apache.solr.cloud.TestCollectionAPI.replicaPropTest(TestCollectionAPI.java:428)
[junit4] > at org.apache.solr.cloud.TestCollectionAPI.test(TestCollectionAPI.java:92)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCollectionAPI_8257B112E10D42BA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{_version_=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1862, maxMBSortInHeap=6.960155467480703, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@16d12ab), locale=ca, timezone=Europe/Kiev
[junit4] 2> NOTE: Linux 4.10.0-40-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=122783192,total=337838080
[junit4] 2> NOTE: All tests run in this JVM: [TestCoreDiscovery, DistributedFacetPivotSmallAdvancedTest, TestLegacyNumericUtils, TestBlendedInfixSuggestions, TestExecutePlanAction, SolrCLIZkUtilsTest, TestValueSourceCache, TestSolrCoreParser, ExitableDirectoryReaderTest, ClusterStateTest, LegacyCloudClusterPropTest, RequiredFieldsTest, DisMaxRequestHandlerTest, TestCustomSort, TestEmbeddedSolrServerConstructors, TestTolerantUpdateProcessorCloud, OverseerTest, TestReloadAndDeleteDocs, CdcrReplicationHandlerTest, TestSolrCoreSnapshots, TestDeleteCollectionOnDownNodes, EchoParamsTest, ZkNodePropsTest, ClassificationUpdateProcessorFactoryTest, TemplateUpdateProcessorTest, NumberUtilsTest, BigEndianAscendingWordSerializerTest, StatsComponentTest, DocValuesMissingTest, TestStressUserVersions, TestQueryTypes, DeleteNodeTest, TestFieldCacheSort, AsyncCallRequestStatusResponseTest, SparseHLLTest, TestRTimerTree, TestStressInPlaceUpdates, TestExclusionRuleCollectionAccess, TestIndexSearcher, TestHttpShardHandlerFactory, CustomCollectionTest, TestUniqueKeyFieldResource, AlternateDirectoryTest, TestExtendedDismaxParser, ConnectionReuseTest, HdfsSyncSliceTest, TestHashPartitioner, TimeZoneUtilsTest, TestSolrQueryResponse, TestUseDocValuesAsStored2, TestSolrConfigHandlerCloud, TestManagedSchemaAPI, UniqFieldsUpdateProcessorFactoryTest, ReplaceNodeTest, TestManagedResourceStorage, TestExactStatsCache, TestSchemaSimilarityResource, TestConfigSetsAPIExclusivity, TestRawResponseWriter, TestRandomFlRTGCloud, TestDocTermOrds, TestGraphMLResponseWriter, TestScoreJoinQPScore, SuggestComponentTest, DistribJoinFromCollectionTest, TestQuerySenderListener, RankQueryTest, ChaosMonkeyNothingIsSafeTest, XmlUpdateRequestHandlerTest, OverseerRolesTest, TestCoreAdminApis, DirectSolrSpellCheckerTest, TestSystemIdResolver, JavabinLoaderTest, UtilsToolTest, TestFieldCollectionResource, DistanceFunctionTest, TestLockTree, TestPKIAuthenticationPlugin, TestLMJelinekMercerSimilarityFactory, TestStreamBody, TestPartialUpdateDeduplication, TestCloudRecovery, TolerantUpdateProcessorTest, SolrCoreCheckLockOnStartupTest, TestHdfsBackupRestoreCore, SimpleMLTQParserTest, HdfsChaosMonkeySafeLeaderTest, HdfsAutoAddReplicasIntegrationTest, TestMultiWordSynonyms, TestTrie, MoveReplicaHDFSFailoverTest, TestExceedMaxTermLength, WordBreakSolrSpellCheckerTest, TestJsonFacets, CloneFieldUpdateProcessorFactoryTest, TestCustomStream, CopyFieldTest, XsltUpdateRequestHandlerTest, HdfsRecoverLeaseTest, TestCSVResponseWriter, SuggesterWFSTTest, HdfsUnloadDistributedZkTest, CoreAdminHandlerTest, HttpPartitionTest, TestSolrCoreProperties, TestCollectionAPI]
[junit4] Completed [333/765 (2!)] on J2 in 51.12s, 1 test, 1 failure <<< FAILURES!
[...truncated 45279 lines...]
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-9.0.1) - Build #
21159 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21159/
Java: 64bit/jdk-9.0.1 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.io.sql.JdbcTest
Error Message:
There are still nodes recoverying - waited for 90 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 90 seconds
at __randomizedtesting.SeedInfo.seed([203BA8AEBD8F9067]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
at org.apache.solr.client.solrj.io.sql.JdbcTest.setupCluster(JdbcTest.java:78)
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:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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:844)
Build Log:
[...truncated 1783 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20171228_073113_91113123710166586291307.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J1-20171228_073113_91015848067634071034036.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 9 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J2-20171228_073113_90913770313181852636321.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 290 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/test/temp/junit4-J1-20171228_073820_0875306862029765628647.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/test/temp/junit4-J2-20171228_073820_08710858193843757770998.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/test/temp/junit4-J0-20171228_073820_0872596130321587369473.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1057 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/test/temp/junit4-J0-20171228_073931_39915378312372129214312.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/test/temp/junit4-J1-20171228_073931_39911142451521800340372.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20171228_073931_39913646978488401620664.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 228 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/test/temp/junit4-J0-20171228_074120_70311748669320410065273.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/test/temp/junit4-J1-20171228_074120_7033168751167470902099.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/icu/test/temp/junit4-J2-20171228_074120_7034555082867557021064.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 243 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/test/temp/junit4-J0-20171228_074132_5036107359871468314120.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/test/temp/junit4-J2-20171228_074132_4961295117582450630007.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 12 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/test/temp/junit4-J1-20171228_074132_49616944283318953811147.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 154 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/morfologik/test/temp/junit4-J1-20171228_074159_39113993688071781610690.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/morfologik/test/temp/junit4-J2-20171228_074159_39213225137555799680500.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/morfologik/test/temp/junit4-J0-20171228_074159_39114221750572489123388.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 164 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/opennlp/test/temp/junit4-J1-20171228_074202_6018240007075170826071.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/opennlp/test/temp/junit4-J0-20171228_074202_6013844209313433202292.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/opennlp/test/temp/junit4-J2-20171228_074202_6013485547075620159496.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 168 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/test/temp/junit4-J0-20171228_074205_4202916512906297983920.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/test/temp/junit4-J2-20171228_074205_42014272749720178699124.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/test/temp/junit4-J1-20171228_074205_42016005650034059648097.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 153 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/smartcn/test/temp/junit4-J0-20171228_074215_22117219434510570514484.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/smartcn/test/temp/junit4-J1-20171228_074215_2212295916357635601836.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 154 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/stempel/test/temp/junit4-J1-20171228_074221_5009395908967548448701.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/stempel/test/temp/junit4-J2-20171228_074221_50013027301261084385699.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/stempel/test/temp/junit4-J0-20171228_074221_50016121905859097922621.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 169 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/uima/test/temp/junit4-J0-20171228_074223_5415339919921073185569.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 153 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/test/temp/junit4-J0-20171228_074230_42913394857547085505252.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/test/temp/junit4-J1-20171228_074230_42916679056928690664614.syserr
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 22 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/test/temp/junit4-J2-20171228_074230_4298884489276098841129.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 1293 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/benchmark/test/temp/junit4-J2-20171228_074246_7689687841739578994559.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/benchmark/test/temp/junit4-J1-20171228_074246_768915491947634758946.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/benchmark/test/temp/junit4-J0-20171228_074246_76810821150702296253936.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 233 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/test/temp/junit4-J1-20171228_074254_06214160065317461335049.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/test/temp/junit4-J2-20171228_074254_06212909376899408639757.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/test/temp/junit4-J0-20171228_074254_0611169907106375950204.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 256 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/test/temp/junit4-J2-20171228_074303_33118431787059125256755.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/test/temp/junit4-J1-20171228_074303_33115710460788810356911.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/test/temp/junit4-J0-20171228_074303_3315788715671669718125.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 214 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/demo/test/temp/junit4-J1-20171228_074927_8587299081385226357279.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/demo/test/temp/junit4-J2-20171228_074927_8589168847468361247971.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/demo/test/temp/junit4-J0-20171228_074927_85815110829201107484725.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 173 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/test/temp/junit4-J2-20171228_074930_2512881324047636925951.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/test/temp/junit4-J0-20171228_074930_2517522286607248917081.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/test/temp/junit4-J1-20171228_074930_25110758090192603909584.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 220 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/facet/test/temp/junit4-J2-20171228_074936_15310957111998330959886.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/facet/test/temp/junit4-J0-20171228_074936_1534501667394892616847.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 9 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/facet/test/temp/junit4-J1-20171228_074936_1532334018600046224698.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 179 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/test/temp/junit4-J2-20171228_075015_85313012185519826209912.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/test/temp/junit4-J0-20171228_075015_8533841059074478585275.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/test/temp/junit4-J1-20171228_075015_8534798080484456308367.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 243 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/test/temp/junit4-J1-20171228_075031_5674915207979227992316.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/test/temp/junit4-J0-20171228_075031_56717735637569312985815.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/test/temp/junit4-J2-20171228_075031_56712060214313961947564.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 160 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/test/temp/junit4-J2-20171228_075049_88510390712604999699488.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/test/temp/junit4-J0-20171228_075049_8857268879333103377858.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/test/temp/junit4-J1-20171228_075049_88517828641567967524481.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 149 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/memory/test/temp/junit4-J0-20171228_075112_6477920104026982340419.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/memory/test/temp/junit4-J1-20171228_075112_6472833106232342473853.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 183 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/test/temp/junit4-J0-20171228_075123_39012678889356892719341.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/test/temp/junit4-J1-20171228_075123_3906010841307792782521.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/test/temp/junit4-J2-20171228_075123_39212058798825249655153.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 301 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/test/temp/junit4-J2-20171228_075139_52413338189016481158207.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/test/temp/junit4-J1-20171228_075139_524254677180347938672.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/test/temp/junit4-J0-20171228_075139_5241938856386927665356.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 227 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/test/temp/junit4-J0-20171228_075146_5308760455074902217338.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/test/temp/junit4-J2-20171228_075146_53016059903787154468610.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/test/temp/junit4-J1-20171228_075146_53016183625281600920265.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 207 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/replicator/test/temp/junit4-J1-20171228_075154_01012563268932162836492.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/replicator/test/temp/junit4-J2-20171228_075154_0104517017832635923660.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/replicator/test/temp/junit4-J0-20171228_075154_0107858719641013858017.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 202 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/test/temp/junit4-J2-20171228_075203_9404890431142752856793.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/test/temp/junit4-J1-20171228_075203_9408409896369877105124.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 21 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/test/temp/junit4-J0-20171228_075203_94016678108620172470086.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 317 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/test/temp/junit4-J1-20171228_075252_53315962502842844817894.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/test/temp/junit4-J2-20171228_075252_53316306275908706932327.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/test/temp/junit4-J0-20171228_075252_5335334097473359350770.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 148 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial/test/temp/junit4-J0-20171228_075307_0287240762968635579116.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 175 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial3d/test/temp/junit4-J1-20171228_075308_7446007258338087598768.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial3d/test/temp/junit4-J0-20171228_075308_7446636207785332564270.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial3d/test/temp/junit4-J2-20171228_075308_7448513832362710981234.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 241 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/test/temp/junit4-J0-20171228_075405_0781268282389880491229.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/test/temp/junit4-J2-20171228_075405_07817988980972178616131.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 15 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/test/temp/junit4-J1-20171228_075405_0789255349548958418346.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 4172 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J0-20171228_075500_68912673295696926170778.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J1-20171228_075500_6986077048651505949444.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J2-20171228_075500_69916250345600301329950.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 885 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J0-20171228_083954_1874078704773951857450.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 4 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.sql.JdbcTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/init-core-data-001
[junit4] 2> 78410 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 78411 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-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> 78411 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 78411 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001
[junit4] 2> 78411 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 78415 INFO (Thread-224) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 78415 INFO (Thread-224) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 78418 ERROR (Thread-224) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 78515 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34243
[junit4] 2> 78516 INFO (zkConnectionManagerCallback-234-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78518 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 78518 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 78518 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c087a4e{/solr,null,AVAILABLE}
[junit4] 2> 78519 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5f6158d{/solr,null,AVAILABLE}
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1400efd{HTTP/1.1,[http/1.1]}{127.0.0.1:37953}
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a7a6c17{HTTP/1.1,[http/1.1]}{127.0.0.1:36187}
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.Server Started @80701ms
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.Server Started @80701ms
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37953}
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36187}
[junit4] 2> 78520 ERROR (jetty-launcher-231-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 78520 ERROR (jetty-launcher-231-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T08:41:14.918469Z
[junit4] 2> 78520 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-28T08:41:14.918481Z
[junit4] 2> 78521 INFO (zkConnectionManagerCallback-238-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78521 INFO (zkConnectionManagerCallback-237-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78522 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 78522 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 78534 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34243/solr
[junit4] 2> 78535 INFO (zkConnectionManagerCallback-242-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78537 INFO (zkConnectionManagerCallback-244-thread-1-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78537 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34243/solr
[junit4] 2> 78543 INFO (zkConnectionManagerCallback-248-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78545 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1609c48573e0005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 78546 INFO (zkConnectionManagerCallback-251-thread-1-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78549 WARN (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 78550 WARN (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 78551 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 78551 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36187_solr
[junit4] 2> 78551 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.c.Overseer Overseer (id=99251026317410308-127.0.0.1:36187_solr-n_0000000000) starting
[junit4] 2> 78551 INFO (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 78552 INFO (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37953_solr
[junit4] 2> 78565 INFO (zkCallback-243-thread-1-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 78566 INFO (zkCallback-250-thread-1-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 78568 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36187_solr
[junit4] 2> 78568 INFO (zkCallback-250-thread-1-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 78570 INFO (zkCallback-243-thread-1-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 78596 INFO (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37953.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 78601 INFO (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37953.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 78601 INFO (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37953.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 78601 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36187.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 78601 INFO (jetty-launcher-231-thread-2) [n:127.0.0.1:37953_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001/node2/.
[junit4] 2> 78608 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36187.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 78608 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36187.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 78609 INFO (jetty-launcher-231-thread-1) [n:127.0.0.1:36187_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001/node1/.
[junit4] 2> 78630 INFO (zkConnectionManagerCallback-256-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78632 INFO (zkConnectionManagerCallback-260-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 78632 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 78633 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34243/solr ready
[junit4] 2> 78635 INFO (qtp1911601658-1153) [n:127.0.0.1:37953_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 78636 INFO (OverseerThreadFactory-573-thread-1-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 78740 INFO (OverseerStateUpdate-99251026317410308-127.0.0.1:36187_solr-n_0000000000) [n:127.0.0.1:36187_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36187/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 78742 INFO (OverseerStateUpdate-99251026317410308-127.0.0.1:36187_solr-n_0000000000) [n:127.0.0.1:36187_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37953/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 78951 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 78951 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 78952 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 78952 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 79056 INFO (zkCallback-250-thread-1-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 79056 INFO (zkCallback-243-thread-1-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 79057 INFO (zkCallback-250-thread-2-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 79057 INFO (zkCallback-243-thread-2-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 79960 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 79960 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 79971 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 79971 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 79998 WARN (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 79998 WARN (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 79999 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 79999 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 79999 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37953.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 79999 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 79999 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001/node2/collection1_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001/node2/./collection1_shard2_replica_n2/data/]
[junit4] 2> 80000 WARN (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 80000 WARN (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 80001 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 80001 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 80001 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36187.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@35f84c46
[junit4] 2> 80001 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 80001 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.sql.JdbcTest_203BA8AEBD8F9067-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/]
[junit4] 2> 80032 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 80032 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 80033 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 80033 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 80034 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 80034 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 80040 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11788ddd[collection1_shard1_replica_n1] main]
[junit4] 2> 80041 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 80041 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 80042 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f8ea815[collection1_shard2_replica_n2] main]
[junit4] 2> 80042 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 80042 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 80043 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 80043 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1588016422784598016
[junit4] 2> 80044 INFO (searcherExecutor-579-thread-1-processing-n:127.0.0.1:36187_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@11788ddd[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 80045 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 80045 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 80046 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 80046 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 80046 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 80046 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36187/solr/collection1_shard1_replica_n1/
[junit4] 2> 80046 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 80046 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:36187/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 80046 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 80047 INFO (searcherExecutor-578-thread-1-processing-n:127.0.0.1:37953_solr x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@3f8ea815[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 80047 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1588016422788792320
[junit4] 2> 80054 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36187/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 80056 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 80056 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 80056 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37953/solr/collection1_shard2_replica_n2/
[junit4] 2> 80056 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 80056 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:37953/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 80056 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 80058 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37953/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 80159 INFO (zkCallback-243-thread-1-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 80159 INFO (zkCallback-250-thread-1-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 80159 INFO (zkCallback-250-thread-2-processing-n:127.0.0.1:37953_solr) [n:127.0.0.1:37953_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 80159 INFO (zkCallback-243-thread-2-processing-n:127.0.0.1:36187_solr) [n:127.0.0.1:36187_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 80205 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 80209 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 80214 INFO (qtp1146560634-1152) [n:127.0.0.1:36187_solr c:collection1 s:shard1 r:core_node3 x:collection1_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=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1262
[junit4] 2> 80214 INFO (qtp1911601658-1158) [n:127.0.0.1:37953_solr c:collection1 s:shard2 r:core_node4 x:collection1_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=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1262
[junit4] 2> 80217 INFO (qtp1911601658-1153) [n:127.0.0.1:37953_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 80637 INFO (OverseerCollectionConfigSetProcessor-99251026317410308-127.0.0.1:36187_solr-n_0000000000) [n:127.0.0.1:36187_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> 110220 ERROR (qtp1911601658-1153) [n:127.0.0.1:37953_solr ] o.a.s.h.a.CollectionsHandler Timed out waiting for new collection's replicas to become ACTIVE replica http://127.0.0.1:36187/solr/collection1_shard1_replica_n1/ is in state of down with timeout=30
[junit4] 2> 110220 INFO (qtp1911601658-1153) [n:127.0.0.1:37953_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=31584
[junit4] 2> 110220 INFO (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):90
[junit4] 2> 200365 ERROR (SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker) [ ] o.a.s.c.Diagnostics Gave up waiting for recovery to finish. THREAD DUMP:
[junit4] 2> "ScheduledTrigger-571-thread-4" prio=5 Id=1218 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1219236e
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1219236e
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1126)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> ...
[junit4] 2>
[junit4] 2> "searcherExecutor-578-thread-1" prio=5 Id=1216 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@65050a4a
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@65050a4a
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> "searcherExecutor-579-thread-1" prio=5 Id=1215 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@28b009cc
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@28b009cc
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> "ScheduledTrigger-571-thread-3" prio=5 Id=1212 TIMED_WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1219236e
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@1219236e
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2104)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> ...
[junit4] 2>
[junit4] 2> "Scheduler-1251001984" prio=5 Id=1207 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@70a3f6d2
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@70a3f6d2
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1119)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> ...
[junit4] 2>
[junit4] 2> "OverseerThreadFactory-573-thread-1" prio=5 Id=1203 WAITING on java.util.concurrent.SynchronousQueue$TransferStack@2b7aa9fb
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.SynchronousQueue$TransferStack@2b7aa9fb
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:361)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.SynchronousQueue.take(SynchronousQueue.java:920)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> ...
[junit4] 2>
[junit4] 2> "Scheduler-2134333876" prio=5 Id=1202 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@66e0b184
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@66e0b184
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1119)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> ...
[junit4] 2>
[junit4] 2> "zkConnectionManagerCallback-260-thread-1" prio=5 Id=1201 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@4e362afd
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@4e362afd
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base@9.0.1/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> "SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker-EventThread" daemon prio=5 Id=1200 WAITING on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@214c508f
[junit4] 2> at java.base@9.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> - waiting on java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject@214c508f
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at java.base@9.0.1/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at app//org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501)
[junit4] 2>
[junit4] 2> "SUITE-JdbcTest-seed#[203BA8AEBD8F9067]-worker-SendThread(127.0.0.1:34243)" daemon prio=5 Id=1199 RUNNABLE (in native)
[junit4] 2> at java.base@9.0.1/sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] 2> at java.base@9.0.1/sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:265)
[junit4] 2> at java.base@9.0.1/sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:92)
[junit4] 2> at java.base@9.0.1/sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] 2> - locked sun.nio.ch.Util$2@5f07102c
[junit4] 2> - locked java.util.Collections$UnmodifiableSet@3c08e6d9
[junit4] 2> - locked sun.nio.ch.EPollSelectorImpl@43964267
[junit4] 2> at java.base@9.0.1/sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] 2> at app//org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSock
[...truncated too long message...]
ce/Lucene-Solr-master-Linux/solr/build/contrib/solr-analytics/test/temp/junit4-J2-20171228_084337_80111035741696547251078.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-analytics/test/temp/junit4-J1-20171228_084337_80116353139268963181338.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 553 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-clustering/test/temp/junit4-J2-20171228_084437_74210318536456928443990.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-clustering/test/temp/junit4-J1-20171228_084437_74210437767426297406327.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-clustering/test/temp/junit4-J0-20171228_084437_7426279209412950321731.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1259 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler-extras/test/temp/junit4-J1-20171228_084453_0389558684991811725557.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 4 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler-extras/test/temp/junit4-J0-20171228_084453_0371507406678059572565.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 644 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler/test/temp/junit4-J0-20171228_084500_15214487315296385078070.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 6 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler/test/temp/junit4-J1-20171228_084500_1529695825818425224149.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-dataimporthandler/test/temp/junit4-J2-20171228_084500_15714041885102451856825.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 558 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-cell/test/temp/junit4-J1-20171228_084524_80912658879244029508889.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-cell/test/temp/junit4-J2-20171228_084524_8094645820283777938765.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-cell/test/temp/junit4-J0-20171228_084524_80914088913433286838901.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 563 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-langid/test/temp/junit4-J0-20171228_084535_48112322791597953311016.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-langid/test/temp/junit4-J1-20171228_084535_48110132217256262037539.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 652 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/temp/junit4-J0-20171228_084541_74611028273592011333315.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/temp/junit4-J1-20171228_084541_74613600999707501467456.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-ltr/test/temp/junit4-J2-20171228_084541_7466304374795085226425.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J2: EOF ----
[...truncated 596 lines...]
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-uima/test/temp/junit4-J1-20171228_084606_58211577014996508689746.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-uima/test/temp/junit4-J0-20171228_084606_5826943519912871571402.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 548 lines...]
[junit4] JVM J0: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/contrib/solr-velocity/test/temp/junit4-J0-20171228_084616_9626940333737460991426.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 32213 lines...]