You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/03/10 21:04:58 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 484 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/484/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest
Error Message:
Address already in use
Stack Trace:
java.net.BindException: Address already in use
at __randomizedtesting.SeedInfo.seed([107C9C3B7CF5E349:E2888B593850EE7A]:0)
at sun.nio.ch.Net.bind0(Native Method)
at sun.nio.ch.Net.bind(Net.java:433)
at sun.nio.ch.Net.bind(Net.java:425)
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)
at org.eclipse.jetty.server.ServerConnector.openAcceptChannel(ServerConnector.java:334)
at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:302)
at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:238)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.eclipse.jetty.server.Server.doStart(Server.java:397)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:396)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:369)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:124)
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 13907 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> 2337392 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/init-core-data-001
[junit4] 2> 2337392 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 2337392 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2337394 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2337394 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001
[junit4] 2> 2337394 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2337394 INFO (Thread-6845) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2337394 INFO (Thread-6845) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2337396 ERROR (Thread-6845) [ ] 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> 2337494 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:56398
[junit4] 2> 2337496 INFO (zkConnectionManagerCallback-5374-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337501 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2337501 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2337501 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2337501 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2337502 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2337502 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2337502 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2337503 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2337503 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2337503 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2337504 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2337504 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2337504 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2337504 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63b97057{/solr,null,AVAILABLE}
[junit4] 2> 2337504 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@32b4896d{/solr,null,AVAILABLE}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@61607701{/solr,null,AVAILABLE}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27dc8d63{/solr,null,AVAILABLE}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@56dca89a{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5da59321{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6cad869b{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-3) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44914}
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38820}
[junit4] 2> 2337506 ERROR (jetty-launcher-5371-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2337506 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2337506 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2337506 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c550ecd{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2337506 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:58.881Z
[junit4] 2> 2337505 INFO (jetty-launcher-5371-thread-2) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2337506 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43153}
[junit4] 2> 2337506 INFO (jetty-launcher-5371-thread-1) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42679}
[junit4] 2> 2337507 ERROR (jetty-launcher-5371-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2337506 ERROR (jetty-launcher-5371-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:58.882Z
[junit4] 2> 2337507 ERROR (jetty-launcher-5371-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:58.882Z
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2337507 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:58.882Z
[junit4] 2> 2337509 INFO (zkConnectionManagerCallback-5376-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337509 INFO (zkConnectionManagerCallback-5382-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337510 INFO (zkConnectionManagerCallback-5380-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337510 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2337510 INFO (zkConnectionManagerCallback-5378-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337510 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2337510 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2337511 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2337515 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10032b246e50002, likely client has closed socket
[junit4] 2> 2337518 INFO (jetty-launcher-5371-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2337518 INFO (jetty-launcher-5371-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2337536 INFO (zkConnectionManagerCallback-5387-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337536 INFO (jetty-launcher-5371-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2337537 INFO (zkConnectionManagerCallback-5390-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337538 INFO (zkConnectionManagerCallback-5394-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337540 INFO (jetty-launcher-5371-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2337541 INFO (zkConnectionManagerCallback-5396-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337541 INFO (zkConnectionManagerCallback-5402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337542 INFO (zkConnectionManagerCallback-5398-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337543 INFO (zkConnectionManagerCallback-5404-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337545 INFO (zkConnectionManagerCallback-5406-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337714 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2337715 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42679_solr
[junit4] 2> 2337717 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.Overseer Overseer (id=72113335312908296-127.0.0.1:42679_solr-n_0000000000) starting
[junit4] 2> 2337724 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2337725 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2337726 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43153_solr
[junit4] 2> 2337728 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2337728 INFO (zkCallback-5403-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2337728 INFO (zkCallback-5397-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2337728 INFO (zkConnectionManagerCallback-5414-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337729 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2337730 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44914_solr
[junit4] 2> 2337730 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2337732 INFO (zkCallback-5397-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2337732 INFO (zkCallback-5403-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2337732 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2337732 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2337732 INFO (zkCallback-5413-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2337734 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2337737 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42679_solr
[junit4] 2> 2337740 INFO (zkCallback-5397-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2337741 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2337742 INFO (zkCallback-5413-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2337742 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2337744 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38820_solr
[junit4] 2> 2337747 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2337747 INFO (zkCallback-5397-thread-2-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2337752 INFO (zkCallback-5413-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2337752 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2337752 INFO (zkCallback-5403-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2337802 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42679.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337807 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42679.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337807 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42679.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337808 INFO (jetty-launcher-5371-thread-1) [n:127.0.0.1:42679_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node1/.
[junit4] 2> 2337858 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44914.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337860 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43153.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337864 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44914.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337864 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44914.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337865 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node3/.
[junit4] 2> 2337866 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43153.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337867 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43153.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337867 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node2/.
[junit4] 2> 2337868 INFO (zkConnectionManagerCallback-5418-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337870 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2337870 INFO (zkConnectionManagerCallback-5422-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337871 INFO (jetty-launcher-5371-thread-3) [n:127.0.0.1:44914_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2337872 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2337873 INFO (jetty-launcher-5371-thread-2) [n:127.0.0.1:43153_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2337919 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38820.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337925 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38820.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337925 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38820.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2337926 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node4/.
[junit4] 2> 2337929 INFO (zkConnectionManagerCallback-5426-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337931 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2337932 INFO (jetty-launcher-5371-thread-4) [n:127.0.0.1:38820_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2337955 INFO (zkConnectionManagerCallback-5428-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337959 INFO (zkConnectionManagerCallback-5432-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337961 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2337963 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2337967 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3c550ecd{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
[junit4] 2> 2337968 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=730223581
[junit4] 2> 2337968 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2337968 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35e75af7: rootName = solr_42679, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4cd3240c
[junit4] 2> 2337972 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2337972 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@56cab79d: rootName = solr_42679, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3ec3b67
[junit4] 2> 2337974 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2337974 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d124a8d: rootName = solr_42679, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6b72610f
[junit4] 2> 2337976 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42679_solr
[junit4] 2> 2337978 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2337978 INFO (zkCallback-5413-thread-1-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337978 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2337978 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337978 INFO (zkCallback-5397-thread-2-processing-n:127.0.0.1:42679_solr) [n:127.0.0.1:42679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337978 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.Overseer Overseer (id=72113335312908296-127.0.0.1:42679_solr-n_0000000000) closing
[junit4] 2> 2337979 INFO (OverseerStateUpdate-72113335312908296-127.0.0.1:42679_solr-n_0000000000) [n:127.0.0.1:42679_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42679_solr
[junit4] 2> 2337979 WARN (OverseerAutoScalingTriggerThread-72113335312908296-127.0.0.1:42679_solr-n_0000000000) [n:127.0.0.1:42679_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2337981 INFO (zkCallback-5417-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337981 INFO (zkCallback-5421-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337980 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337980 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337980 INFO (zkCallback-5403-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337983 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10032b246e5000d, likely client has closed socket
[junit4] 2> 2337984 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2337985 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@32b4896d{/solr,null,UNAVAILABLE}
[junit4] 2> 2337986 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2337987 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43153_solr
[junit4] 2> 2337987 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2337988 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2337988 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2337988 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2337988 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@118ea2fc{/solr,null,AVAILABLE}
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6ecc517f{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37530}
[junit4] 2> 2337989 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2337989 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.Overseer Overseer (id=72113335312908300-127.0.0.1:43153_solr-n_0000000001) starting
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2337989 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:59.364Z
[junit4] 2> 2337991 INFO (zkConnectionManagerCallback-5434-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2337992 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2337998 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2338000 INFO (zkConnectionManagerCallback-5438-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338003 INFO (zkConnectionManagerCallback-5440-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338013 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2338016 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2338018 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37530_solr
[junit4] 2> 2338020 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338020 INFO (zkCallback-5403-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338020 INFO (zkCallback-5421-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338020 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338020 INFO (zkCallback-5417-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338020 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338021 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338021 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338092 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37530.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338098 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37530.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338098 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37530.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338099 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node1/.
[junit4] 2> 2338102 INFO (zkConnectionManagerCallback-5445-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338104 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2338105 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:37530_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2338147 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:52193/solr. Proxy: http://127.0.0.1:37530/solr
[junit4] 2> 2338148 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5da59321{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
[junit4] 2> 2338148 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=106560872
[junit4] 2> 2338149 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2338149 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70a21568: rootName = solr_44914, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@b6ee347
[junit4] 2> 2338154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2338154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38e7f2a6: rootName = solr_44914, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3ec3b67
[junit4] 2> 2338156 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2338156 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b7f1d23: rootName = solr_44914, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6b72610f
[junit4] 2> 2338159 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44914_solr
[junit4] 2> 2338160 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338160 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338161 INFO (zkCallback-5421-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338161 INFO (zkCallback-5403-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338161 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2338161 INFO (zkCallback-5417-thread-1-processing-n:127.0.0.1:44914_solr) [n:127.0.0.1:44914_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338161 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338161 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338162 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2338162 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338162 INFO (zkCallback-5444-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338165 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@61607701{/solr,null,UNAVAILABLE}
[junit4] 2> 2338165 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2338167 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2338167 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2338167 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2338168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2338168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6fec8857{/solr,null,AVAILABLE}
[junit4] 2> 2338168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2c1b9d74{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2338168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2338168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61147}
[junit4] 2> 2338168 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2338169 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2338169 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2338169 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2338169 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:59.544Z
[junit4] 2> 2338170 INFO (zkConnectionManagerCallback-5447-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338171 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2338177 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2338179 INFO (zkConnectionManagerCallback-5451-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338182 INFO (zkConnectionManagerCallback-5453-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338195 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2338199 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2338202 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61147_solr
[junit4] 2> 2338204 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338204 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338204 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338204 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338204 INFO (zkCallback-5421-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338204 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338204 INFO (zkCallback-5444-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338205 INFO (zkCallback-5452-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338281 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61147.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338288 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61147.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338288 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61147.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338289 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node3/.
[junit4] 2> 2338292 INFO (zkConnectionManagerCallback-5458-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338294 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2338296 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:61147_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2338323 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:63976/solr. Proxy: http://127.0.0.1:61147/solr
[junit4] 2> 2338324 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@56dca89a{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
[junit4] 2> 2338325 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=20841342
[junit4] 2> 2338325 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2338325 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@643a87a4: rootName = solr_43153, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@193c6739
[junit4] 2> 2338329 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2338329 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1778690: rootName = solr_43153, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3ec3b67
[junit4] 2> 2338332 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2338332 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b539038: rootName = solr_43153, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6b72610f
[junit4] 2> 2338334 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:43153_solr
[junit4] 2> 2338336 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338336 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338336 INFO (zkCallback-5405-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338336 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2338337 INFO (zkCallback-5421-thread-1-processing-n:127.0.0.1:43153_solr) [n:127.0.0.1:43153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2338338 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338338 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.Overseer Overseer (id=72113335312908300-127.0.0.1:43153_solr-n_0000000001) closing
[junit4] 2> 2338338 INFO (zkCallback-5452-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338338 INFO (zkCallback-5444-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338338 INFO (OverseerStateUpdate-72113335312908300-127.0.0.1:43153_solr-n_0000000001) [n:127.0.0.1:43153_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43153_solr
[junit4] 2> 2338339 WARN (OverseerAutoScalingTriggerThread-72113335312908300-127.0.0.1:43153_solr-n_0000000001) [n:127.0.0.1:43153_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2338341 INFO (zkCallback-5457-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338344 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10032b246e5000c, likely client has closed socket
[junit4] 2> 2338344 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38820_solr
[junit4] 2> 2338345 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@63b97057{/solr,null,UNAVAILABLE}
[junit4] 2> 2338346 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2338347 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.Overseer Overseer (id=72113335312908297-127.0.0.1:38820_solr-n_0000000003) starting
[junit4] 2> 2338349 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@676abfc4{/solr,null,AVAILABLE}
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ecbd413{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2338350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50120}
[junit4] 2> 2338350 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2338351 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2338351 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2338351 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2338351 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:59.726Z
[junit4] 2> 2338358 INFO (zkConnectionManagerCallback-5460-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338359 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2338364 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2338366 INFO (zkConnectionManagerCallback-5464-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338369 INFO (zkConnectionManagerCallback-5466-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338378 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2338381 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2338384 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50120_solr
[junit4] 2> 2338385 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338385 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338386 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338386 INFO (zkCallback-5457-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338386 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338387 INFO (zkCallback-5452-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338387 INFO (zkCallback-5444-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338388 INFO (zkCallback-5465-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338431 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50120.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338437 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50120.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338437 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50120.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338438 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node2/.
[junit4] 2> 2338442 INFO (zkConnectionManagerCallback-5471-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338444 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2338446 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:50120_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2338492 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:39208/solr. Proxy: http://127.0.0.1:50120/solr
[junit4] 2> 2338493 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6cad869b{HTTP/1.1,[http/1.1]}{127.0.0.1:?}
[junit4] 2> 2338494 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1880218167
[junit4] 2> 2338495 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2338495 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ea33fb3: rootName = solr_38820, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6d179d17
[junit4] 2> 2338499 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2338500 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@123ebea0: rootName = solr_38820, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3ec3b67
[junit4] 2> 2338502 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2338502 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f2d65ad: rootName = solr_38820, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6b72610f
[junit4] 2> 2338511 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38820_solr
[junit4] 2> 2338513 INFO (zkCallback-5395-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338513 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338513 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338513 INFO (zkCallback-5465-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338514 INFO (zkCallback-5425-thread-1-processing-n:127.0.0.1:38820_solr) [n:127.0.0.1:38820_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338514 INFO (zkCallback-5444-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338514 INFO (zkCallback-5452-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338515 INFO (zkCallback-5470-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338515 INFO (zkCallback-5457-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2338516 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2338516 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2338516 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.Overseer Overseer (id=72113335312908297-127.0.0.1:38820_solr-n_0000000003) closing
[junit4] 2> 2338517 INFO (OverseerStateUpdate-72113335312908297-127.0.0.1:38820_solr-n_0000000003) [n:127.0.0.1:38820_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38820_solr
[junit4] 2> 2338517 WARN (OverseerAutoScalingTriggerThread-72113335312908297-127.0.0.1:38820_solr-n_0000000003) [n:127.0.0.1:38820_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2338521 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37530_solr
[junit4] 2> 2338521 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@27dc8d63{/solr,null,UNAVAILABLE}
[junit4] 2> 2338522 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2338524 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.Overseer Overseer (id=72113335312908309-127.0.0.1:37530_solr-n_0000000004) starting
[junit4] 2> 2338524 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2338525 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2338525 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2338525 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2338525 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d568538{/solr,null,AVAILABLE}
[junit4] 2> 2338526 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e810b05{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2338526 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2338526 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47976}
[junit4] 2> 2338526 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2338527 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0
[junit4] 2> 2338527 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2338527 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2338527 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T20:20:59.902Z
[junit4] 2> 2338538 INFO (zkConnectionManagerCallback-5473-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2338554 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56398/solr
[junit4] 2> 2338556 INFO (zkConnectionManagerCallback-5477-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338559 INFO (zkConnectionManagerCallback-5479-thread-1-processing-n:127.0.0.1:47976_solr) [n:127.0.0.1:47976_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338570 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2338572 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2338574 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47976_solr
[junit4] 2> 2338576 INFO (zkCallback-5439-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338577 INFO (zkCallback-5431-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338577 INFO (zkCallback-5465-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338577 INFO (zkCallback-5457-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338578 INFO (zkCallback-5452-thread-1-processing-n:127.0.0.1:61147_solr) [n:127.0.0.1:61147_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338578 INFO (zkCallback-5444-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338579 INFO (zkCallback-5478-thread-1-processing-n:127.0.0.1:47976_solr) [n:127.0.0.1:47976_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338579 INFO (zkCallback-5470-thread-1-processing-n:127.0.0.1:50120_solr) [n:127.0.0.1:50120_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2338663 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47976.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338669 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47976.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338669 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47976.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@118d98b8
[junit4] 2> 2338670 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001/tempDir-001/node4/.
[junit4] 2> 2338681 INFO (zkConnectionManagerCallback-5484-thread-1-processing-n:127.0.0.1:47976_solr) [n:127.0.0.1:47976_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2338683 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2338685 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [n:127.0.0.1:47976_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56398/solr ready
[junit4] 2> 2338717 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[107C9C3B7CF5E349]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:47916/solr. Proxy: http://127.0.0.1:47976/solr
[junit4] 2> 2338748 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[107C9C3B7CF5E349]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMostInSyncReplicasCanWinElection
[junit4] 2> 2338749 INFO (SocketProxy-Acceptor-37530) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=46111,localport=37530], receiveBufferSize:130880
[junit4] 2> 2338749 INFO (SocketProxy-Acceptor-37530) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=52193,localport=45582], receiveBufferSize=139264
[junit4] 2> 2338750 INFO (qtp889792997-25640) [n:127.0.0.1:37530_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=3&name=collection1&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2338753 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2338755 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/collection1.AUTOCREATED/protwords.txt
[junit4] 2> 2338757 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 2338760 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/collection1.AUTOCREATED/solrconfig.xml
[junit4] 2> 2338762 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/collection1.AUTOCREATED/synonyms.txt
[junit4] 2> 2338764 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/collection1.AUTOCREATED/stopwords.txt
[junit4] 2> 2338767 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/collection1.AUTOCREATED/params.json
[junit4] 2> 2338770 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/collection1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 2338773 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 2338775 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/collection1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 2338778 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/collection1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 2338780 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/collection1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 2338782 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/collection1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 2338785 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 2338787 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 2338790 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/collection1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 2338792 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 2338794 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/collection1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 2338796 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 2338799 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/collection1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 2338801 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 2338803 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 2338806 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 2338808 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/collection1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 2338811 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/collection1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 2338813 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 2338815 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/collection1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 2338817 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/collection1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 2338820 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/collection1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 2338822 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 2338825 INFO (OverseerThreadFactory-9630-thread-1-processing-n:127.0.0.1:37530_solr) [n:127.0.0.1:37530_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lan
[...truncated too long message...]
n
[junit4] 2> 19 /solr/aliases.json
[junit4] 2> 9 /solr/security.json
[junit4] 2> 8 /solr/collections/basicTest/terms/shard1
[junit4] 2> 4 /solr/configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 4 /solr/configs/collection1.AUTOCREATED
[junit4] 2> 2 /solr/collections/basicTest/collectionprops.json
[junit4] 2> 2 /solr/configs/basicTest.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/basicTest.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 54 /solr/collections/collection1/state.json
[junit4] 2> 19 /solr/clusterstate.json
[junit4] 2> 11 /solr/collections/basicTest/state.json
[junit4] 2> 5 /solr/autoscaling.json
[junit4] 2> 2 /solr/overseer_elect/election/72113335312908313-127.0.0.1:61147_solr-n_0000000005
[junit4] 2> 2 /solr/overseer_elect/election/72113335312908300-127.0.0.1:43153_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/live_nodes
[junit4] 2> 19 /solr/collections
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 5 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_107C9C3B7CF5E349-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=587, maxMBSortInHeap=7.127489102042764, sim=RandomSimilarity(queryNorm=false): {}, locale=th-TH-u-nu-thai-x-lvariant-TH, timezone=Africa/Asmara
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=3,threads=1,free=111806160,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [DistributedIntervalFacetingTest, TestJavabinTupleStreamParser, TestSolrConfigHandler, TestComplexPhraseLeadingWildcard, TestTolerantUpdateProcessorRandomCloud, ChaosMonkeyNothingIsSafeWithPullReplicasTest, HdfsAutoAddReplicasIntegrationTest, TestPrepRecovery, MetricsHandlerTest, TestInfoStreamLogging, DirectUpdateHandlerTest, DistributedFacetPivotLongTailTest, TestTlogReplica, TestDocSet, HighlighterMaxOffsetTest, RegexBoostProcessorTest, TestLeaderInitiatedRecoveryThread, TemplateUpdateProcessorTest, HdfsRestartWhileUpdatingTest, JsonLoaderTest, DistributedQueryComponentCustomSortTest, DocValuesTest, TokenizerChainTest, DateRangeFieldTest, TestLegacyNumericUtils, VMParamsZkACLAndCredentialsProvidersTest, TestMiniSolrCloudClusterSSL, TestFilteredDocIdSet, SolrMetricManagerTest, DocValuesNotIndexedTest, ChangedSchemaMergeTest, SolrGraphiteReporterTest, TestPullReplicaErrorHandling, RequestHandlersTest, TestSolrDeletionPolicy2, TestAtomicUpdateErrorCases, TestSchemalessBufferedUpdates, ChaosMonkeySafeLeaderTest, TestPointFields, TestScoreJoinQPNoScore, DataDrivenBlockJoinTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestRequestForwarding, TestSearcherReuse, MetricsConfigTest, SolrIndexMetricsTest, TestCodecSupport, AnalyticsMergeStrategyTest, TestRandomDVFaceting, TestChildDocTransformer, ConfigureRecoveryStrategyTest, IndexSchemaTest, AutoAddReplicasPlanActionTest, SimpleFacetsTest, CdcrBidirectionalTest, ZkControllerTest, TestConfig, DeleteLastCustomShardedReplicaTest, SolrCloudReportersTest, SolrCoreCheckLockOnStartupTest, TestPolicyCloud, TestSmileRequest, MinimalSchemaTest, BlockJoinFacetSimpleTest, TestCryptoKeys, DistributedQueueTest, TestInPlaceUpdatesDistrib, TestWriterPerf, TestCustomDocTransformer, TestStressReorder, ZkSolrClientTest, TestFastLRUCache, DistributedSpellCheckComponentTest, TestBadConfig, TestComponentsName, TestPayloadCheckQParserPlugin, TestExactSharedStatsCache, TestDFISimilarityFactory, CloneFieldUpdateProcessorFactoryTest, DistributedSuggestComponentTest, MoveReplicaHDFSTest, ClassificationUpdateProcessorIntegrationTest, TestDistributedMissingSort, TestDynamicLoading, AddReplicaTest, TestDefaultStatsCache, TestUniqueKeyFieldResource, TestIndexingPerformance, PreAnalyzedFieldTest, SpellCheckCollatorTest, TestXmlQParserPlugin, StressHdfsTest, HLLUtilTest, TestNRTOpen, TestCopyFieldCollectionResource, TestMissingGroups, HdfsRecoverLeaseTest, TestBulkSchemaConcurrent, FileBasedSpellCheckerTest, CoreMergeIndexesAdminHandlerTest, TestHdfsBackupRestoreCore, TestConfigSets, ImplicitSnitchTest, NoCacheHeaderTest, BitVectorTest, HttpPartitionOnCommitTest, TestFieldCacheWithThreads, NumberUtilsTest, UpdateRequestProcessorFactoryTest, PreAnalyzedUpdateProcessorTest, DeleteStatusTest, UUIDFieldTest, TestRandomFaceting, TestSolrCLIRunExample, SuggesterWFSTTest, TestSolrCloudWithDelegationTokens, ExternalFileFieldSortTest, TestSchemaVersionResource, NodeLostTriggerTest, TestStressRecovery, TestLegacyField, BadIndexSchemaTest, DistribDocExpirationUpdateProcessorTest, TestRuleBasedAuthorizationPlugin, TestReversedWildcardFilterFactory, SolrMetricsIntegrationTest, TestCoreAdminApis, TestNoOpRegenerator, TestMinMaxOnMultiValuedField, TlogReplayBufferedWhileIndexingTest, TestSlowCompositeReaderWrapper, MissingSegmentRecoveryTest, TestManagedSynonymFilterFactory, DocValuesMultiTest, TestExtendedDismaxParser, FullHLLTest, SolrJmxReporterTest, SuggestComponentContextFilterQueryTest, URLClassifyProcessorTest, TestJsonRequest, SecurityConfHandlerTest, TestSchemaResource, OverseerTaskQueueTest, LegacyCloudClusterPropTest, DefaultValueUpdateProcessorTest, SolrCmdDistributorTest, HdfsDirectoryTest, LeaderFailureAfterFreshStartTest, TestAnalyzedSuggestions, DistanceFunctionTest, TestFuzzyAnalyzedSuggestions, SpellPossibilityIteratorTest, AssignBackwardCompatibilityTest, SliceStateTest, TestRequestStatusCollectionAPI, AnalysisErrorHandlingTest, RecoveryAfterSoftCommitTest, OverseerTest, TestSolr4Spatial, BlockDirectoryTest, TestMultiValuedNumericRangeQuery, XsltUpdateRequestHandlerTest, TestLMJelinekMercerSimilarityFactory, BadCopyFieldTest, TestEmbeddedSolrServerAdminHandler, TestStandardQParsers, TestSearchPerf, TestCloudInspectUtil, RulesTest, TestSimpleTrackingShardHandler, HdfsChaosMonkeySafeLeaderTest, TestSubQueryTransformerCrossCore, ClusterStateUpdateTest, TimeZoneUtilsTest, TestRandomRequestDistribution, TestObjectReleaseTracker, TestStreamBody, ResponseLogComponentTest, TestUpdate, SolrXmlInZkTest, TestLMDirichletSimilarityFactory, TestWordDelimiterFilterFactory, LoggingHandlerTest, TestSort, ZkShardTermsTest, ClusterStateTest, TestDistributedStatsComponentCardinality, TestUseDocValuesAsStored, TestFileDictionaryLookup, TestClusterProperties, CdcrReplicationDistributedZkTest, BasicDistributedZk2Test, SyncSliceTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, TestZkChroot, ZkCLITest, TestDistributedSearch, TestDistributedGrouping, TestRecovery, TestHashPartitioner, TestStressVersions, DistributedTermsComponentTest, TestRangeQuery, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, ConvertedLegacyTest, TestFiltering, TestFunctionQuery, BasicFunctionalityTest, HighlighterTest, SoftAutoCommitTest, XmlUpdateRequestHandlerTest, PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, TestJmxIntegration, TestCSVResponseWriter, QueryParsingTest, PingRequestHandlerTest, HighlighterConfigTest, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, BadComponentTest, TestBinaryField, NumericFieldsTest, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, SynonymTokenizerTest, EchoParamsTest, TestDFRSimilarityFactory, TestIBSimilarityFactory, ResourceLoaderTest, ScriptEngineTest, DateMathParserTest, PrimUtilsTest, DOMUtilTest, RAMDirectoryFactoryTest, TestLRUCache, TestUtils, TestDocumentBuilder, SystemInfoHandlerTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase, SolrTestCaseJ4Test, TestCrossCoreJoin, TestHighlightDedupGrouping, TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, AliasIntegrationTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CleanupOldIndexTest, ConcurrentCreateRoutedAliasTest, CreateCollectionCleanupTest, DeleteReplicaTest, DistribCursorPagingTest, HttpPartitionTest, LIROnShardRestartTest, LeaderElectionContextKeyTest, LeaderVoteWaitTimeoutTest]
[junit4] Completed [529/782 (1!)] on J1 in 23.77s, 2 tests, 1 error <<< FAILURES!
[...truncated 50447 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/484/consoleText
[repro] Revision: 037f1ee3b8afe78c510211a228203f18bfe84220
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=LeaderVoteWaitTimeoutTest -Dtests.method=basicTest -Dtests.seed=107C9C3B7CF5E349 -Dtests.slow=true -Dtests.locale=th-TH-u-nu-thai-x-lvariant-TH -Dtests.timezone=Africa/Asmara -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] LeaderVoteWaitTimeoutTest
[repro] ant compile-test
[...truncated 3331 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=107C9C3B7CF5E349 -Dtests.slow=true -Dtests.locale=th-TH-u-nu-thai-x-lvariant-TH -Dtests.timezone=Africa/Asmara -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 73 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
[...truncated 70 lines...]
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 485 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/485/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testGammaDistribution
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([55FC5857EAED224A:688673F9C995885D]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testGammaDistribution(StreamExpressionTest.java:8650)
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 15725 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/init-core-data-001
[junit4] 2> 143525 WARN (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=35 numCloses=35
[junit4] 2> 143525 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 143527 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 143527 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 143528 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001
[junit4] 2> 143528 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 143529 INFO (Thread-261) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 143529 INFO (Thread-261) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 143535 ERROR (Thread-261) [ ] 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> 143629 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:60524
[junit4] 2> 143631 INFO (zkConnectionManagerCallback-383-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143639 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T09:27:37+12:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 143640 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 143640 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 143640 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 143641 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@51580ca7{/solr,null,AVAILABLE}
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3ddaab9d{HTTP/1.1,[http/1.1]}{127.0.0.1:40205}
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.e.j.s.Server Started @146432ms
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40205}
[junit4] 2> 143643 ERROR (jetty-launcher-380-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 143643 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T07:00:08.253Z
[junit4] 2> 143644 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T09:27:37+12:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 143644 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T09:27:37+12:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 143646 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 143646 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 143646 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 143646 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5a45fbda{/solr,null,AVAILABLE}
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5165d935{HTTP/1.1,[http/1.1]}{127.0.0.1:63809}
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.e.j.s.Server Started @146436ms
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63809}
[junit4] 2> 143647 ERROR (jetty-launcher-380-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 143647 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T07:00:08.257Z
[junit4] 2> 143649 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T09:27:37+12:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 143652 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 143652 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 143652 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 143653 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2a2c28cd{/solr,null,AVAILABLE}
[junit4] 2> 143653 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4e707287{HTTP/1.1,[http/1.1]}{127.0.0.1:34453}
[junit4] 2> 143653 INFO (jetty-launcher-380-thread-2) [ ] o.e.j.s.Server Started @146443ms
[junit4] 2> 143653 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34453}
[junit4] 2> 143653 ERROR (jetty-launcher-380-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 143654 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 143654 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 143654 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 143654 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T07:00:08.264Z
[junit4] 2> 143662 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 143662 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 143662 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 143663 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6296a8ea{/solr,null,AVAILABLE}
[junit4] 2> 143663 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@614a55a3{HTTP/1.1,[http/1.1]}{127.0.0.1:61366}
[junit4] 2> 143663 INFO (jetty-launcher-380-thread-3) [ ] o.e.j.s.Server Started @146453ms
[junit4] 2> 143663 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61366}
[junit4] 2> 143663 ERROR (jetty-launcher-380-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 143664 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 143664 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 143664 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 143664 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T07:00:08.274Z
[junit4] 2> 143666 INFO (zkConnectionManagerCallback-385-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143666 INFO (zkConnectionManagerCallback-387-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143666 INFO (zkConnectionManagerCallback-389-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143668 INFO (zkConnectionManagerCallback-391-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143668 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 143668 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 143668 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 143668 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 143676 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10034fb8bed0002, likely client has closed socket
[junit4] 2> 143677 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10034fb8bed0003, likely client has closed socket
[junit4] 2> 143680 INFO (jetty-launcher-380-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60524/solr
[junit4] 2> 143683 INFO (jetty-launcher-380-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60524/solr
[junit4] 2> 143695 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10034fb8bed0004, likely client has closed socket
[junit4] 2> 143697 INFO (zkConnectionManagerCallback-399-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143699 INFO (jetty-launcher-380-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60524/solr
[junit4] 2> 143700 INFO (zkConnectionManagerCallback-395-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143704 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10034fb8bed0001, likely client has closed socket
[junit4] 2> 143704 INFO (jetty-launcher-380-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60524/solr
[junit4] 2> 143704 INFO (zkConnectionManagerCallback-403-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143711 INFO (zkConnectionManagerCallback-409-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143719 INFO (zkConnectionManagerCallback-413-thread-1-processing-n:127.0.0.1:61366_solr) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143742 WARN (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 143744 INFO (zkConnectionManagerCallback-411-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143744 INFO (zkConnectionManagerCallback-407-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143747 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10034fb8bed0008, likely client has closed socket
[junit4] 2> 143754 INFO (zkConnectionManagerCallback-416-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143762 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 143763 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40205_solr
[junit4] 2> 143765 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.Overseer Overseer (id=72115849026928649-127.0.0.1:40205_solr-n_0000000000) starting
[junit4] 2> 143768 WARN (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 143773 WARN (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 143774 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 143775 WARN (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 143776 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63809_solr
[junit4] 2> 143816 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 143817 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 143817 INFO (zkCallback-408-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 143821 INFO (zkCallback-412-thread-1-processing-n:127.0.0.1:61366_solr) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 143824 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 143824 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 143829 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34453_solr
[junit4] 2> 143847 INFO (zkCallback-410-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 143847 INFO (zkCallback-412-thread-1-processing-n:127.0.0.1:61366_solr) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 143848 INFO (zkCallback-408-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 143849 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 143856 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61366_solr
[junit4] 2> 143858 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 143868 INFO (zkCallback-412-thread-1-processing-n:127.0.0.1:61366_solr) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 143868 INFO (zkCallback-410-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 143885 INFO (zkCallback-408-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 143898 INFO (zkConnectionManagerCallback-423-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 143903 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 143905 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60524/solr ready
[junit4] 2> 143910 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40205_solr
[junit4] 2> 143912 INFO (zkCallback-410-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 143912 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 143913 INFO (zkCallback-422-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 143955 INFO (zkCallback-412-thread-1-processing-n:127.0.0.1:61366_solr) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 143955 INFO (zkCallback-408-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 143997 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63809.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144063 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63809.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144063 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63809.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144064 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node3/.
[junit4] 2> 144079 INFO (zkConnectionManagerCallback-427-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 144083 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 144087 INFO (jetty-launcher-380-thread-4) [n:127.0.0.1:63809_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60524/solr ready
[junit4] 2> 144129 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40205.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144157 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61366.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144179 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40205.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144179 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40205.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144180 INFO (jetty-launcher-380-thread-1) [n:127.0.0.1:40205_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node1/.
[junit4] 2> 144182 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61366.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144183 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61366.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144184 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node4/.
[junit4] 2> 144189 INFO (zkConnectionManagerCallback-431-thread-1-processing-n:127.0.0.1:61366_solr) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 144190 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34453.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144191 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 144193 INFO (jetty-launcher-380-thread-3) [n:127.0.0.1:61366_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60524/solr ready
[junit4] 2> 144202 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34453.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144202 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34453.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 144204 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node2/.
[junit4] 2> 144210 INFO (zkConnectionManagerCallback-435-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 144211 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 144213 INFO (jetty-launcher-380-thread-2) [n:127.0.0.1:34453_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60524/solr ready
[junit4] 2> 144322 INFO (zkConnectionManagerCallback-437-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 144327 INFO (zkConnectionManagerCallback-441-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 144329 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 144331 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60524/solr ready
[junit4] 2> 144345 INFO (qtp1599370731-1587) [n:127.0.0.1:34453_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 144349 INFO (OverseerThreadFactory-487-thread-1-processing-n:127.0.0.1:40205_solr) [n:127.0.0.1:40205_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 144477 INFO (OverseerStateUpdate-72115849026928649-127.0.0.1:40205_solr-n_0000000000) [n:127.0.0.1:40205_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34453/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 144480 INFO (OverseerStateUpdate-72115849026928649-127.0.0.1:40205_solr-n_0000000000) [n:127.0.0.1:40205_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63809/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 144684 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 144684 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 144684 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 144685 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 144827 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 144827 INFO (zkCallback-410-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 144828 INFO (zkCallback-410-thread-2-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 144828 INFO (zkCallback-415-thread-2-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 145753 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 145753 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 145768 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 145810 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 145858 WARN (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 145858 WARN (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 145858 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 145859 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 145859 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34453.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 145859 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 145859 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node2/collection1_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
[junit4] 2> 145896 WARN (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 145896 WARN (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 145897 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 145897 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 145897 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63809.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dbb7274
[junit4] 2> 145897 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 145897 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node3/collection1_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001/tempDir-001/node3/./collection1_shard2_replica_n2/data/]
[junit4] 2> 145970 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 145971 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 145972 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 145972 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 145974 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d58e2ac[collection1_shard1_replica_n1] main]
[junit4] 2> 145976 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 145977 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 145977 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 145978 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594623640823922688
[junit4] 2> 145987 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 145999 INFO (searcherExecutor-495-thread-1-processing-n:127.0.0.1:34453_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@6d58e2ac[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 146002 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 146002 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 146002 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/
[junit4] 2> 146002 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 146002 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 146002 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 146012 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 146026 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 146026 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 146027 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 146027 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 146030 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@282f01f5[collection1_shard2_replica_n2] main]
[junit4] 2> 146035 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 146036 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 146036 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 146037 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594623640885788672
[junit4] 2> 146040 INFO (searcherExecutor-496-thread-1-processing-n:127.0.0.1:63809_solr x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@282f01f5[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 146045 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 146052 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 146052 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 146052 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/
[junit4] 2> 146052 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 146052 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 146052 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 146070 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 146178 INFO (zkCallback-410-thread-2-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146178 INFO (zkCallback-415-thread-2-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146178 INFO (zkCallback-410-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146178 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146222 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 146227 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 146230 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1547
[junit4] 2> 146231 INFO (qtp230124139-1574) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1547
[junit4] 2> 146240 INFO (qtp1599370731-1587) [n:127.0.0.1:34453_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 146333 INFO (zkCallback-410-thread-1-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146333 INFO (zkCallback-410-thread-2-processing-n:127.0.0.1:34453_solr) [n:127.0.0.1:34453_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146345 INFO (zkCallback-415-thread-1-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146345 INFO (zkCallback-415-thread-2-processing-n:127.0.0.1:63809_solr) [n:127.0.0.1:63809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 146352 INFO (OverseerCollectionConfigSetProcessor-72115849026928649-127.0.0.1:40205_solr-n_0000000000) [n:127.0.0.1:40205_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 147241 INFO (qtp1599370731-1587) [n:127.0.0.1:34453_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2896
[junit4] 2> 147241 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):90
[junit4] 2> 147242 INFO (SUITE-StreamExpressionTest-seed#[55FC5857EAED224A]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 147295 INFO (TEST-StreamExpressionTest.testPoissonDistribution-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPoissonDistribution
[junit4] 2> 147372 INFO (qtp230124139-1581) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 147373 INFO (qtp230124139-1581) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642275151872)} 0 12
[junit4] 2> 147380 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642294026240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147380 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147380 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642294026240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147380 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147385 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147385 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147385 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 4
[junit4] 2> 147386 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6
[junit4] 2> 147392 INFO (qtp1599370731-1589) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 147392 INFO (qtp1599370731-1589) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642213285888),commit=} 0 90
[junit4] 2> 147400 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=let(a%3DpoissonDistribution(100),+++++++++++++++b%3Dsample(a,+10000),+++++++++++++++tuple(d%3Ddescribe(b),++++++++++++++++++++++p%3Dprobability(a,+100),++++++++++++++++++++++c%3DcumulativeProbability(a,+100)))&wt=json&version=2.2} status=0 QTime=3
[junit4] 2> 147554 INFO (TEST-StreamExpressionTest.testPoissonDistribution-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPoissonDistribution
[junit4] 2> 147599 INFO (TEST-StreamExpressionTest.testPolyfit-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPolyfit
[junit4] 2> 147610 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642528907264)} 0 5
[junit4] 2> 147613 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642538344448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147613 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147613 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147613 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 147615 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642540441600,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147616 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147617 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147617 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 147617 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642525761536),commit=} 0 17
[junit4] 2> 147627 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,++++a%3Darray(0,1,2,3,4,5,6,7),++++fit%3Dpolyfit(a,+1))&wt=json&version=2.2} status=0 QTime=4
[junit4] 2> 147729 INFO (TEST-StreamExpressionTest.testPolyfit-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPolyfit
[junit4] 2> 147803 INFO (TEST-StreamExpressionTest.testTtest-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTtest
[junit4] 2> 147809 INFO (qtp230124139-1577) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642743865344)} 0 0
[junit4] 2> 147813 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642748059648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147813 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147814 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147814 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 4
[junit4] 2> 147815 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642750156800,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147815 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147815 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147815 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 147816 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642738622464),commit=} 0 12
[junit4] 2> 147822 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,a%3Darray(0,1,2,3,4,5,6,7,9,10,11,12),+b%3Darray(0,1,2,3,4,5,6,7,1,1,1,1),+ttest%3Dttest(a,+b),sample2Mean%3Dmean(b),onesamplettest%3Dttest(sample2Mean,+b),pairedttest%3DpairedTtest(a,b))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 147834 INFO (TEST-StreamExpressionTest.testTtest-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTtest
[junit4] 2> 147875 INFO (TEST-StreamExpressionTest.testPercentile-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPercentile
[junit4] 2> 147881 INFO (qtp230124139-1577) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642818314240)} 0 1
[junit4] 2> 147883 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642821459968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147883 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147883 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147883 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 147884 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642822508544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147884 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147884 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147884 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 147884 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642814119936),commit=} 0 8
[junit4] 2> 147888 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=percentile(array(1,2,3,4,5,6,7,8,9,10,11),+50)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 147897 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=percentile(array(11,10,3,4,5,6,7,8,9,2,1),+50)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 147899 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=percentile(array(11,10,3,4,5,6,7,8,9,2,1),+20)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 147900 INFO (TEST-StreamExpressionTest.testPercentile-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPercentile
[junit4] 2> 147952 INFO (TEST-StreamExpressionTest.testConvolution-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConvolution
[junit4] 2> 147970 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642900103168)} 0 12
[junit4] 2> 147971 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642913734656,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147972 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147973 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147973 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 147974 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623642916880384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147974 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 147974 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147974 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 147975 INFO (qtp230124139-1577) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1594623642894860288),commit=} 0 21
[junit4] 2> 148112 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[id_1 (1594623642964066304), id_3 (1594623642965114880), id_4 (1594623642965114881), id_5 (1594623642966163456), id_6 (1594623642966163457), id_7 (1594623642966163458), id_8 (1594623642966163459), id_9 (1594623642966163460), id_11 (1594623642967212032), id_12 (1594623642967212033), ... (125 adds)]} 0 93
[junit4] 2> 148131 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[id_2 (1594623642924220416), id_10 (1594623642942046208), id_13 (1594623642942046209), id_14 (1594623642942046210), id_16 (1594623642942046211), id_17 (1594623642943094784), id_18 (1594623642943094785), id_21 (1594623642943094786), id_22 (1594623642943094787), id_23 (1594623642943094788), ... (125 adds)]} 0 150
[junit4] 2> 148136 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623643086749696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 148136 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64c39b83 commitCommandVersion:1594623643086749696
[junit4] 2> 148143 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623643094089728,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 148143 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6e181775 commitCommandVersion:1594623643094089728
[junit4] 2> 148167 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@6efe75f3[collection1_shard2_replica_n2] main]
[junit4] 2> 148168 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 148169 INFO (searcherExecutor-496-thread-1-processing-n:127.0.0.1:63809_solr x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@6efe75f3[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c125)))}
[junit4] 2> 148169 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 33
[junit4] 2> 148170 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@44fed023[collection1_shard1_replica_n1] main]
[junit4] 2> 148171 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 148171 INFO (searcherExecutor-495-thread-1-processing-n:127.0.0.1:34453_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@44fed023[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c125)))}
[junit4] 2> 148172 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 32
[junit4] 2> 148172 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{commit=} 0 36
[junit4] 2> 148177 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=let(a%3Dtimeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),+b%3Dselect(timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),mult(2,+count(*))+as+nvalue),+c%3Dcol(a,+count(*)),+d%3Dcol(b,+nvalue),+tuple(colc%3Dc,+cold%3Dd,+conv%3Dconv(c,d)))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 148195 INFO (zkConnectionManagerCallback-445-thread-1-processing-n:127.0.0.1:34453_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 148200 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 148208 INFO (qtp1599370731-1591) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60524/solr ready
[junit4] 2> 148330 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1520751612875&isShard=true&wt=javabin} hits=125 status=0 QTime=35
[junit4] 2> 148330 INFO (qtp230124139-1577) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1520751612875&isShard=true&wt=javabin} hits=125 status=0 QTime=36
[junit4] 2> 148331 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:4&field=test_dt&rows=0&wt=javabin&version=2} hits=250 status=0 QTime=66
[junit4] 2> 148340 INFO (qtp1599370731-1593) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1520751612943&isShard=true&wt=javabin} hits=125 status=0 QTime=3
[junit4] 2> 148347 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:4&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:63809/solr/collection1_shard2_replica_n2/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1520751612943&isShard=true&wt=javabin} hits=125 status=0 QTime=6
[junit4] 2> 148348 INFO (qtp1599370731-1594) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:4&field=test_dt&rows=0&wt=javabin&version=2} hits=250 status=0 QTime=14
[junit4] 2> 148352 INFO (TEST-StreamExpressionTest.testConvolution-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConvolution
[junit4] 2> 148483 INFO (TEST-StreamExpressionTest.testBasicTextLogitStream-seed#[55FC5857EAED224A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasicTextLogitStream
[junit4] 2> 148491 INFO (qtp1599370731-1593) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8a3e62[collection1_shard1_replica_n1] realtime]
[junit4] 2> 148491 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@71bf290b[collection1_shard2_replica_n2] realtime]
[junit4] 2> 148492 INFO (qtp230124139-1578) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1594623643455848448)} 0 4
[junit4] 2> 148496 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623643464237056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 148496 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6e181775 commitCommandVersion:1594623643464237056
[junit4] 2> 148501 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@420b3c89[collection1_shard1_replica_n1] main]
[junit4] 2> 148502 INFO (searcherExecutor-495-thread-1-processing-n:127.0.0.1:34453_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@420b3c89[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 148503 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 148503 INFO (qtp1599370731-1592) [n:127.0.0.1:34453_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34453/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 7
[junit4] 2> 148507 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594623643475771392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 148507 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@64c39b83 commitCommandVersion:1594623643475771392
[junit4] 2> 148508 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ec0837c[collection1_shard2_replica_n2] main]
[junit4] 2> 148508 INFO (qtp230124139-1582) [n:127.0.0.1:63809_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 148510
[...truncated too long message...]
tion/collectionprops.json
[junit4] 2> 2 /solr/collections/destination1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue1/collectionprops.json
[junit4] 2> 2 /solr/collections/modelCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/uknownCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 104 /solr/clusterstate.json
[junit4] 2> 40 /solr/collections/destinationCollection/state.json
[junit4] 2> 30 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 20 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus1/state.json
[junit4] 2> 10 /solr/collections/destination/state.json
[junit4] 2> 10 /solr/collections/destination1/state.json
[junit4] 2> 10 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/collections/workQueue1/state.json
[junit4] 2> 10 /solr/collections/workQueue/state.json
[junit4] 2> 10 /solr/collections/uknownCollection/state.json
[junit4] 2> 10 /solr/collections/modelCollection/state.json
[junit4] 2> 10 /solr/collections/checkpointCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72115849026928652-127.0.0.1:63809_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 104 /solr/live_nodes
[junit4] 2> 103 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_55FC5857EAED224A-001
[junit4] 2> Mar 11, 2018 7:02:12 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name_s=Lucene50(blocksize=128), expr_s=PostingsFormat(name=LuceneFixedGap), iteration_i=PostingsFormat(name=LuceneFixedGap), terms_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), join2_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), subject=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=FST50, falseNegative_i=FST50, a_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a2_s=Lucene50(blocksize=128), a_i=PostingsFormat(name=LuceneFixedGap), level1_s=PostingsFormat(name=LuceneFixedGap), body_t=Lucene50(blocksize=128), a_s=Lucene50(blocksize=128), a_t=FST50, id=PostingsFormat(name=LuceneFixedGap), text=Lucene50(blocksize=128), alpha_d=Lucene50(blocksize=128), i_multi=PostingsFormat(name=LuceneFixedGap), idf_d=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), b_ls=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), checkpoint_ss=Lucene50(blocksize=128), test_t=Lucene50(blocksize=128), ident_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), order_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), error_d=PostingsFormat(name=LuceneFixedGap), truePositive_i=PostingsFormat(name=LuceneFixedGap), side_s=Lucene50(blocksize=128), miles_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), field_s=FST50, price_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a1_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), join1_i=PostingsFormat(name=LuceneFixedGap), join3_i=Lucene50(blocksize=128), test_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), falsePositive_i=FST50, field_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), s_multi=Lucene50(blocksize=128), col_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), level2_s=FST50, a_ss=FST50, score_f=Lucene50(blocksize=128), tv_text=FST50, term_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text_s=PostingsFormat(name=LuceneFixedGap), timestamp=FST50, idfs_ds=FST50, weights_ds=Lucene50(blocksize=128), featureSet_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), trueNegative_i=Lucene50(blocksize=128), index_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), test_dt=PostingsFormat(name=LuceneFixedGap), out_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), whitetok=Lucene50(blocksize=128), _version_=FST50}, docValues:{name_s=DocValuesFormat(name=Asserting), expr_s=DocValuesFormat(name=Lucene70), iteration_i=DocValuesFormat(name=Lucene70), terms_ss=DocValuesFormat(name=Lucene70), join2_s=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Memory), falseNegative_i=DocValuesFormat(name=Memory), a_f=DocValuesFormat(name=Lucene70), a2_s=DocValuesFormat(name=Asserting), a_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Lucene70), a_s=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Lucene70), alpha_d=DocValuesFormat(name=Asserting), i_multi=DocValuesFormat(name=Lucene70), idf_d=DocValuesFormat(name=Lucene70), b_ls=DocValuesFormat(name=Lucene70), checkpoint_ss=DocValuesFormat(name=Asserting), ident_s=DocValuesFormat(name=Lucene70), order_i=DocValuesFormat(name=Lucene70), error_d=DocValuesFormat(name=Lucene70), truePositive_i=DocValuesFormat(name=Lucene70), side_s=DocValuesFormat(name=Asserting), miles_i=DocValuesFormat(name=Lucene70), field_s=DocValuesFormat(name=Memory), price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70), join1_i=DocValuesFormat(name=Lucene70), join3_i=DocValuesFormat(name=Asserting), test_i=DocValuesFormat(name=Lucene70), falsePositive_i=DocValuesFormat(name=Memory), field_i=DocValuesFormat(name=Lucene70), s_multi=DocValuesFormat(name=Asserting), col_s=DocValuesFormat(name=Lucene70), level2_s=DocValuesFormat(name=Memory), a_ss=DocValuesFormat(name=Memory), score_f=DocValuesFormat(name=Asserting), term_s=DocValuesFormat(name=Lucene70), text_s=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Memory), idfs_ds=DocValuesFormat(name=Memory), weights_ds=DocValuesFormat(name=Asserting), featureSet_s=DocValuesFormat(name=Lucene70), trueNegative_i=DocValuesFormat(name=Asserting), index_i=DocValuesFormat(name=Lucene70), test_dt=DocValuesFormat(name=Lucene70), out_i=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=492, maxMBSortInHeap=7.157925955001248, sim=RandomSimilarity(queryNorm=false): {}, locale=lt, timezone=America/Argentina/Ushuaia
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=3,threads=1,free=172315560,total=283701248
[junit4] 2> NOTE: All tests run in this JVM: [OrEvaluatorTest, TestSuggesterResponse, MergeIndexesEmbeddedTest, NormalizeEvaluatorTest, ArcCosineEvaluatorTest, ConcurrentUpdateSolrClientTest, LargeVolumeEmbeddedTest, NaturalLogEvaluatorTest, OperationsTest, StreamExpressionParserTest, CommonParamsTest, DocumentAnalysisResponseTest, SolrExceptionTest, HttpSolrClientConPoolTest, SolrExampleBinaryTest, UniformDistributionEvaluatorTest, LBHttpSolrClientBuilderTest, TestConfigSetAdminRequest, CoalesceEvaluatorTest, StreamExpressionToExplanationTest, SolrSchemalessExampleTest, FieldValueEvaluatorTest, SolrExampleJettyTest, ArcTangentEvaluatorTest, ContentStreamTest, SolrParamTest, SolrPingTest, CubedRootEvaluatorTest, JdbcDriverTest, SolrExampleStreamingBinaryTest, CorrelationEvaluatorTest, HyperbolicCosineEvaluatorTest, JDBCStreamTest, CloudSolrClientTest, AbsoluteValueEvaluatorTest, LargeVolumeBinaryJettyTest, JettyWebappTest, TestSpellCheckResponse, LargeVolumeJettyTest, QueryResponseTest, TestNamedListCodec, ModifiableSolrParamsTest, FieldAnalysisResponseTest, NamedListTest, FacetFieldTest, CollectionAdminRequestRequiredParamsTest, TestSolrJErrorHandling, GraphExpressionTest, StreamExpressionTest]
[junit4] Completed [146/146 (1!)] on J1 in 124.68s, 156 tests, 1 failure, 2 skipped <<< FAILURES!
[...truncated 48649 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/485/consoleText
[repro] Revision: 1f994c97301fbe8926115925102c78a8a133e26b
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] Repro line: ant test -Dtestcase=StreamExpressionTest -Dtests.method=testGammaDistribution -Dtests.seed=55FC5857EAED224A -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=America/Argentina/Ushuaia -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/solrj
[repro] StreamExpressionTest
[repro] ant compile-test
[...truncated 2479 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.StreamExpressionTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=55FC5857EAED224A -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=America/Argentina/Ushuaia -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 843 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
[repro] Exiting with code 0
[...truncated 70 lines...]