Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6942/ Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest.testSimple Error Message: No live SolrServers available to handle this request:[https://127.0.0.1:65357/solr] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:65357/solr] at __randomizedtesting.SeedInfo.seed([D752E36C4B9CF3E0:EFE1C7926C6F2731]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:462) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest.testSimple(AutoAddReplicasPlanActionTest.java:108) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:65357/solr at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:640) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) ... 44 more Caused by: java.net.SocketException: Software caused connection abort: recv failed at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:171) at java.net.SocketInputStream.read(SocketInputStream.java:141) at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) at sun.security.ssl.InputRecord.read(InputRecord.java:503) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:983) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:940) at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:525) ... 48 more FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling Error Message: Both triggers should have fired by now Stack Trace: java.lang.AssertionError: Both triggers should have fired by now at __randomizedtesting.SeedInfo.seed([D752E36C4B9CF3E0:2C704B4999361072]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:194) 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 11503 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> 40868 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\init-core-data-001 [junit4] 2> 40870 WARN (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 40870 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 40873 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 40876 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\tempDir-001 [junit4] 2> 40876 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 40876 INFO (Thread-167) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 40876 INFO (Thread-167) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 40879 ERROR (Thread-167) [ ] 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> 40976 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:59403 [junit4] 2> 40986 INFO (jetty-launcher-51-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 40986 INFO (jetty-launcher-51-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 40987 INFO (jetty-launcher-51-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3d655fc0{/solr,null,AVAILABLE} [junit4] 2> 40987 INFO (jetty-launcher-51-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@72e6efc7{/solr,null,AVAILABLE} [junit4] 2> 40991 INFO (jetty-launcher-51-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f215593{HTTP/1.1,[http/1.1]}{127.0.0.1:59407} [junit4] 2> 40991 INFO (jetty-launcher-51-thread-2) [ ] o.e.j.s.Server Started @48700ms [junit4] 2> 40991 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59407} [junit4] 2> 40997 ERROR (jetty-launcher-51-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 40997 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 40997 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 40997 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 40997 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-05T11:29:35.396Z [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8307f19{HTTP/1.1,[http/1.1]}{127.0.0.1:59410} [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.e.j.s.Server Started @48708ms [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59410} [junit4] 2> 40999 ERROR (jetty-launcher-51-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 40999 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-05T11:29:35.398Z [junit4] 2> 41006 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 41006 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 41021 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 0x15eec4c462e0002, 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> 41026 INFO (jetty-launcher-51-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59403/solr [junit4] 2> 41026 INFO (jetty-launcher-51-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59403/solr [junit4] 2> 41031 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 0x15eec4c462e0003, 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> 41032 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 0x15eec4c462e0004, 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> 41146 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 41148 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59407_solr [junit4] 2> 41148 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.Overseer Overseer (id=98776054187819014-127.0.0.1:59407_solr-n_0000000000) starting [junit4] 2> 41156 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59407_solr [junit4] 2> 41158 INFO (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 41160 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 41162 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 41164 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 41166 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:59407_solr] [junit4] 2> 41166 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 41166 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 41169 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 41170 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 41171 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 41175 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59410_solr [junit4] 2> 41241 INFO (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 41241 INFO (zkCallback-62-thread-1-processing-n:127.0.0.1:59410_solr) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 41251 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 41295 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59407.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41302 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59407.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41302 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59407.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41303 INFO (jetty-launcher-51-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\tempDir-001\node2\. [junit4] 2> 41304 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59410.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41309 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59410.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41309 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59410.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41312 INFO (jetty-launcher-51-thread-1) [n:127.0.0.1:59410_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\tempDir-001\node1\. [junit4] 2> 41342 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 0x15eec4c462e0007, 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> 41350 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 41351 INFO (SUITE-TriggerIntegrationTest-seed#[D752E36C4B9CF3E0]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 41367 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling [junit4] 2> 41367 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2 [junit4] 2> 41369 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 41369 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 41372 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 41373 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 41373 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 41409 INFO (qtp86356961-564) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 41410 INFO (qtp86356961-564) [n:127.0.0.1:59407_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 41433 INFO (qtp86356961-559) [n:127.0.0.1:59407_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=8 [junit4] 2> 41436 INFO (qtp1929173830-571) [n:127.0.0.1:59410_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> 41438 DEBUG (qtp86356961-564) [n:127.0.0.1:59407_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 41445 INFO (qtp86356961-564) [n:127.0.0.1:59407_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=52 [junit4] 2> 41446 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 41453 INFO (qtp1929173830-570) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 41454 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 41454 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 41454 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 41454 INFO (qtp1929173830-570) [n:127.0.0.1:59410_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 41456 INFO (qtp86356961-561) [n:127.0.0.1:59407_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> 41456 INFO (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 41457 INFO (qtp1929173830-569) [n:127.0.0.1:59410_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> 41457 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 41457 DEBUG (qtp1929173830-570) [n:127.0.0.1:59410_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 41457 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 41458 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 41458 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 41459 INFO (qtp1929173830-570) [n:127.0.0.1:59410_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=11 [junit4] 2> 41460 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 41460 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 41460 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 41461 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 41461 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 41461 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 41463 INFO (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 41464 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 41464 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@26026aec{/solr,null,AVAILABLE} [junit4] 2> 41466 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 41466 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@692b72f5{HTTP/1.1,[http/1.1]}{127.0.0.1:59449} [junit4] 2> 41466 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.Server Started @49175ms [junit4] 2> 41466 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59449} [junit4] 2> 41466 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 41467 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 41467 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 41467 ERROR (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 41468 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 41469 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 41469 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 41469 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-05T11:29:35.868Z [junit4] 2> 41473 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 41484 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59403/solr [junit4] 2> 41489 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 0x15eec4c462e000c, 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> 41499 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 41502 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 41504 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59449_solr [junit4] 2> 41507 INFO (zkCallback-62-thread-1-processing-n:127.0.0.1:59410_solr) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 41507 INFO (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 41508 INFO (zkCallback-69-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 41508 INFO (zkCallback-80-thread-1-processing-n:127.0.0.1:59449_solr) [n:127.0.0.1:59449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 41590 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59449.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41596 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59449.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41596 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59449.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 41597 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59449_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\tempDir-001\node3\. [junit4] 2> 42460 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 42460 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 42460 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:59449_solr at time 1507202976859000000 [junit4] 2> 42460 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:59449_solr] added at times [1507202976859000000] [junit4] 2> 42471 INFO (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 42473 INFO (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 42474 INFO (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 42475 DEBUG (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for NodeAddedEvent{id='14eaa91f38178cc0T7l036fph02o9yi70k2hguzlqr', source='node_added_trigger1', eventTime=1507202976859000000, properties={eventTimes=[1507202976859000000], _enqueue_time_=1507202976861000000, nodeNames=[127.0.0.1:59449_solr]}} [junit4] 2> 42480 INFO (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger1 [junit4] 2> 43471 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 43471 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 43471 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:59449_solr at time 1507202977870000000 [junit4] 2> 43471 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing registered processor for nodes: [127.0.0.1:59449_solr] added at times [1507202977870000000] [junit4] 2> 43484 INFO (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 43487 INFO (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 43487 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 0x15eec4c462e000f, 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> 43488 INFO (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 43488 DEBUG (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for NodeAddedEvent{id='14eaa91f745a2f80T7l036fph02o9yi70k2hguzlqt', source='node_added_trigger2', eventTime=1507202977870000000, properties={eventTimes=[1507202977870000000], _enqueue_time_=1507202977870000000, nodeNames=[127.0.0.1:59449_solr]}} [junit4] 2> 43488 INFO (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.ActionThrottle The last action attempt started 1012ms ago. [junit4] 2> 43488 INFO (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.ActionThrottle Throttling action attempts - waiting for 3987ms [junit4] 2> 47475 INFO (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest last action at 1507202976879000000 time = 1507202981874000000 [junit4] 2> 47475 INFO (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest action executed again before minimum wait time from node_added_trigger2 [junit4] 2> 47487 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 47487 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 47492 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 47492 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 48488 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 48488 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 48493 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 48493 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 49489 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 49489 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 49494 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 49494 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 50490 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 50490 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 50494 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 50494 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 51490 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 51490 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 51495 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 51495 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 52492 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 52492 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 52495 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 52495 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 53493 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 53493 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 53496 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 53496 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 54493 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 54493 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 54497 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 54497 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 55495 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 55495 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 55498 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 55498 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 56496 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 56496 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 56498 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 56498 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 57497 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 57497 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 57499 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 57499 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 58498 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 58498 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 58500 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 58500 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 59499 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 59499 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 59506 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 59506 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 60501 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 60501 DEBUG (ScheduledTrigger-261-thread-3) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 60506 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 60506 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 61503 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 61503 DEBUG (ScheduledTrigger-261-thread-2) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 61507 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 61507 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 61629 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerThrottling [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TriggerIntegrationTest -Dtests.method=testTriggerThrottling -Dtests.seed=D752E36C4B9CF3E0 -Dtests.slow=true -Dtests.locale=de-AT -Dtests.timezone=America/Indiana/Petersburg -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 20.3s J1 | TriggerIntegrationTest.testTriggerThrottling <<< [junit4] > Throwable #1: java.lang.AssertionError: Both triggers should have fired by now [junit4] > at __randomizedtesting.SeedInfo.seed([D752E36C4B9CF3E0:2C704B4999361072]:0) [junit4] > at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:194) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> 61643 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger [junit4] 2> 61643 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5 [junit4] 2> 61644 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 61644 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 61650 WARN (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.ScheduledTriggers Failed to remove state for removed trigger /autoscaling/triggerState/node_added_trigger1 [junit4] 2> org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:111) [junit4] 2> at org.apache.zookeeper.ZooKeeper.multiInternal(ZooKeeper.java:1006) [junit4] 2> at org.apache.zookeeper.ZooKeeper.multi(ZooKeeper.java:910) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$11.execute(SolrZkClient.java:593) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$11.execute(SolrZkClient.java:590) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.multi(SolrZkClient.java:590) [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.zkDelTree(ScheduledTriggers.java:362) [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.removeTriggerZKData(ScheduledTriggers.java:347) [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.remove(ScheduledTriggers.java:340) [junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:204) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 61651 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 61651 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 61651 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 61657 INFO (qtp86356961-560) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 61659 INFO (qtp86356961-560) [n:127.0.0.1:59407_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 61661 INFO (qtp86356961-580) [n:127.0.0.1:59407_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> 61663 INFO (qtp1929173830-569) [n:127.0.0.1:59410_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> 61666 INFO (qtp873946405-638) [n:127.0.0.1:59449_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> 61666 DEBUG (qtp86356961-560) [n:127.0.0.1:59407_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 61667 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 0x15eec4c462e0010, 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> 61671 INFO (qtp86356961-560) [n:127.0.0.1:59407_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=19 [junit4] 2> 61672 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 61673 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:59449_solr, 127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 61673 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 61673 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 61675 INFO (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 61677 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 61677 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75fddfc7{/solr,null,AVAILABLE} [junit4] 2> 61677 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@42923e1f{HTTP/1.1,[http/1.1]}{127.0.0.1:59562} [junit4] 2> 61677 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.Server Started @69387ms [junit4] 2> 61677 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59562} [junit4] 2> 61678 ERROR (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 61678 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 61678 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 61678 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 61678 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-05T11:29:56.077Z [junit4] 2> 61684 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 61685 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 61685 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 61685 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr] [junit4] 2> 61694 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 61702 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 0x15eec4c462e0011, 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> 61707 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59403/solr [junit4] 2> 61714 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 0x15eec4c462e0012, 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> 61728 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 61731 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 61736 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59562_solr [junit4] 2> 61764 INFO (zkCallback-62-thread-1-processing-n:127.0.0.1:59410_solr) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 61764 INFO (zkCallback-97-thread-1-processing-n:127.0.0.1:59562_solr) [n:127.0.0.1:59562_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 61765 INFO (zkCallback-80-thread-1-processing-n:127.0.0.1:59449_solr) [n:127.0.0.1:59449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 61765 INFO (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 61765 INFO (zkCallback-69-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 61871 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59562.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 61876 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59562.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 61877 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59562.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 61879 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59562_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\tempDir-001\node4\. [junit4] 2> 62687 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 62687 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 62687 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:59562_solr at time 1507202997086000000 [junit4] 2> 63690 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 63690 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 64691 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 64691 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 65691 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 65691 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 65691 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:59562_solr] added at times [1507202997086000000] [junit4] 2> 65703 INFO (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 65707 INFO (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 65709 INFO (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 65709 DEBUG (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for NodeAddedEvent{id='14eaa923edb71380T7l036fph02o9yi70k2hguzlqv', source='node_added_trigger', eventTime=1507202997086000000, properties={eventTimes=[1507202997086000000], _enqueue_time_=1507203000090000000, nodeNames=[127.0.0.1:59562_solr]}} [junit4] 2> 65709 INFO (AutoscalingActionExecutor-262-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.ActionThrottle The last action attempt started 18234ms ago. [junit4] 2> 65725 INFO (qtp1929173830-569) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 65729 INFO (qtp1929173830-569) [n:127.0.0.1:59410_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 65735 INFO (qtp1079633600-678) [n:127.0.0.1:59562_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> 65738 INFO (qtp86356961-580) [n:127.0.0.1:59407_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> 65741 INFO (qtp1929173830-577) [n:127.0.0.1:59410_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> 65747 INFO (qtp873946405-634) [n:127.0.0.1:59449_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> 65747 DEBUG (qtp1929173830-569) [n:127.0.0.1:59410_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 65749 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 0x15eec4c462e0015, 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> 65749 INFO (qtp1929173830-569) [n:127.0.0.1:59410_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=35 [junit4] 2> 65750 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 65751 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:59449_solr, 127.0.0.1:59562_solr, 127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 65751 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 65751 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 65753 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 65753 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 66713 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 66713 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 67714 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 67714 DEBUG (ScheduledTrigger-261-thread-4) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 67753 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTrigger [junit4] 2> 67783 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEventFromRestoredState [junit4] 2> 67783 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 8 [junit4] 2> 67784 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 67784 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 67786 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 67786 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 67786 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 67792 INFO (qtp873946405-631) [n:127.0.0.1:59449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 67793 INFO (qtp873946405-631) [n:127.0.0.1:59449_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59403/solr ready [junit4] 2> 67795 INFO (qtp1079633600-670) [n:127.0.0.1:59562_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> 67796 INFO (qtp86356961-566) [n:127.0.0.1:59407_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> 67798 INFO (qtp1929173830-582) [n:127.0.0.1:59410_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> 67799 INFO (qtp873946405-640) [n:127.0.0.1:59449_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> 67800 DEBUG (qtp873946405-631) [n:127.0.0.1:59449_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 67801 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 0x15eec4c462e0016, 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> 67801 INFO (qtp873946405-631) [n:127.0.0.1:59449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=14 [junit4] 2> 67802 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 9 [junit4] 2> 67803 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:59449_solr, 127.0.0.1:59562_solr, 127.0.0.1:59407_solr, 127.0.0.1:59410_solr] [junit4] 2> 67803 DEBUG (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=10, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true} [junit4] 2> 67804 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 9 [junit4] 2> 67806 INFO (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 67806 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 67806 DEBUG (OverseerAutoScalingTriggerThread-98776054187819014-127.0.0.1:59407_solr-n_0000000000) [n:127.0.0.1:59407_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVersion 9 [junit4] 2> 67806 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 67806 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr] [junit4] 2> 67808 INFO (qtp86356961-566) [n:127.0.0.1:59407_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 67820 INFO (qtp86356961-566) [n:127.0.0.1:59407_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=12 [junit4] 2> 67822 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 67822 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f783068{/solr,null,AVAILABLE} [junit4] 2> 67823 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3a469673{HTTP/1.1,[http/1.1]}{127.0.0.1:59596} [junit4] 2> 67824 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.e.j.s.Server Started @75533ms [junit4] 2> 67824 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59596} [junit4] 2> 67824 ERROR (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 67824 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 67824 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 67825 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 67825 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-05T11:30:02.224Z [junit4] 2> 67829 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 67837 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 0x15eec4c462e0017, 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> 67842 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59403/solr [junit4] 2> 67847 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 0x15eec4c462e0018, 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> 67856 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 67858 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 67860 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59596_solr [junit4] 2> 67863 INFO (zkCallback-62-thread-1-processing-n:127.0.0.1:59410_solr) [n:127.0.0.1:59410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 67863 INFO (zkCallback-97-thread-1-processing-n:127.0.0.1:59562_solr) [n:127.0.0.1:59562_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 67864 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:59596_solr) [n:127.0.0.1:59596_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 67865 INFO (zkCallback-63-thread-1-processing-n:127.0.0.1:59407_solr) [n:127.0.0.1:59407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 67865 INFO (zkCallback-80-thread-1-processing-n:127.0.0.1:59449_solr) [n:127.0.0.1:59449_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 67865 INFO (zkCallback-69-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 67938 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59596.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 67943 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59596.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 67943 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59596.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541e78f9 [junit4] 2> 67945 INFO (TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[D752E36C4B9CF3E0]) [n:127.0.0.1:59596_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_D752E36C4B9CF3E0-001\tempDir-001\node5\. [junit4] 2> 68808 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 68808 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr, 127.0.0.1:59596_solr] [junit4] 2> 68808 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:59596_solr at time 1507203003207000000 [junit4] 2> 69811 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 69811 DEBUG (ScheduledTrigger-261-thread-1) [n:127.0.0.1:59407_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:59407_solr, 127.0.0.1:59410_solr, 127.0.0.1:59449_solr, 127.0.0.1:59562_solr, 127.0.0.1:59596_solr] [junit4] 2> 69815 INFO ( [...truncated too long message...] current create/delete watches above limit: [junit4] 2> [junit4] 2> 7 /solr/aliases.json [junit4] 2> 4 /solr/security.json [junit4] 2> 4 /solr/configs/conf [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 21 /solr/collections/testSimple2/state.json [junit4] 2> 19 /solr/collections/testSimple1/state.json [junit4] 2> 7 /solr/clusterprops.json [junit4] 2> 7 /solr/clusterstate.json [junit4] 2> 5 /solr/collections/testSimple3/state.json [junit4] 2> 3 /solr/overseer_elect/election/98776236384845833-127.0.0.1:65358_solr-n_0000000000 [junit4] 2> 2 /solr/collections/testSimple1/leader_elect/shard1/election/98776236384845833-core_node5-n_0000000000 [junit4] 2> 2 /solr/collections/testSimple2/leader_elect/shard1/election/98776236384845831-core_node3-n_0000000000 [junit4] 2> 2 /solr/collections/testSimple1/leader_elect/shard2/election/98776236384845833-core_node8-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 7 /solr/collections [junit4] 2> 6 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_D752E36C4B9CF3E0-001 [junit4] 2> Oct 05, 2017 12:16:08 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 4 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=252, maxMBSortInHeap=6.092856918176227, sim=RandomSimilarity(queryNorm=true): {}, locale=ga, timezone=US/Alaska [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=125891936,total=519569408 [junit4] 2> NOTE: All tests run in this JVM: [TestSQLHandlerNonCloud, TestPhraseSuggestions, TestReplicationHandler, RequiredFieldsTest, ShardSplitTest, TestQueryWrapperFilter, TestStressRecovery, TestFieldCollectionResource, V2ApiIntegrationTest, InfixSuggestersTest, DisMaxRequestHandlerTest, TlogReplayBufferedWhileIndexingTest, TestNamedUpdateProcessors, TestRandomFlRTGCloud, TestFieldCache, TestBinaryField, IndexSchemaTest, TestSha256AuthenticationProvider, TestReversedWildcardFilterFactory, TestFaceting, NumericFieldsTest, TestLeaderElectionWithEmptyReplica, SubstringBytesRefFilterTest, OverseerTest, DistributedQueueTest, LeaderFailureAfterFreshStartTest, CdcrRequestHandlerTest, TestTrieFacet, HighlighterConfigTest, TestPointFields, ResponseHeaderTest, CollectionStateFormat2Test, BooleanFieldTest, TestImpersonationWithHadoopAuth, TestLegacyField, TestStressCloudBlindAtomicUpdates, MultiTermTest, OutputWriterTest, TestCloudInspectUtil, DateFieldTest, TestMissingGroups, AtomicUpdateProcessorFactoryTest, AssignTest, ReturnFieldsTest, RequestHandlersTest, TestPolicyCloud, LeaderElectionTest, DefaultValueUpdateProcessorTest, ImplicitSnitchTest, TestImplicitCoreProperties, DistributedSuggestComponentTest, SpellCheckCollatorWithCollapseTest, RulesTest, TestConfigReload, CdcrUpdateLogTest, DistributedFacetExistsSmallTest, HdfsCollectionsAPIDistributedZkTest, HighlighterMaxOffsetTest, TestHdfsUpdateLog, RAMDirectoryFactoryTest, TestCloudRecovery, UUIDUpdateProcessorFallbackTest, DeleteLastCustomShardedReplicaTest, TestIBSimilarityFactory, DeleteStatusTest, DeleteReplicaTest, TestMacroExpander, MinimalSchemaTest, TestAuthenticationFramework, SolrTestCaseJ4Test, AnalysisAfterCoreReloadTest, TestDocBasedVersionConstraints, DocExpirationUpdateProcessorFactoryTest, TestCustomStream, SuggesterTest, HdfsThreadLeakTest, ExplicitHLLTest, CustomCollectionTest, TestRandomDVFaceting, ConfigureRecoveryStrategyTest, TestFoldingMultitermQuery, TestLMDirichletSimilarityFactory, PKIAuthenticationIntegrationTest, TestRequestForwarding, ZkCLITest, OverseerCollectionConfigSetProcessorTest, NotRequiredUniqueKeyTest, DocValuesNotIndexedTest, SearchHandlerTest, TestSolrConfigHandlerConcurrent, ParsingFieldUpdateProcessorsTest, TestEmbeddedSolrServerSchemaAPI, TestShortCircuitedRequests, PathHierarchyTokenizerFactoryTest, AlternateDirectoryTest, BigEndianAscendingWordSerializerTest, TestSolrCoreSnapshots, TestBulkSchemaAPI, TestUtils, URLClassifyProcessorTest, ConcurrentDeleteAndCreateCollectionTest, HdfsLockFactoryTest, TestClusterProperties, TestMaxTokenLenTokenizer, HttpTriggerListenerTest, TestExclusionRuleCollectionAccess, TestExactStatsCache, TestAtomicUpdateErrorCases, TestZkAclsWithHadoopAuth, TestReloadAndDeleteDocs, AnalyticsQueryTest, TestPayloadCheckQParserPlugin, MoveReplicaHDFSTest, XmlUpdateRequestHandlerTest, TestTolerantUpdateProcessorCloud, SimpleFacetsTest, TestSolrXml, SolrCloudExampleTest, TestScoreJoinQPScore, SimplePostToolTest, TestSurroundQueryParser, TestStressLiveNodes, TestManagedSchemaAPI, TestSolrCoreProperties, CollectionsAPIAsyncDistributedZkTest, DirectoryFactoryTest, SolrIndexSplitterTest, ExecutePlanActionTest, LeaderElectionContextKeyTest, TestRecoveryHdfs, MultiThreadedOCPTest, OverseerTaskQueueTest, TestOmitPositions, TestSolrDeletionPolicy2, AliasIntegrationTest, PrimitiveFieldTypeTest, WrapperMergePolicyFactoryTest, SuggesterWFSTTest, TestRawTransformer, TestGraphMLResponseWriter, TestFilteredDocIdSet, QueryParsingTest, ClusterStateUpdateTest, HdfsUnloadDistributedZkTest, LeaderInitiatedRecoveryOnShardRestartTest, TestSchemalessBufferedUpdates, RestartWhileUpdatingTest, TestPushWriter, TestDynamicFieldCollectionResource, BJQParserTest, TestComplexPhraseLeadingWildcard, TestConfigsApi, TestNonDefinedSimilarityFactory, HighlighterTest, TestHighlightDedupGrouping, CdcrReplicationDistributedZkTest, TestNestedDocsSort, CursorPagingTest, DistanceFunctionTest, TestHashQParserPlugin, ClassificationUpdateProcessorFactoryTest, TestFuzzyAnalyzedSuggestions, TestNoOpRegenerator, TestCollectionsAPIViaSolrCloudCluster, TimeZoneUtilsTest, TestSubQueryTransformerDistrib, TestJsonFacets, TestCSVResponseWriter, ClassificationUpdateProcessorTest, TestConfigSetImmutable, XsltUpdateRequestHandlerTest, NodeLostTriggerTest, TestConfigSetsAPIExclusivity, QueryElevationComponentTest, TestCoreContainer, WordBreakSolrSpellCheckerTest, TestBinaryResponseWriter, TestPullReplica, TestSubQueryTransformer, BasicDistributedZkTest, UnloadDistributedZkTest, SyncSliceTest, ShardRoutingTest, TestRandomFaceting, ZkSolrClientTest, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestReload, TestStressVersions, DistributedTermsComponentTest, TestSolr4Spatial, StatsComponentTest, SolrCmdDistributorTest, BadIndexSchemaTest, TestSort, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, TestExtendedDismaxParser, CoreAdminHandlerTest, DocValuesTest, SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, DocValuesMultiTest, SolrInfoBeanTest, LukeRequestHandlerTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, LoggingHandlerTest, SolrPluginUtilsTest, TestCollationField, UpdateRequestProcessorFactoryTest, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, SOLR749Test, TestQuerySenderListener, TestSolrIndexConfig, TestQuerySenderNoQuery, ResponseLogComponentTest, BadComponentTest, TestMergePolicyConfig, SampleTest, TestElisionMultitermQuery, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, TestCodecSupport, TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, DateMathParserTest, PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, TestLRUCache, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, CircularListTest, DistributedMLTComponentTest, TestCursorMarkWithoutUniqueKey, TestTolerantSearch, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, ConnectionReuseTest, BaseCdcrDistributedZkTest, CdcrBootstrapTest, CdcrReplicationHandlerTest, CdcrVersionReplicationTest, CloudExitableDirectoryReaderTest, ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, DeleteNodeTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, HealthCheckHandlerTest, LeaderFailoverAfterPartitionTest, MigrateRouteKeyTest, MissingSegmentRecoveryTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerModifyCollectionTest, OverseerRolesTest, PeerSyncReplicationTest, TestCloudPivotFacet, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestHdfsCloudBackupRestore, TestLeaderElectionZkExpiry, TestLockTree, TestOnReconnectListenerSupport, TestRebalanceLeaders, TestRequestStatusCollectionAPI, TestSkipOverseerOperations, TestTlogReplica, AutoAddReplicasPlanActionTest] [junit4] Completed [568/745 (2!)] on J0 in 13.71s, 1 test, 1 error <<< FAILURES! [...truncated 47473 lines...] BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:826: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:706: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:693: Source checkout is dirty (unversioned/missing files) after running tests!!! Offending files: * lucene/licenses/morfologik-ukrainian-search-3.7.5.jar.sha1 Total time: 92 minutes 34 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
