You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/08/09 08:39:55 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+178) - Build
# 20286 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20286/
Java: 32bit/jdk-9-ea+178 -client -XX:+UseSerialGC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testExecutorStream
Error Message:
Error from server at https://127.0.0.1:46513/solr/workQueue_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/workQueue_shard2_replica_n3/update. Reason: <pre> Can not find: /solr/workQueue_shard2_replica_n3/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at https://127.0.0.1:46513/solr/workQueue_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/workQueue_shard2_replica_n3/update. Reason:
<pre> Can not find: /solr/workQueue_shard2_replica_n3/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([A4B1321301176E87:8671B3E8227D4497]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:539)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:993)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testExecutorStream(StreamExpressionTest.java:6798)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:46513/solr/workQueue_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/workQueue_shard2_replica_n3/update. Reason:
<pre> Can not find: /solr/workQueue_shard2_replica_n3/update</pre></p>
<hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
</body>
</html>
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:591)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:516)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
... 1 more
Build Log:
[...truncated 14078 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
[junit4] 2> 93477 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/init-core-data-001
[junit4] 2> 93478 WARN (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21
[junit4] 2> 93478 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 93479 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 93479 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001
[junit4] 2> 93481 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 93481 INFO (Thread-305) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 93481 INFO (Thread-305) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 93483 ERROR (Thread-305) [ ] 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> 93581 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:39723
[junit4] 2> 93588 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 93588 INFO (jetty-launcher-231-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 93588 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 93589 INFO (jetty-launcher-231-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 93589 INFO (jetty-launcher-231-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@167f7f4{/solr,null,AVAILABLE}
[junit4] 2> 93589 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@efb890{/solr,null,AVAILABLE}
[junit4] 2> 93589 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b3ca87{/solr,null,AVAILABLE}
[junit4] 2> 93591 INFO (jetty-launcher-231-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3a5d0{SSL,[ssl, http/1.1]}{127.0.0.1:45323}
[junit4] 2> 93591 INFO (jetty-launcher-231-thread-3) [ ] o.e.j.s.Server Started @95075ms
[junit4] 2> 93591 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45323}
[junit4] 2> 93591 ERROR (jetty-launcher-231-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 93591 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 93591 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 93591 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 93592 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-09T08:32:00.262005Z
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f6f3cb{/solr,null,AVAILABLE}
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@12a69d9{SSL,[ssl, http/1.1]}{127.0.0.1:46161}
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-1) [ ] o.e.j.s.Server Started @95078ms
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46161}
[junit4] 2> 93594 ERROR (jetty-launcher-231-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14db836{SSL,[ssl, http/1.1]}{127.0.0.1:38031}
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-4) [ ] o.e.j.s.Server Started @95078ms
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 93594 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38031}
[junit4] 2> 93595 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-09T08:32:00.264995Z
[junit4] 2> 93595 ERROR (jetty-launcher-231-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 93595 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 93595 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 93595 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 93595 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-09T08:32:00.265283Z
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16a418a{SSL,[ssl, http/1.1]}{127.0.0.1:46513}
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.e.j.s.Server Started @95082ms
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46513}
[junit4] 2> 93599 ERROR (jetty-launcher-231-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 93599 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-09T08:32:00.269653Z
[junit4] 2> 93604 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 93604 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 93604 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 93616 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 93618 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39723/solr
[junit4] 2> 93621 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39723/solr
[junit4] 2> 93621 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39723/solr
[junit4] 2> 93630 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39723/solr
[junit4] 2> 93641 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15dc61f3499000b, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 93657 WARN (jetty-launcher-231-thread-1) [ ] 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> 93657 WARN (jetty-launcher-231-thread-3) [ ] 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> 93657 WARN (jetty-launcher-231-thread-4) [ ] 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> 93657 WARN (jetty-launcher-231-thread-2) [ ] 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> 93662 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 93662 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 93662 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46161_solr
[junit4] 2> 93662 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45323_solr
[junit4] 2> 93663 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98452604200747018-127.0.0.1:46161_solr-n_0000000000) starting
[junit4] 2> 93663 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 93666 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 93666 INFO (zkCallback-253-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 93666 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 93667 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 93668 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 93669 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38031_solr
[junit4] 2> 93669 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46513_solr
[junit4] 2> 93670 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 93670 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 93670 INFO (zkCallback-253-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 93670 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 93670 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 93671 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 93681 INFO (zkCallback-253-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 93704 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46161_solr
[junit4] 2> 93705 INFO (zkCallback-253-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 93705 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 93705 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 93705 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 93761 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46513.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93765 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46513.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93765 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46513.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93766 INFO (jetty-launcher-231-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node2/.
[junit4] 2> 93785 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38031.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93798 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38031.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93798 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38031.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93799 INFO (jetty-launcher-231-thread-4) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node4/.
[junit4] 2> 93805 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45323.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93816 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45323.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93816 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45323.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93817 INFO (jetty-launcher-231-thread-3) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node3/.
[junit4] 2> 93851 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46161.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93853 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46161.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93853 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46161.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 93854 INFO (jetty-launcher-231-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node1/.
[junit4] 2> 93874 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 93874 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 93919 INFO (qtp20950354-1283) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 93923 INFO (OverseerThreadFactory-500-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection collection1_collection
[junit4] 2> 94027 INFO (OverseerStateUpdate-98452604200747018-127.0.0.1:46161_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46513/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 94029 INFO (OverseerStateUpdate-98452604200747018-127.0.0.1:46161_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_collection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:38031/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 94235 INFO (qtp20950354-1284) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 94235 INFO (qtp20950354-1284) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 94266 INFO (qtp19097906-1257) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 94267 INFO (qtp19097906-1257) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 94370 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 94370 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 94370 INFO (zkCallback-251-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 94370 INFO (zkCallback-248-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95251 INFO (qtp20950354-1284) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 95257 INFO (qtp20950354-1284) [ ] o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n2] Schema name=test
[junit4] 2> 95274 INFO (qtp19097906-1257) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 95287 INFO (qtp19097906-1257) [ ] o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 95302 WARN (qtp20950354-1284) [ ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 95302 WARN (qtp20950354-1284) [ ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 95302 INFO (qtp20950354-1284) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 95302 INFO (qtp20950354-1284) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard2_replica_n2' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 95303 INFO (qtp20950354-1284) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38031.solr.core.collection1_collection.shard2.replica_n2' (registry 'solr.core.collection1_collection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 95303 INFO (qtp20950354-1284) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 95303 INFO (qtp20950354-1284) [ ] o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node4/collection1_collection_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node4/./collection1_collection_shard2_replica_n2/data/]
[junit4] 2> 95348 WARN (qtp19097906-1257) [ ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 95348 WARN (qtp19097906-1257) [ ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 95348 INFO (qtp19097906-1257) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 95348 INFO (qtp19097906-1257) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard1_replica_n1' using configuration from collection collection1_collection, trusted=true
[junit4] 2> 95349 INFO (qtp19097906-1257) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46513.solr.core.collection1_collection.shard1.replica_n1' (registry 'solr.core.collection1_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fc3e8d
[junit4] 2> 95349 INFO (qtp19097906-1257) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 95349 INFO (qtp19097906-1257) [ ] o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node2/collection1_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001/tempDir-001/node2/./collection1_collection_shard1_replica_n1/data/]
[junit4] 2> 95353 INFO (qtp20950354-1284) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 95353 INFO (qtp20950354-1284) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 95354 INFO (qtp20950354-1284) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 95354 INFO (qtp20950354-1284) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 95355 INFO (qtp20950354-1284) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ca86[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 95356 INFO (qtp20950354-1284) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 95356 INFO (qtp20950354-1284) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 95357 INFO (qtp20950354-1284) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 95362 INFO (searcherExecutor-509-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@3ca86[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 95362 INFO (qtp20950354-1284) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575241669182226432
[junit4] 2> 95367 INFO (qtp20950354-1284) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 95367 INFO (qtp20950354-1284) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 95367 INFO (qtp20950354-1284) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/
[junit4] 2> 95367 INFO (qtp20950354-1284) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 95367 INFO (qtp20950354-1284) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/ has no replicas
[junit4] 2> 95367 INFO (qtp20950354-1284) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 95369 INFO (qtp20950354-1284) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/ shard2
[junit4] 2> 95399 INFO (qtp19097906-1257) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 95399 INFO (qtp19097906-1257) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 95400 INFO (qtp19097906-1257) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 95400 INFO (qtp19097906-1257) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 95401 INFO (qtp19097906-1257) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@480d55[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 95402 INFO (qtp19097906-1257) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 95402 INFO (qtp19097906-1257) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 95402 INFO (qtp19097906-1257) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 95403 INFO (qtp19097906-1257) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1575241669225218048
[junit4] 2> 95404 INFO (searcherExecutor-511-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@480d55[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 95406 INFO (qtp19097906-1257) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 95406 INFO (qtp19097906-1257) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 95406 INFO (qtp19097906-1257) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/
[junit4] 2> 95406 INFO (qtp19097906-1257) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 95406 INFO (qtp19097906-1257) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 95406 INFO (qtp19097906-1257) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 95407 INFO (qtp19097906-1257) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/ shard1
[junit4] 2> 95509 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95509 INFO (zkCallback-251-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95509 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95509 INFO (zkCallback-248-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95519 INFO (qtp20950354-1284) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 95520 INFO (qtp20950354-1284) [ ] 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_collection_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1285
[junit4] 2> 95558 INFO (qtp19097906-1257) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 95559 INFO (qtp19097906-1257) [ ] 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_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1292
[junit4] 2> 95561 INFO (qtp20950354-1283) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 95660 INFO (zkCallback-248-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95660 INFO (zkCallback-251-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95660 INFO (zkCallback-251-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95660 INFO (zkCallback-248-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 95924 INFO (OverseerCollectionConfigSetProcessor-98452604200747018-127.0.0.1:46161_solr-n_0000000000) [ ] 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> 96561 INFO (qtp20950354-1283) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2641
[junit4] 2> 96562 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1_collection failOnTimeout:true timeout (sec):90
[junit4] 2> 96563 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1_collection
[junit4] 2> 96564 INFO (qtp20950354-1288) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 96667 INFO (qtp20950354-1288) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=103
[junit4] 2> 96682 INFO (TEST-StreamExpressionTest.testSqlStream-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSqlStream
[junit4] 2> 96696 INFO (qtp20950354-1288) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1575241670577881088)} 0 3
[junit4] 2> 96699 INFO (qtp20950354-1285) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241670584172544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 96699 INFO (qtp20950354-1285) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 96699 INFO (qtp20950354-1285) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 96699 INFO (qtp20950354-1285) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 96703 INFO (qtp19097906-1263) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241670588366848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 96704 INFO (qtp19097906-1263) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 96704 INFO (qtp19097906-1263) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 96704 INFO (qtp19097906-1263) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 96705 INFO (qtp19097906-1276) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575241670572638208),commit=} 0 17
[junit4] 2> 96709 INFO (qtp19097906-1260) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[0 (1575241670592561152), 4 (1575241670594658304), 1 (1575241670594658305)]} 0 2
[junit4] 2> 96712 INFO (qtp20950354-1290) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[2 (1575241670595706880), 3 (1575241670597804032)]} 0 1
[junit4] 2> 96720 INFO (qtp20950354-1290) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241670606192640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 96720 INFO (qtp20950354-1290) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@526f72 commitCommandVersion:1575241670606192640
[junit4] 2> 96721 INFO (qtp19097906-1266) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241670607241216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 96722 INFO (qtp19097906-1266) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f1b8f commitCommandVersion:1575241670607241216
[junit4] 2> 96732 INFO (qtp19097906-1266) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14dc61f[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 96732 INFO (qtp20950354-1290) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1518168[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 96732 INFO (qtp19097906-1266) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 96733 INFO (qtp20950354-1290) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 96733 INFO (searcherExecutor-509-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@1518168[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 96733 INFO (qtp20950354-1290) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 13
[junit4] 2> 96735 INFO (searcherExecutor-511-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@14dc61f[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C3)))}
[junit4] 2> 96735 INFO (qtp19097906-1266) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 14
[junit4] 2> 96736 INFO (qtp20950354-1283) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 23
[junit4] 2> 96741 INFO (TEST-StreamExpressionTest.testSqlStream-seed#[A4B1321301176E87]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 96742 INFO (TEST-StreamExpressionTest.testSqlStream-seed#[A4B1321301176E87]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 96754 INFO (qtp20950354-1283) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=sql(collection1,+stmt%3D"select+id+from+collection1+order+by+a_i+asc")&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 96756 INFO (qtp20950354-1283) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 96757 INFO (qtp20950354-1283) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 96763 INFO (qtp20950354-1290) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/sql params={indent=off&wt=json&version=2.2&stmt=select+id+from+collection1+order+by+a_i+asc} status=0 QTime=1
[junit4] 2> 97239 INFO (qtp20950354-1290) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 97240 INFO (qtp20950354-1290) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 97252 INFO (qtp20950354-1290) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 97252 INFO (qtp20950354-1290) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 97257 INFO (qtp20950354-1290) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 97258 INFO (qtp20950354-1290) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 97263 INFO (qtp19097906-1257) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/admin/luke params={numTerms=0&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 97317 INFO (qtp20950354-1290) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 97318 INFO (qtp20950354-1290) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 97323 INFO (qtp20950354-1290) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 97324 INFO (qtp20950354-1290) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 97328 INFO (qtp20950354-1284) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/admin/luke params={numTerms=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 97837 INFO (qtp20950354-1290) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 97837 INFO (qtp20950354-1290) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 97851 INFO (qtp20950354-1407) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/export params={q=*:*&distrib=false&indent=off&fl=id,a_i&sort=a_i+asc&wt=json&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 97859 INFO (qtp19097906-1260) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/export params={q=*:*&distrib=false&indent=off&fl=id,a_i&sort=a_i+asc&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 97874 INFO (TEST-StreamExpressionTest.testSqlStream-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSqlStream
[junit4] 2> 97896 INFO (TEST-StreamExpressionTest.testAnalyzeEvaluator-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAnalyzeEvaluator
[junit4] 2> 97902 INFO (qtp20950354-1284) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@191daf9[collection1_collection_shard2_replica_n2] realtime]
[junit4] 2> 97903 INFO (qtp19097906-1266) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@167cfb0[collection1_collection_shard1_replica_n1] realtime]
[junit4] 2> 97903 INFO (qtp20950354-1284) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1575241671840366592)} 0 6
[junit4] 2> 97905 INFO (qtp19097906-1273) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241671848755200,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 97905 INFO (qtp19097906-1273) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f1b8f commitCommandVersion:1575241671848755200
[junit4] 2> 97906 INFO (qtp19097906-1273) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@83e846[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 97906 INFO (qtp19097906-1273) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 97907 INFO (searcherExecutor-511-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@83e846[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 97907 INFO (qtp19097906-1273) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 97923 INFO (qtp20950354-1286) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241671867629568,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 97923 INFO (qtp20950354-1286) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@526f72 commitCommandVersion:1575241671867629568
[junit4] 2> 97923 INFO (qtp20950354-1286) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@217e0e[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 97924 INFO (qtp20950354-1286) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 97925 INFO (searcherExecutor-509-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@217e0e[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 97925 INFO (qtp20950354-1286) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 97926 INFO (qtp19097906-1266) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575241671844560896),commit=} 0 29
[junit4] 2> 97930 INFO (qtp19097906-1260) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[1 (1575241671871823872)]} 0 3
[junit4] 2> 97933 INFO (qtp20950354-1283) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241671878115328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 97933 INFO (qtp20950354-1283) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 97933 INFO (qtp19097906-1257) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241671878115328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 97933 INFO (qtp19097906-1257) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f1b8f commitCommandVersion:1575241671878115328
[junit4] 2> 97934 INFO (qtp20950354-1283) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 97934 INFO (qtp20950354-1283) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 97938 INFO (qtp19097906-1257) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16e6d39[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 97938 INFO (qtp19097906-1257) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 97939 INFO (searcherExecutor-511-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@16e6d39[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1)))}
[junit4] 2> 97940 INFO (qtp19097906-1257) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6
[junit4] 2> 97940 INFO (qtp19097906-1276) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 97946 INFO (qtp19097906-1276) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=cartesianProduct(search(collection1,+q%3D"*:*",+fl%3D"id,+test_t",+sort%3D"id+desc"),+analyze(test_t,+test_t)+as+test_t)&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 97953 INFO (qtp19097906-1257) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,+test_t&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 97954 INFO (qtp20950354-1284) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,+test_t&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 97964 INFO (qtp19097906-1276) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=analyze("hello+world",+test_t)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 97971 INFO (qtp19097906-1263) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=cartesianProduct(search(collection1,+q%3D"*:*",+fl%3D"id,+test_t",+sort%3D"id+desc"),+analyze(test_t)+as+test_t)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 97976 INFO (qtp20950354-1286) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,+test_t&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 97977 INFO (qtp19097906-1273) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,+test_t&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 97986 INFO (qtp19097906-1263) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=cartesianProduct(search(collection1,+q%3D"*:*",+fl%3D"id",+sort%3D"id+desc"),+analyze(test_t,+test_t)+as+test_t)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 97989 INFO (qtp19097906-1421) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 97991 INFO (qtp20950354-1407) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 98003 INFO (qtp19097906-1421) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=select(search(collection1,+q%3D"*:*",+fl%3D"id,+test_t",+sort%3D"id+desc"),+analyze(test_t,+test_t)+as+test1_t)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98006 INFO (qtp20950354-1407) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,+test_t&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 98010 INFO (qtp19097906-1276) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,+test_t&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 98012 INFO (TEST-StreamExpressionTest.testAnalyzeEvaluator-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAnalyzeEvaluator
[junit4] 2> 98040 INFO (TEST-StreamExpressionTest.testListStream-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListStream
[junit4] 2> 98042 INFO (qtp19097906-1422) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dccc31[collection1_collection_shard1_replica_n1] realtime]
[junit4] 2> 98043 INFO (qtp20950354-1290) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1575241671991361536)} 0 1
[junit4] 2> 98044 INFO (qtp19097906-1421) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241671994507264,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98044 INFO (qtp19097906-1421) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f1b8f commitCommandVersion:1575241671994507264
[junit4] 2> 98045 INFO (qtp19097906-1421) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a96550[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 98045 INFO (qtp20950354-1285) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241671995555840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98046 INFO (qtp20950354-1285) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98046 INFO (qtp19097906-1421) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98046 INFO (qtp20950354-1285) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98046 INFO (qtp20950354-1285) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 98047 INFO (searcherExecutor-511-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@a96550[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 98047 INFO (qtp19097906-1421) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 98047 INFO (qtp19097906-1422) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575241671991361536),commit=} 0 6
[junit4] 2> 98050 INFO (qtp20950354-1288) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[hello (1575241671998701568), hello1 (1575241672000798720), hello2 (1575241672000798721)]} 0 2
[junit4] 2> 98053 INFO (qtp19097906-1260) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672003944448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98053 INFO (qtp19097906-1260) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98053 INFO (qtp19097906-1260) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98053 INFO (qtp19097906-1260) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 98055 INFO (qtp20950354-1406) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672006041600,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98055 INFO (qtp20950354-1406) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@526f72 commitCommandVersion:1575241672006041600
[junit4] 2> 98064 INFO (qtp20950354-1406) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16a312[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 98064 INFO (qtp20950354-1406) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98065 INFO (searcherExecutor-509-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@16a312[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C3)))}
[junit4] 2> 98065 INFO (qtp20950354-1406) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 10
[junit4] 2> 98066 INFO (qtp20950354-1286) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 14
[junit4] 2> 98072 INFO (qtp19097906-1273) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=list(search(collection1,+q%3D"id:hello",++fl%3Did,+sort%3D"id+desc"),search(collection1,+q%3D"id:hello1",+fl%3Did,+sort%3D"id+desc"),search(collection1,+q%3D"id:hello2",+fl%3Did,+sort%3D"id+desc"))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98076 INFO (qtp19097906-1421) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=id:hello&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 98076 INFO (qtp20950354-1406) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=id:hello&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 98083 INFO (qtp20950354-1288) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=id:hello1&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 98086 INFO (qtp19097906-1263) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=id:hello1&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 98090 INFO (qtp20950354-1285) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=id:hello2&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 98091 INFO (qtp19097906-1257) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=id:hello2&distrib=false&fl=id&sort=id+desc&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 98095 INFO (TEST-StreamExpressionTest.testListStream-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListStream
[junit4] 2> 98121 INFO (TEST-StreamExpressionTest.testAnova-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAnova
[junit4] 2> 98124 INFO (qtp20950354-1407) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@504c1d[collection1_collection_shard2_replica_n2] realtime]
[junit4] 2> 98125 INFO (qtp20950354-1407) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1575241672077344768)} 0 1
[junit4] 2> 98131 INFO (qtp20950354-1290) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672085733376,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98131 INFO (qtp20950354-1290) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@526f72 commitCommandVersion:1575241672085733376
[junit4] 2> 98132 INFO (qtp19097906-1257) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672086781952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98132 INFO (qtp19097906-1257) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98133 INFO (qtp20950354-1290) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f2b3d9[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 98133 INFO (qtp20950354-1290) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98133 INFO (qtp19097906-1257) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98133 INFO (qtp19097906-1257) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 98134 INFO (searcherExecutor-509-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@f2b3d9[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 98134 INFO (qtp20950354-1290) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 98136 INFO (qtp19097906-1263) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575241672076296192),commit=} 0 14
[junit4] 2> 98141 INFO (qtp19097906-1266) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=anova(array(1,2,3,5,4,6),+array(5,2,3,5,4,6),+array(1,2,7,5,4,6))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98171 INFO (TEST-StreamExpressionTest.testAnova-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAnova
[junit4] 2> 98192 INFO (TEST-StreamExpressionTest.testHist-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testHist
[junit4] 2> 98195 INFO (qtp19097906-1260) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1575241672151793664)} 0 1
[junit4] 2> 98197 INFO (qtp19097906-1273) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672154939392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98197 INFO (qtp19097906-1273) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98197 INFO (qtp19097906-1273) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98197 INFO (qtp19097906-1273) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 98197 INFO (qtp20950354-1408) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672154939392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98197 INFO (qtp20950354-1408) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98197 INFO (qtp20950354-1408) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98197 INFO (qtp20950354-1408) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 98198 INFO (qtp20950354-1283) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575241672150745088),commit=} 0 4
[junit4] 2> 98203 INFO (qtp19097906-1263) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=hist(sequence(100,+0,+1),+10)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98214 INFO (qtp19097906-1266) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=hist(sequence(100,+0,+1),+5)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98215 INFO (TEST-StreamExpressionTest.testHist-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testHist
[junit4] 2> 98242 INFO (TEST-StreamExpressionTest.testParallelFetchStream-seed#[A4B1321301176E87]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testParallelFetchStream
[junit4] 2> 98248 INFO (qtp19097906-1272) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1575241672205271040)} 0 2
[junit4] 2> 98249 INFO (qtp20950354-1406) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672209465344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98249 INFO (qtp20950354-1406) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98249 INFO (qtp20950354-1406) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98250 INFO (qtp20950354-1406) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 98250 INFO (qtp19097906-1260) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672210513920,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98250 INFO (qtp19097906-1260) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 98250 INFO (qtp19097906-1260) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98250 INFO (qtp19097906-1260) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38031/solr/collection1_collection_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 98251 INFO (qtp20950354-1288) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1575241672203173888),commit=} 0 7
[junit4] 2> 98266 INFO (qtp19097906-1276) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[0 (1575241672212611072), 4 (1575241672226242560), 1 (1575241672226242561), 8 (1575241672226242562)]} 0 14
[junit4] 2> 98266 INFO (qtp20950354-1284) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2}{add=[2 (1575241672212611072), 3 (1575241672226242560), 5 (1575241672226242561), 6 (1575241672226242562), 7 (1575241672226242563), 9 (1575241672227291136)]} 0 14
[junit4] 2> 98267 INFO (qtp20950354-1286) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672228339712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98268 INFO (qtp20950354-1286) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@526f72 commitCommandVersion:1575241672228339712
[junit4] 2> 98268 INFO (qtp19097906-1422) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1575241672229388288,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 98268 INFO (qtp19097906-1422) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6f1b8f commitCommandVersion:1575241672229388288
[junit4] 2> 98273 INFO (qtp20950354-1286) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6902b2[collection1_collection_shard2_replica_n2] main]
[junit4] 2> 98273 INFO (qtp20950354-1286) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98274 INFO (qtp19097906-1422) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@d02b13[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 98274 INFO (searcherExecutor-509-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new searcher Searcher@6902b2[collection1_collection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C6)))}
[junit4] 2> 98274 INFO (qtp19097906-1422) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 98275 INFO (searcherExecutor-511-thread-1) [ ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@d02b13[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C4)))}
[junit4] 2> 98275 INFO (qtp20950354-1286) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 7
[junit4] 2> 98275 INFO (qtp19097906-1422) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46513/solr/collection1_collection_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6
[junit4] 2> 98275 INFO (qtp19097906-1266) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 98278 INFO (TEST-StreamExpressionTest.testParallelFetchStream-seed#[A4B1321301176E87]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 98279 INFO (TEST-StreamExpressionTest.testParallelFetchStream-seed#[A4B1321301176E87]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39723/solr ready
[junit4] 2> 98288 INFO (qtp20950354-1285) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/stream params={distrib=false&workerID=0&indent=off&numWorkers=2&expr=fetch(collection1,on%3D"id%3Da_i",fl%3Dsubject,batchSize%3D2,search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:39723/solr"))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98291 INFO (qtp19097906-1263) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={distrib=false&workerID=1&indent=off&numWorkers=2&expr=fetch(collection1,on%3D"id%3Da_i",fl%3Dsubject,batchSize%3D2,search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:39723/solr"))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 98301 INFO (qtp20950354-1286) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2} hits=2 status=0 QTime=2
[junit4] 2> 98303 INFO (qtp19097906-1273) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 98305 INFO (qtp20950354-1290) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=4 status=0 QTime=0
[junit4] 2> 98315 INFO (qtp19097906-1273) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+0+2&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 98315 INFO (qtp20950354-1284) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+0+2&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 98317 INFO (qtp19097906-1421) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 98326 INFO (qtp20950354-1406) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+3+1&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=1 status=0 QTime=2
[junit4] 2> 98326 INFO (qtp20950354-1288) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+4+6&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=1 status=0 QTime=2
[junit4] 2> 98326 INFO (qtp19097906-1276) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+3+1&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 98327 INFO (qtp19097906-1273) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+4+6&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 98334 INFO (qtp20950354-1406) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+5+7&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=2 status=0 QTime=0
[junit4] 2> 98337 INFO (qtp19097906-1421) [ ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+5+7&distrib=false&fl=subject,a_i,_version_&sort=_version_+desc&rows=2&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 98342 INFO (qtp20950354-1290) [ ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] webapp=/solr path=/select params={q={!+df%3Da_i+q.op%3DOR+cache%3Dfalse+}+8&distrib=false&fl=subject,a_i,_version_&sort=_version_+des
[...truncated too long message...]
orters for registry=solr.core.mainCorpus.shard2.replica_n2, tag=20884912
[junit4] 2> 162330 INFO (jetty-closer-232-thread-4) [ ] o.a.s.c.Overseer Overseer (id=98452604200747018-127.0.0.1:46161_solr-n_0000000000) closing
[junit4] 2> 162330 INFO (OverseerStateUpdate-98452604200747018-127.0.0.1:46161_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:46161_solr
[junit4] 2> 162330 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15dc61f3499003d, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 162331 INFO (coreCloseExecutor-639-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.mainCorpus.shard1.replica_n1, tag=30531263
[junit4] 2> 162331 INFO (coreCloseExecutor-642-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.mainCorpus.shard2.leader, tag=20884912
[junit4] 2> 162331 INFO (coreCloseExecutor-642-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.workQueue.shard2.replica_n2, tag=24066042
[junit4] 2> 162331 INFO (coreCloseExecutor-640-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1_collection.shard1.replica_n1, tag=2759201
[junit4] 2> 162334 INFO (coreCloseExecutor-640-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1_collection.shard1.leader, tag=2759201
[junit4] 2> 162334 INFO (coreCloseExecutor-639-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.destination.shard1.leader, tag=11145979
[junit4] 2> 162334 INFO (coreCloseExecutor-639-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.mainCorpus.shard1.leader, tag=30531263
[junit4] 2> 162335 INFO (coreCloseExecutor-642-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.destination.shard2.replica_n2, tag=13058145
[junit4] 2> 162335 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 162335 INFO (zkCallback-253-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 162336 INFO (zkCallback-251-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 162336 INFO (zkCallback-253-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 162336 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 162337 INFO (coreCloseExecutor-642-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.workQueue.shard2.leader, tag=24066042
[junit4] 2> 162337 INFO (coreCloseExecutor-642-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.destination.shard2.leader, tag=13058145
[junit4] 2> 162337 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 163835 WARN (zkCallback-248-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 163836 INFO (jetty-closer-232-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@b3ca87{/solr,null,UNAVAILABLE}
[junit4] 2> 163836 WARN (zkCallback-251-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 163836 INFO (jetty-closer-232-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@f6f3cb{/solr,null,UNAVAILABLE}
[junit4] 2> 163837 WARN (zkCallback-253-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 163838 INFO (jetty-closer-232-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@167f7f4{/solr,null,UNAVAILABLE}
[junit4] 2> 163838 WARN (zkCallback-247-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 163838 INFO (jetty-closer-232-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@efb890{/solr,null,UNAVAILABLE}
[junit4] 2> 163839 ERROR (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] 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> 163839 INFO (SUITE-StreamExpressionTest-seed#[A4B1321301176E87]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39723 39723
[junit4] 2> 168843 INFO (Thread-305) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39723 39723
[junit4] 2> 168844 WARN (Thread-305) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 72 /solr/aliases.json
[junit4] 2> 11 /solr/configs/conf
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 72 /solr/clusterprops.json
[junit4] 2> 72 /solr/clusterstate.json
[junit4] 2> 22 /solr/collections/destination/state.json
[junit4] 2> 20 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 20 /solr/collections/destinationCollection/state.json
[junit4] 2> 20 /solr/collections/mainCorpus/state.json
[junit4] 2> 18 /solr/collections/workQueue/state.json
[junit4] 2> 18 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 10 /solr/collections/collection1_collection/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 72 /solr/live_nodes
[junit4] 2> 72 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_A4B1321301176E87-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {expr_s=Lucene50(blocksize=128), join2_s=PostingsFormat(name=Memory), multiDefault=PostingsFormat(name=Memory), subject=PostingsFormat(name=Memory), a1_s=PostingsFormat(name=Memory), a2_s=PostingsFormat(name=LuceneVarGapFixedInterval), test_t=PostingsFormat(name=LuceneVarGapFixedInterval), level1_s=Lucene50(blocksize=128), body_t=PostingsFormat(name=LuceneVarGapFixedInterval), ident_s=PostingsFormat(name=Memory), s_multi=PostingsFormat(name=LuceneVarGapFixedInterval), a_ss=PostingsFormat(name=Direct), col_s=PostingsFormat(name=Memory), level2_s=PostingsFormat(name=Direct), a_s=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=PostingsFormat(name=Direct), side_s=PostingsFormat(name=LuceneVarGapFixedInterval), id=Lucene50(blocksize=128), text=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{expr_s=DocValuesFormat(name=Asserting), join2_s=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70), a_f=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Direct), join1_i=DocValuesFormat(name=Asserting), a2_s=DocValuesFormat(name=Lucene70), a_i=DocValuesFormat(name=Asserting), join3_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Lucene70), field_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Asserting), s_multi=DocValuesFormat(name=Lucene70), a_ss=DocValuesFormat(name=Direct), col_s=DocValuesFormat(name=Lucene70), level2_s=DocValuesFormat(name=Direct), a_s=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Direct), i_multi=DocValuesFormat(name=Asserting), b_ls=DocValuesFormat(name=Lucene70), test_dt=DocValuesFormat(name=Asserting), ident_s=DocValuesFormat(name=Lucene70), order_i=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Direct), side_s=DocValuesFormat(name=Lucene70), miles_i=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1449, maxMBSortInHeap=5.574548074433832, sim=RandomSimilarity(queryNorm=true): {}, locale=zh-Hant-TW, timezone=America/Argentina/Mendoza
[junit4] 2> NOTE: Linux 4.10.0-27-generic i386/Oracle Corporation 9 (32-bit)/cpus=8,threads=1,free=77178168,total=204701696
[junit4] 2> NOTE: All tests run in this JVM: [TestLBHttpSolrClient, TestNamedListCodec, OperationsTest, TestCollectionStateWatchers, NoOpResponseParserTest, TestEmbeddedSolrServer, OrEvaluatorTest, SolrExampleStreamingTest, StreamExpressionToExpessionTest, TestValidatingJsonMap, GraphTest, GreaterThanEqualToEvaluatorTest, CubedRootEvaluatorTest, HyperbolicSineEvaluatorTest, RoundEvaluatorTest, CommonParamsTest, LargeVolumeEmbeddedTest, SubtractEvaluatorTest, AnlysisResponseBaseTest, TestSpellCheckResponse, ArcCosineEvaluatorTest, BasicHttpSolrClientTest, JDBCStreamTest, StreamExpressionTest]
[junit4] Completed [135/135 (1!)] on J0 in 75.38s, 93 tests, 1 error, 9 skipped <<< FAILURES!
[...truncated 35646 lines...]