Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19872/
Java: 32bit/jdk-9-ea+173 -server -XX:+UseParallelGC

1 tests failed.
FAILED:  
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelCommitStream

Error Message:
expected:<5> but was:<2>

Stack Trace:
java.lang.AssertionError: expected:<5> but was:<2>
        at 
__randomizedtesting.SeedInfo.seed([67FB4D8A1BBE6E3A:47112F8A87FF8376]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelCommitStream(StreamExpressionTest.java:4582)
        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)




Build Log:
[...truncated 14070 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/init-core-data-001
   [junit4]   2> 71173 WARN  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
   [junit4]   2> 71173 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 71173 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 71174 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001
   [junit4]   2> 71174 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 71174 INFO  (Thread-523) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 71174 INFO  (Thread-523) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 71175 ERROR (Thread-523) [    ] 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> 71274 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:41669
   [junit4]   2> 71280 INFO  (jetty-launcher-732-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 71283 INFO  (jetty-launcher-732-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 71284 INFO  (jetty-launcher-732-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 71284 INFO  (jetty-launcher-732-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 71284 INFO  (jetty-launcher-732-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@b049c7{/solr,null,AVAILABLE}
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@65ac29{/solr,null,AVAILABLE}
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@75e85d{HTTP/1.1,[http/1.1]}{127.0.0.1:32839}
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@d9eed5{HTTP/1.1,[http/1.1]}{127.0.0.1:41715}
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-1) [    ] 
o.e.j.s.Server Started @72697ms
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-4) [    ] 
o.e.j.s.Server Started @72697ms
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=32839}
   [junit4]   2> 71285 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41715}
   [junit4]   2> 71286 ERROR (jetty-launcher-732-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 71286 ERROR (jetty-launcher-732-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-15T09:24:18.352335Z
   [junit4]   2> 71286 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-15T09:24:18.352360Z
   [junit4]   2> 71288 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 71288 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 71288 INFO  (jetty-launcher-732-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2c9b10{/solr,null,AVAILABLE}
   [junit4]   2> 71288 INFO  (jetty-launcher-732-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1ccba44{/solr,null,AVAILABLE}
   [junit4]   2> 71289 INFO  (jetty-launcher-732-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@f8b69b{HTTP/1.1,[http/1.1]}{127.0.0.1:43357}
   [junit4]   2> 71289 INFO  (jetty-launcher-732-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1d035e5{HTTP/1.1,[http/1.1]}{127.0.0.1:34131}
   [junit4]   2> 71289 INFO  (jetty-launcher-732-thread-2) [    ] 
o.e.j.s.Server Started @72701ms
   [junit4]   2> 71289 INFO  (jetty-launcher-732-thread-3) [    ] 
o.e.j.s.Server Started @72701ms
   [junit4]   2> 71289 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43357}
   [junit4]   2> 71289 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34131}
   [junit4]   2> 71289 ERROR (jetty-launcher-732-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 71290 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 71290 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 71290 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 71290 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-15T09:24:18.356206Z
   [junit4]   2> 71289 ERROR (jetty-launcher-732-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 71290 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 71291 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 71291 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 71291 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-15T09:24:18.357115Z
   [junit4]   2> 71293 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 0x15cab1152c10002, 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> 71295 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 71295 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 71296 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41669/solr
   [junit4]   2> 71298 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41669/solr
   [junit4]   2> 71307 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41669/solr
   [junit4]   2> 71307 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41669/solr
   [junit4]   2> 71321 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 71321 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 71321 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:32839_solr
   [junit4]   2> 71321 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41715_solr
   [junit4]   2> 71322 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=98141382786482182-127.0.0.1:32839_solr-n_0000000000) starting
   [junit4]   2> 71323 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 0x15cab1152c1000a, 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> 71327 INFO  (zkCallback-745-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 71327 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 71332 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 71333 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 71333 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34131_solr
   [junit4]   2> 71334 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 71339 INFO  (zkCallback-745-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 71339 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:32839_solr
   [junit4]   2> 71340 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 71347 INFO  (zkCallback-755-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 71348 INFO  (zkCallback-755-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 71348 INFO  (zkCallback-745-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 71352 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 71353 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 71354 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43357_solr
   [junit4]   2> 71355 INFO  (zkCallback-756-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 71355 INFO  (zkCallback-755-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 71356 INFO  (zkCallback-745-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 71359 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 71395 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43357.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71397 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43357.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71397 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43357.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71398 INFO  (jetty-launcher-732-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node2/.
   [junit4]   2> 71434 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41715.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71437 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34131.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71437 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41715.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71437 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41715.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71438 INFO  (jetty-launcher-732-thread-4) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node4/.
   [junit4]   2> 71441 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34131.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71441 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34131.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71442 INFO  (jetty-launcher-732-thread-3) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node3/.
   [junit4]   2> 71470 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32839.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71472 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32839.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71473 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32839.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 71473 INFO  (jetty-launcher-732-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node1/.
   [junit4]   2> 71502 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 0x15cab1152c1000d, 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> 71504 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 71504 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41669/solr ready
   [junit4]   2> 71509 INFO  (qtp10962926-2262) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 71510 INFO  (OverseerThreadFactory-654-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Create collection collection1
   [junit4]   2> 71613 INFO  (qtp16239796-2286) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 71613 INFO  (qtp31311506-2274) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 71613 INFO  (qtp16239796-2286) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 71613 INFO  (qtp31311506-2274) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 71716 INFO  (zkCallback-755-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 71716 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 72624 INFO  (qtp31311506-2274) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.0.0
   [junit4]   2> 72624 INFO  (qtp16239796-2286) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.0.0
   [junit4]   2> 72632 INFO  (qtp31311506-2274) [    ] o.a.s.s.IndexSchema 
[collection1_shard1_replica_n1] Schema name=test
   [junit4]   2> 72633 INFO  (qtp16239796-2286) [    ] o.a.s.s.IndexSchema 
[collection1_shard2_replica_n1] Schema name=test
   [junit4]   2> 72795 WARN  (qtp31311506-2274) [    ] o.a.s.s.IndexSchema 
Field lowerfilt1and2 is not multivalued and destination for multiple copyFields 
(2)
   [junit4]   2> 72795 WARN  (qtp31311506-2274) [    ] o.a.s.s.IndexSchema 
Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 72796 INFO  (qtp31311506-2274) [    ] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 72796 INFO  (qtp31311506-2274) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica_n1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 72796 INFO  (qtp31311506-2274) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41715.solr.core.collection1.shard1.replica_n1' (registry 
'solr.core.collection1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 72796 INFO  (qtp31311506-2274) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 72796 INFO  (qtp31311506-2274) [    ] o.a.s.c.SolrCore 
[[collection1_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node4/collection1_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node4/./collection1_shard1_replica_n1/data/]
   [junit4]   2> 72798 WARN  (qtp16239796-2286) [    ] o.a.s.s.IndexSchema 
Field lowerfilt1and2 is not multivalued and destination for multiple copyFields 
(2)
   [junit4]   2> 72798 WARN  (qtp16239796-2286) [    ] o.a.s.s.IndexSchema 
Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 72799 INFO  (qtp16239796-2286) [    ] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 72799 INFO  (qtp16239796-2286) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard2_replica_n1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 72799 INFO  (qtp16239796-2286) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34131.solr.core.collection1.shard2.replica_n1' (registry 
'solr.core.collection1.shard2.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1b09664
   [junit4]   2> 72799 INFO  (qtp16239796-2286) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 72799 INFO  (qtp16239796-2286) [    ] o.a.s.c.SolrCore 
[[collection1_shard2_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node3/collection1_shard2_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001/tempDir-001/node3/./collection1_shard2_replica_n1/data/]
   [junit4]   2> 72849 INFO  (qtp31311506-2274) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 72849 INFO  (qtp31311506-2274) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 72850 INFO  (qtp31311506-2274) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 72850 INFO  (qtp31311506-2274) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 72851 INFO  (qtp31311506-2274) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@11147c[collection1_shard1_replica_n1] main]
   [junit4]   2> 72852 INFO  (qtp31311506-2274) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 72853 INFO  (qtp31311506-2274) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 72853 INFO  (qtp31311506-2274) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 72853 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@11147c[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 72854 INFO  (qtp31311506-2274) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1570262126344273920
   [junit4]   2> 72858 INFO  (qtp31311506-2274) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 72858 INFO  (qtp31311506-2274) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 72858 INFO  (qtp31311506-2274) [    ] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/
   [junit4]   2> 72858 INFO  (qtp31311506-2274) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 72858 INFO  (qtp31311506-2274) [    ] o.a.s.c.SyncStrategy 
http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/ has no replicas
   [junit4]   2> 72858 INFO  (qtp31311506-2274) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 72861 INFO  (qtp31311506-2274) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/ shard1
   [junit4]   2> 72867 INFO  (qtp16239796-2286) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 72867 INFO  (qtp16239796-2286) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 72868 INFO  (qtp16239796-2286) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 72868 INFO  (qtp16239796-2286) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 72870 INFO  (qtp16239796-2286) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@9782c8[collection1_shard2_replica_n1] main]
   [junit4]   2> 72871 INFO  (qtp16239796-2286) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 72871 INFO  (qtp16239796-2286) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 72872 INFO  (qtp16239796-2286) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 72872 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@9782c8[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 72872 INFO  (qtp16239796-2286) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1570262126363148288
   [junit4]   2> 72877 INFO  (qtp16239796-2286) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 72877 INFO  (qtp16239796-2286) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 72877 INFO  (qtp16239796-2286) [    ] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/
   [junit4]   2> 72877 INFO  (qtp16239796-2286) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 72877 INFO  (qtp16239796-2286) [    ] o.a.s.c.SyncStrategy 
http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/ has no replicas
   [junit4]   2> 72877 INFO  (qtp16239796-2286) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 72880 INFO  (qtp16239796-2286) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/ shard2
   [junit4]   2> 72982 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 72982 INFO  (zkCallback-755-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 73012 INFO  (qtp31311506-2274) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 73014 INFO  (qtp31311506-2274) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1400
   [junit4]   2> 73031 INFO  (qtp16239796-2286) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 73033 INFO  (qtp16239796-2286) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1419
   [junit4]   2> 73035 INFO  (qtp10962926-2262) [    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 73134 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 73134 INFO  (zkCallback-755-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 73511 INFO  
(OverseerCollectionConfigSetProcessor-98141382786482182-127.0.0.1:32839_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> 74036 INFO  (qtp10962926-2262) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=2527
   [junit4]   2> 74037 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):90
   [junit4]   2> 74037 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 74062 INFO  
(TEST-StreamExpressionTest.testConvolution-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testConvolution
   [junit4]   2> 74069 INFO  (qtp31311506-2276) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127616196608)} 0 1
   [junit4]   2> 74071 INFO  (qtp31311506-2269) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127620390912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74071 INFO  (qtp31311506-2269) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 74071 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127620390912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74071 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 74072 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74072 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 74075 INFO  (qtp31311506-2269) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74075 INFO  (qtp31311506-2269) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 4
   [junit4]   2> 74075 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127614099456),commit=} 0 11
   [junit4]   2> 74097 INFO  (qtp31311506-2270) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_2 
(1570262127629828096), id_10 (1570262127631925248), id_13 
(1570262127631925249), id_14 (1570262127631925250), id_16 
(1570262127631925251), id_17 (1570262127631925252), id_18 
(1570262127631925253), id_21 (1570262127632973824), id_22 
(1570262127632973825), id_23 (1570262127632973826), ... (125 adds)]} 0 17
   [junit4]   2> 74102 INFO  (qtp16239796-2264) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_1 
(1570262127628779520), id_3 (1570262127631925248), id_4 (1570262127631925249), 
id_5 (1570262127631925250), id_6 (1570262127631925251), id_7 
(1570262127631925252), id_8 (1570262127631925253), id_9 (1570262127632973824), 
id_11 (1570262127632973825), id_12 (1570262127632973826), ... (125 adds)]} 0 22
   [junit4]   2> 74103 INFO  (qtp16239796-2266) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127653945344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74103 INFO  (qtp31311506-2274) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127653945344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74104 INFO  (qtp16239796-2266) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127653945344
   [junit4]   2> 74104 INFO  (qtp31311506-2274) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127653945344
   [junit4]   2> 74112 INFO  (qtp16239796-2266) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@17ea5cb[collection1_shard2_replica_n1] main]
   [junit4]   2> 74112 INFO  (qtp31311506-2274) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3b419[collection1_shard1_replica_n1] main]
   [junit4]   2> 74112 INFO  (qtp31311506-2274) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74112 INFO  (qtp16239796-2266) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74113 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@17ea5cb[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C125)))}
   [junit4]   2> 74113 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@3b419[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C125)))}
   [junit4]   2> 74113 INFO  (qtp16239796-2266) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 9
   [junit4]   2> 74113 INFO  (qtp31311506-2274) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 9
   [junit4]   2> 74114 INFO  (qtp16239796-2268) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 12
   [junit4]   2> 74116 INFO  (qtp622335-2294) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 74120 INFO  (qtp31311506-2271) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/stream 
params={expr=let(a%3Dtimeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),+b%3Dselect(timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),mult(2,+count(*))+as+nvalue),+c%3Dcol(a,+count(*)),+d%3Dcol(b,+nvalue),+tuple(colc%3Dc,+cold%3Dd,+conv%3Dconv(c,d)))&wt=json&version=2.2}
 status=0 QTime=2
   [junit4]   2> 74122 INFO  (qtp31311506-2271) [    ] o.a.s.c.c.ZkStateReader 
Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 74123 INFO  (qtp31311506-2271) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41669/solr ready
   [junit4]   2> 74132 INFO  (qtp16239796-2264) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661190&isShard=true&wt=javabin}
 hits=125 status=0 QTime=5
   [junit4]   2> 74133 INFO  (qtp31311506-2272) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661190&isShard=true&wt=javabin}
 hits=125 status=0 QTime=5
   [junit4]   2> 74133 INFO  (qtp31311506-2274) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=9
   [junit4]   2> 74137 INFO  (qtp16239796-2266) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661200&isShard=true&wt=javabin}
 hits=125 status=0 QTime=2
   [junit4]   2> 74137 INFO  (qtp31311506-2382) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661200&isShard=true&wt=javabin}
 hits=125 status=0 QTime=2
   [junit4]   2> 74137 INFO  (qtp31311506-2269) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=3
   [junit4]   2> 74145 INFO  
(TEST-StreamExpressionTest.testConvolution-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testConvolution
   [junit4]   2> 74160 INFO  
(TEST-StreamExpressionTest.testNullStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testNullStream
   [junit4]   2> 74163 INFO  (qtp16239796-2286) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@55bc82[collection1_shard2_replica_n1] realtime]
   [junit4]   2> 74163 INFO  (qtp31311506-2272) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1a51d4d[collection1_shard1_replica_n1] realtime]
   [junit4]   2> 74164 INFO  (qtp31311506-2272) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127714762752)} 0 2
   [junit4]   2> 74165 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127718957056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74165 INFO  (qtp16239796-2293) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127718957056
   [junit4]   2> 74165 INFO  (qtp16239796-2293) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1949c33[collection1_shard2_replica_n1] main]
   [junit4]   2> 74165 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74165 INFO  (qtp31311506-2272) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127718957056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74165 INFO  (qtp31311506-2272) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127718957056
   [junit4]   2> 74166 INFO  (qtp31311506-2272) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1a256a3[collection1_shard1_replica_n1] main]
   [junit4]   2> 74166 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@1949c33[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 74167 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@1a256a3[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 74167 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 74168 INFO  (qtp31311506-2272) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74168 INFO  (qtp31311506-2272) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 74168 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127714762752),commit=} 0 7
   [junit4]   2> 74170 INFO  (qtp31311506-2276) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0 
(1570262127723151360), 4 (1570262127724199936), 1 (1570262127724199937)]} 0 1
   [junit4]   2> 74170 INFO  (qtp16239796-2281) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2 
(1570262127723151360), 3 (1570262127724199936), 5 (1570262127724199937)]} 0 1
   [junit4]   2> 74172 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127726297088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74172 INFO  (qtp31311506-2271) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127726297088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74172 INFO  (qtp16239796-2293) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127726297088
   [junit4]   2> 74172 INFO  (qtp31311506-2271) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127726297088
   [junit4]   2> 74175 INFO  (qtp31311506-2271) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1197ed4[collection1_shard1_replica_n1] main]
   [junit4]   2> 74176 INFO  (qtp16239796-2293) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@de849f[collection1_shard2_replica_n1] main]
   [junit4]   2> 74176 INFO  (qtp31311506-2271) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74176 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74176 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@1197ed4[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C3)))}
   [junit4]   2> 74177 INFO  (qtp31311506-2271) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 4
   [junit4]   2> 74177 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@de849f[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C3)))}
   [junit4]   2> 74177 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 4
   [junit4]   2> 74177 INFO  (qtp31311506-2382) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 6
   [junit4]   2> 74179 INFO  
(TEST-StreamExpressionTest.testNullStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 74180 INFO  
(TEST-StreamExpressionTest.testNullStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41669/solr ready
   [junit4]   2> 74182 INFO  (qtp31311506-2276) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&wt=json&version=2.2} 
hits=3 status=0 QTime=0
   [junit4]   2> 74182 INFO  (qtp16239796-2281) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc&wt=json&version=2.2} 
hits=3 status=0 QTime=0
   [junit4]   2> 74183 INFO  
(TEST-StreamExpressionTest.testNullStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testNullStream
   [junit4]   2> 74203 INFO  
(TEST-StreamExpressionTest.testTimeSeriesStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testTimeSeriesStream
   [junit4]   2> 74205 INFO  (qtp31311506-2272) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1c21967[collection1_shard1_replica_n1] realtime]
   [junit4]   2> 74205 INFO  (qtp16239796-2293) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1a0cbb1[collection1_shard2_replica_n1] realtime]
   [junit4]   2> 74205 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127759851520)} 0 1
   [junit4]   2> 74206 INFO  (qtp31311506-2382) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127761948672,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74206 INFO  (qtp16239796-2281) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127761948672,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74206 INFO  (qtp31311506-2382) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127761948672
   [junit4]   2> 74206 INFO  (qtp16239796-2281) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127761948672
   [junit4]   2> 74206 INFO  (qtp31311506-2382) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1d158b4[collection1_shard1_replica_n1] main]
   [junit4]   2> 74206 INFO  (qtp16239796-2281) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1d1870d[collection1_shard2_replica_n1] main]
   [junit4]   2> 74207 INFO  (qtp16239796-2281) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74207 INFO  (qtp31311506-2382) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74208 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@1d1870d[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 74208 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@1d158b4[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 74208 INFO  (qtp16239796-2281) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 74208 INFO  (qtp31311506-2382) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 74209 INFO  (qtp31311506-2272) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127759851520),commit=} 0 5
   [junit4]   2> 74226 INFO  (qtp16239796-2266) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_1 
(1570262127767191552), id_3 (1570262127768240128), id_4 (1570262127768240129), 
id_5 (1570262127768240130), id_6 (1570262127769288704), id_7 
(1570262127769288705), id_8 (1570262127769288706), id_9 (1570262127769288707), 
id_11 (1570262127769288708), id_12 (1570262127769288709), ... (125 adds)]} 0 14
   [junit4]   2> 74228 INFO  (qtp31311506-2270) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_2 
(1570262127767191552), id_10 (1570262127768240128), id_13 
(1570262127768240129), id_14 (1570262127769288704), id_16 
(1570262127769288705), id_17 (1570262127769288706), id_18 
(1570262127769288707), id_21 (1570262127769288708), id_22 
(1570262127769288709), id_23 (1570262127769288710), ... (125 adds)]} 0 17
   [junit4]   2> 74231 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127788163072,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74231 INFO  (qtp16239796-2286) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127788163072
   [junit4]   2> 74231 INFO  (qtp31311506-2271) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127788163072,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74231 INFO  (qtp31311506-2271) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127788163072
   [junit4]   2> 74235 INFO  (qtp31311506-2271) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1c3349a[collection1_shard1_replica_n1] main]
   [junit4]   2> 74236 INFO  (qtp31311506-2271) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74236 INFO  (qtp16239796-2286) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1c4db8f[collection1_shard2_replica_n1] main]
   [junit4]   2> 74236 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@1c3349a[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C125)))}
   [junit4]   2> 74236 INFO  (qtp31311506-2271) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 5
   [junit4]   2> 74236 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74237 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@1c4db8f[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C125)))}
   [junit4]   2> 74237 INFO  (qtp16239796-2286) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 74239 INFO  (qtp31311506-2276) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 10
   [junit4]   2> 74244 INFO  (qtp16239796-2281) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/stream 
params={expr=timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f))&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 74248 INFO  (qtp31311506-2272) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661311&isShard=true&wt=javabin}
 hits=125 status=0 QTime=1
   [junit4]   2> 74248 INFO  (qtp16239796-2289) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661311&isShard=true&wt=javabin}
 hits=125 status=0 QTime=1
   [junit4]   2> 74248 INFO  (qtp16239796-2286) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=3
   [junit4]   2> 74254 INFO  (qtp31311506-2269) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/stream 
params={expr=timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+format%3D"yyyy",+count(*),+sum(price_f),+max(price_f),+min(price_f))&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 74257 INFO  (qtp31311506-2276) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&format=yyyy&start=0&fsv=true&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661321&isShard=true&wt=javabin}
 hits=125 status=0 QTime=2
   [junit4]   2> 74258 INFO  (qtp16239796-2264) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&format=yyyy&start=0&fsv=true&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661321&isShard=true&wt=javabin}
 hits=125 status=0 QTime=2
   [junit4]   2> 74258 INFO  (qtp31311506-2274) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&format=yyyy&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=3
   [junit4]   2> 74264 INFO  (qtp16239796-2266) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/stream 
params={expr=timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+format%3D"yyyy-MM",+count(*),+sum(price_f),+max(price_f),+min(price_f))&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 74267 INFO  (qtp31311506-2270) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&format=yyyy-MM&start=0&fsv=true&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661331&isShard=true&wt=javabin}
 hits=125 status=0 QTime=1
   [junit4]   2> 74267 INFO  (qtp16239796-2268) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&format=yyyy-MM&start=0&fsv=true&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497518661331&isShard=true&wt=javabin}
 hits=125 status=0 QTime=1
   [junit4]   2> 74267 INFO  (qtp16239796-2397) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&format=yyyy-MM&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=2
   [junit4]   2> 74268 INFO  
(TEST-StreamExpressionTest.testTimeSeriesStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testTimeSeriesStream
   [junit4]   2> 74291 INFO  
(TEST-StreamExpressionTest.testRandomStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testRandomStream
   [junit4]   2> 74293 INFO  (qtp16239796-2293) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@8ac9a[collection1_shard2_replica_n1] realtime]
   [junit4]   2> 74293 INFO  (qtp31311506-2275) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@ab42cc[collection1_shard1_replica_n1] realtime]
   [junit4]   2> 74293 INFO  (qtp31311506-2275) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127852126208)} 0 1
   [junit4]   2> 74297 INFO  (qtp31311506-2276) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127857369088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74297 INFO  (qtp16239796-2289) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127857369088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74297 INFO  (qtp31311506-2276) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127857369088
   [junit4]   2> 74297 INFO  (qtp16239796-2289) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127857369088
   [junit4]   2> 74297 INFO  (qtp31311506-2276) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1abd93d[collection1_shard1_replica_n1] main]
   [junit4]   2> 74297 INFO  (qtp16239796-2289) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7f4355[collection1_shard2_replica_n1] main]
   [junit4]   2> 74298 INFO  (qtp31311506-2276) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74298 INFO  (qtp16239796-2289) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74298 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@1abd93d[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 74298 INFO  (qtp31311506-2276) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 74298 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@7f4355[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 74298 INFO  (qtp16239796-2289) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 74299 INFO  (qtp16239796-2293) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570262127852126208),commit=} 0 7
   [junit4]   2> 74318 INFO  (qtp16239796-2264) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2 
(1570262127862611968), 3 (1570262127863660544), 5 (1570262127863660545), 6 
(1570262127863660546), 7 (1570262127863660547), 9 (1570262127863660548), 17 
(1570262127863660549), 18 (1570262127863660550), 19 (1570262127863660551), 21 
(1570262127863660552), ... (478 adds)]} 0 16
   [junit4]   2> 74324 INFO  (qtp31311506-2382) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0 
(1570262127862611968), 1 (1570262127863660544), 4 (1570262127863660545), 8 
(1570262127863660546), 10 (1570262127863660547), 11 (1570262127863660548), 12 
(1570262127863660549), 13 (1570262127863660550), 14 (1570262127863660551), 15 
(1570262127863660552), ... (522 adds)]} 0 22
   [junit4]   2> 74325 INFO  (qtp16239796-2397) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127886729216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74325 INFO  (qtp16239796-2397) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e0fcdd 
commitCommandVersion:1570262127886729216
   [junit4]   2> 74325 INFO  (qtp31311506-2270) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570262127886729216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74325 INFO  (qtp31311506-2270) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a7eaaf 
commitCommandVersion:1570262127886729216
   [junit4]   2> 74343 INFO  (qtp16239796-2397) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3af4a8[collection1_shard2_replica_n1] main]
   [junit4]   2> 74344 INFO  (qtp16239796-2397) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74344 INFO  (searcherExecutor-664-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher 
Searcher@3af4a8[collection1_shard2_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C478)))}
   [junit4]   2> 74344 INFO  (qtp16239796-2397) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 19
   [junit4]   2> 74344 INFO  (qtp31311506-2270) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@e83289[collection1_shard1_replica_n1] main]
   [junit4]   2> 74345 INFO  (qtp31311506-2270) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74345 INFO  (searcherExecutor-663-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher 
Searcher@e83289[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C522)))}
   [junit4]   2> 74346 INFO  (qtp31311506-2270) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 20
   [junit4]   2> 74346 INFO  (qtp31311506-2269) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 22
   [junit4]   2> 74357 INFO  
(TEST-StreamExpressionTest.testRandomStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 74358 INFO  
(TEST-StreamExpressionTest.testRandomStream-seed#[67FB4D8A1BBE6E3A]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41669/solr ready
   [junit4]   2> 74362 INFO  (qtp16239796-2281) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&shards.purpose=4&start=0&fsv=true&sort=random_-1729956895+asc&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661425&isShard=true&wt=javabin}
 hits=478 status=0 QTime=2
   [junit4]   2> 74362 INFO  (qtp31311506-2272) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&shards.purpose=4&start=0&fsv=true&sort=random_-1729956895+asc&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661425&isShard=true&wt=javabin}
 hits=522 status=0 QTime=2
   [junit4]   2> 74378 INFO  (qtp31311506-2276) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id,+a_i&shards.purpose=64&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661425&ids=0,1,4,800,8,801,802,805,807,808,809,812,813,814,816,817,819,821,822,825,826,828,834,837,838,839,840,841,842,843,844,602,845,847,605,848,608,609,850,853,854,613,614,858,616,617,860,620,862,622,623,865,866,625,868,626,869,627,628,629,870,871,630,632,874,633,876,635,878,638,639,881,640,882,883,400,884,642,885,643,402,403,404,647,406,408,409,650,892,894,412,654,897,656,657,416,418,419,661,420,662,663,664,422,423,666,667,425,668,426,669,428,670,671,672,674,676,434,435,436,438,680,683,441,684,685,201,444,203,445,687,689,447,448,206,207,449,690,691,451,452,694,453,211,695,454,212,455,213,698,456,215,218,219,460,461,220,221,463,222,226,468,469,227,228,470,231,473,232,476,236,238,480,482,240,241,483,484,242,243,485,244,245,487,488,489,491,250,495,253,255,257,258,265,267,268,273,275,277,278,280,283,284,285,287,288,290,293,294,296,297,298,299,900,901,902,905,906,907,908,910,912,913,915,916,920,922,924,926,930,932,936,938,940,701,945,705,947,706,948,707,709,950,952,710,953,711,954,955,957,715,716,718,960,961,721,965,723,966,725,967,726,728,970,971,972,730,973,731,974,975,734,977,978,979,737,738,739,983,985,501,744,745,746,504,989,748,506,749,991,750,751,994,753,995,512,754,996,998,514,999,758,518,520,763,521,522,523,766,767,769,529,771,772,773,533,775,535,779,538,539,783,541,300,542,301,785,786,787,303,546,304,788,305,789,548,308,309,791,550,551,310,795,553,796,554,797,555,314,556,798,799,557,316,561,562,320,321,563,565,323,566,325,568,569,329,570,331,332,574,333,576,335,578,579,339,580,340,583,584,100,342,101,585,102,344,587,345,347,348,349,107,108,109,350,592,594,352,110,595,111,353,355,113,356,114,357,358,118,10,11,12,13,14,15,16,360,361,362,122,123,365,124,367,125,368,369,129,20,22,24,27,28,373,374,132,133,376,135,377,379,138,32,34,35,36,38,380,382,384,385,386,387,146,388,148,40,41,42,43,44,46,47,48,49,392,150,393,151,394,154,397,156,398,399,157,50,51,52,54,55,59,160,161,166,167,168,169,61,63,64,65,66,171,173,175,176,70,72,73,74,75,77,79,182,183,184,185,188,189,80,83,84,85,190,192,193,197,93,94,96,99&isShard=true&wt=javabin}
 status=0 QTime=3
   [junit4]   2> 74378 INFO  (qtp16239796-2264) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id,+a_i&shards.purpose=64&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661425&ids=2,3,5,6,7,9,803,804,806,810,811,815,818,820,823,824,827,829,830,831,832,833,835,836,600,601,603,604,846,606,607,849,851,852,610,611,612,855,856,615,857,859,618,619,861,863,621,864,624,867,872,873,631,875,634,877,636,879,637,880,641,401,644,886,645,887,888,646,889,405,648,407,649,890,891,651,893,652,410,895,411,653,896,655,413,898,414,415,899,658,417,659,660,421,665,424,427,429,430,673,431,432,433,675,677,678,679,437,439,681,440,682,442,200,443,202,686,688,204,446,205,208,209,692,450,693,210,696,697,214,457,699,458,216,459,217,462,464,223,465,224,466,467,225,229,471,230,472,474,475,233,234,477,235,478,237,479,239,481,486,246,247,248,249,490,492,493,251,252,494,254,496,497,256,498,499,259,260,261,262,263,264,266,269,270,271,272,274,276,279,281,282,286,289,291,292,295,903,904,909,911,914,917,918,919,921,923,925,927,928,929,931,933,934,935,937,939,941,700,942,943,944,702,703,946,704,949,708,951,712,713,956,714,958,959,717,719,962,720,963,722,964,724,968,969,727,729,732,733,976,735,736,980,981,740,982,741,500,984,742,743,986,502,987,503,988,747,505,507,508,509,990,992,993,510,752,511,513,997,755,756,757,515,516,759,517,519,760,761,762,764,765,524,525,526,768,527,528,770,530,531,532,774,534,776,777,778,536,537,780,781,782,540,784,543,544,302,545,547,306,549,307,790,792,793,552,794,311,312,313,315,558,559,317,318,319,560,564,322,324,567,326,327,328,571,330,572,573,575,334,577,336,337,338,581,582,341,343,586,103,588,104,346,589,105,106,590,591,593,351,354,112,596,597,598,115,599,116,359,117,119,17,18,19,120,363,121,364,366,126,127,128,21,23,25,26,29,370,371,130,372,131,375,134,378,136,137,139,30,31,33,37,39,381,140,141,383,142,143,144,145,389,147,149,45,390,391,152,395,153,396,155,158,159,53,56,57,58,162,163,164,165,60,62,67,68,69,170,172,174,177,178,179,71,76,78,180,181,186,187,81,82,86,87,88,89,191,194,195,196,198,199,90,91,92,95,97,98&isShard=true&wt=javabin}
 status=0 QTime=3
   [junit4]   2> 74386 INFO  (qtp16239796-2289) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:3&fl=id,+a_i&sort=random_-1729956895+asc&rows=1000&wt=javabin&version=2}
 hits=1000 status=0 QTime=27
   [junit4]   2> 74391 INFO  (qtp31311506-2269) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&shards.purpose=4&start=0&fsv=true&sort=random_1839950245+asc&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661454&isShard=true&wt=javabin}
 hits=522 status=0 QTime=2
   [junit4]   2> 74391 INFO  (qtp16239796-2286) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&shards.purpose=4&start=0&fsv=true&sort=random_1839950245+asc&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661454&isShard=true&wt=javabin}
 hits=478 status=0 QTime=2
   [junit4]   2> 74402 INFO  (qtp16239796-2266) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id,+a_i&shards.purpose=64&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661454&ids=2,3,5,6,7,9,803,804,806,810,811,815,818,820,823,824,827,829,830,831,832,833,835,836,600,601,603,604,846,606,849,607,851,852,610,611,612,855,856,857,615,859,618,619,861,621,863,864,624,867,872,873,631,875,634,877,636,637,879,880,641,401,886,644,887,645,646,888,889,405,648,649,407,890,891,893,651,410,652,895,411,653,896,655,413,898,414,415,899,658,659,417,660,421,665,424,427,429,430,431,673,432,433,675,677,678,437,679,439,681,682,440,200,442,443,686,202,688,446,204,205,208,209,450,692,693,210,696,697,214,457,699,216,458,217,459,462,464,223,465,466,224,225,467,229,471,472,230,474,475,233,234,235,477,478,237,479,239,481,486,246,247,248,249,490,492,493,251,252,494,254,496,497,498,256,499,259,260,261,262,263,264,266,269,270,271,272,274,276,279,281,282,286,289,291,292,295,903,904,909,911,914,917,918,919,921,923,925,927,928,929,931,933,934,935,937,939,941,942,700,943,702,944,703,946,704,949,708,951,712,713,714,956,958,959,717,719,962,720,963,964,722,724,968,969,727,729,732,733,976,735,736,980,981,740,982,741,742,500,984,743,502,986,987,503,988,747,505,507,508,509,990,992,993,752,510,511,513,755,997,756,757,515,516,759,517,519,760,761,762,764,765,524,525,526,768,527,528,770,530,531,532,774,534,776,777,778,536,537,780,781,782,540,784,543,544,302,545,547,306,307,549,790,792,793,794,552,311,312,313,315,558,559,317,318,319,560,322,564,324,567,326,327,328,571,330,572,573,575,334,577,336,337,338,581,582,341,343,586,103,588,346,104,105,589,106,590,591,593,351,596,112,354,597,598,599,115,116,117,359,119,17,18,19,120,363,121,364,366,126,127,128,21,23,25,26,29,370,371,372,130,131,375,134,136,378,137,139,30,31,33,37,39,381,140,141,383,142,143,144,145,147,389,149,45,390,391,152,395,153,396,155,158,159,53,56,57,58,162,163,164,165,60,62,67,68,69,170,172,174,177,178,179,71,76,78,180,181,186,187,81,82,86,87,88,89,191,194,195,196,198,199,90,91,92,95,97,98&isShard=true&wt=javabin}
 status=0 QTime=1
   [junit4]   2> 74403 INFO  (qtp31311506-2272) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id,+a_i&shards.purpose=64&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=1000&version=2&q=*:*&NOW=1497518661454&ids=0,1,4,800,8,801,802,805,807,808,809,812,813,814,816,817,819,821,822,825,826,828,834,837,838,839,840,841,842,843,602,844,845,605,847,848,608,609,850,853,854,613,614,616,858,617,860,862,620,622,865,623,866,625,868,626,869,627,628,629,870,871,630,632,874,633,876,635,878,638,639,881,640,882,883,642,884,400,885,643,402,403,404,647,406,408,409,650,892,894,654,412,897,656,657,416,418,419,661,662,420,663,422,664,423,666,667,425,426,668,669,428,670,671,672,674,434,676,435,436,438,680,683,441,684,685,201,444,687,445,203,447,689,206,448,207,449,690,691,451,452,694,453,211,695,454,212,455,213,698,456,215,218,219,460,461,220,221,463,222,226,468,227,469,228,470,473,231,232,476,236,238,480,240,482,241,483,242,484,243,485,244,245,487,488,489,491,250,495,253,255,257,258,265,267,268,273,275,277,278,280,283,284,285,287,288,290,293,294,296,297,298,299,900,901,902,905,906,907,908,910,912,913,915,916,920,922,924,926,930,932,936,938,940,701,945,705,947,948,706,707,709,950,710,952,953,711,954,955,957,715,716,718,960,961,721,723,965,966,967,725,726,728,970,971,730,972,731,973,974,975,734,977,978,979,737,738,739,983,501,985,744,745,746,504,989,748,506,749,991,750,751,994,995,753,996,754,512,514,998,999,758,518,520,521,763,522,523,766,767,769,529,771,772,773,775,533,535,779,538,539,541,783,542,300,785,301,786,303,787,546,788,304,789,305,548,308,309,791,550,551,310,553,795,554,796,797,555,314,556,798,799,557,316,561,320,562,563,321,323,565,566,325,568,569,329,570,331,574,332,333,576,335,578,579,339,580,340,583,342,100,584,101,585,344,102,345,587,347,348,107,349,108,109,592,350,352,594,110,353,595,111,355,113,356,114,357,358,118,10,11,12,13,14,15,16,360,361,362,122,365,123,124,125,367,368,369,129,20,22,24,27,28,373,132,374,133,376,377,135,379,138,32,34,35,36,38,380,382,384,385,386,387,146,388,148,40,41,42,43,44,46,47,48,49,150,392,151,393,394,154,397,398,156,399,157,50,51,52,54,55,59,160,161,166,167,168,169,61,63,64,65,66,171,173,175,176,70,72,73,74,75,77,79,182,183,184,185,188,189,80,83,84,85,190,192,193,197,93,94,96,99&isShard=true&wt=javabin}
 status=0 QTime=1
   [junit4]   2> 74411 INFO  (qtp31311506-2270) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:3&fl=id,+a_i&sort=random_1839950245+asc&rows=1000&wt=javabin&version=2}
 hits=1000 status=0 QTime=23
   [junit4]   2> 74416 INFO  (qtp16239796-2281) [    ] o.a.s.c.S.Request 
[collection1_shard2_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&shards.purpose=4&start=0&fsv=true&sort=random_1441089646+asc&shard.url=http://127.0.0.1:34131/solr/collection1_shard2_replica_n1/&rows=1&version=2&q=*:*&NOW=1497518661481&isShard=true&wt=javabin}
 hits=478 status=0 QTime=0
   [junit4]   2> 74416 INFO  (qtp31311506-2274) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&shards.purpose=4&start=0&fsv=true&sort=random_1441089646+asc&shard.url=http://127.0.0.1:41715/solr/collection1_shard1_replica_n1/&rows=1&versi

[...truncated too long message...]

ic reporters for registry=solr.cluster, tag=null
   [junit4]   2> 169281 INFO  (zkCallback-755-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 169281 INFO  (zkCallback-752-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 169281 INFO  (zkCallback-752-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41715_solr
   [junit4]   2> 169283 INFO  (coreCloseExecutor-856-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard2_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@a83be2
   [junit4]   2> 169283 INFO  (zkCallback-2328-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (2)
   [junit4]   2> 169291 INFO  (coreCloseExecutor-856-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1.shard2.replica_n1, tag=11025378
   [junit4]   2> 169293 INFO  (coreCloseExecutor-856-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.collection1.shard2.leader, tag=11025378
   [junit4]   2> 169295 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 0x15cab1152c1000b, 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> 169295 INFO  (zkCallback-752-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 169295 WARN  (zkCallback-752-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> 169295 INFO  (jetty-closer-733-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@65ac29{/solr,null,UNAVAILABLE}
   [junit4]   2> 170781 WARN  (zkCallback-745-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> 170781 INFO  (jetty-closer-733-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@b049c7{/solr,null,UNAVAILABLE}
   [junit4]   2> 170795 WARN  (zkCallback-755-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> 170795 INFO  (jetty-closer-733-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2c9b10{/solr,null,UNAVAILABLE}
   [junit4]   2> 170795 ERROR 
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-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> 170795 INFO  
(SUITE-StreamExpressionTest-seed#[67FB4D8A1BBE6E3A]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:41669 41669
   [junit4]   2> 170829 INFO  (Thread-523) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:41669 41669
   [junit4]   2> 170829 WARN  (Thread-523) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        91      /solr/aliases.json
   [junit4]   2>        91      /solr/clusterprops.json
   [junit4]   2>        13      /solr/configs/conf
   [junit4]   2>        7       /solr/configs/ml
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        91      /solr/clusterstate.json
   [junit4]   2>        8       
/solr/collections/destinationCollection/state.json
   [junit4]   2>        4       /solr/collections/destination/state.json
   [junit4]   2>        4       
/solr/collections/parallelDestinationCollection/state.json
   [junit4]   2>        4       
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2>        4       /solr/collections/mainCorpus/state.json
   [junit4]   2>        2       /solr/collections/collection1/state.json
   [junit4]   2>        2       /solr/collections/workQueue/state.json
   [junit4]   2>        2       /solr/collections/uknownCollection/state.json
   [junit4]   2>        2       /solr/collections/modelCollection/state.json
   [junit4]   2>        2       
/solr/collections/checkpointCollection/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        91      /solr/live_nodes
   [junit4]   2>        91      /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/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_67FB4D8A1BBE6E3A-001
   [junit4]   2> Jun 15, 2017 9:25:57 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{expr_s=FST50, name_s=PostingsFormat(name=Memory), iteration_i=FST50, 
terms_ss=BlockTreeOrds(blocksize=128), join2_s=BlockTreeOrds(blocksize=128), 
multiDefault=BlockTreeOrds(blocksize=128), 
subject=BlockTreeOrds(blocksize=128), 
intDefault=PostingsFormat(name=LuceneFixedGap), 
a_f=BlockTreeOrds(blocksize=128), 
falseNegative_i=PostingsFormat(name=LuceneFixedGap), 
a2_s=PostingsFormat(name=Memory), a_i=FST50, level1_s=FST50, 
body_t=PostingsFormat(name=Memory), a_s=PostingsFormat(name=Memory), 
a_t=PostingsFormat(name=LuceneFixedGap), id=FST50, 
text=PostingsFormat(name=Memory), alpha_d=PostingsFormat(name=Memory), 
i_multi=FST50, idf_d=BlockTreeOrds(blocksize=128), 
b_ls=BlockTreeOrds(blocksize=128), checkpoint_ss=PostingsFormat(name=Memory), 
test_t=PostingsFormat(name=Memory), ident_s=BlockTreeOrds(blocksize=128), 
order_i=BlockTreeOrds(blocksize=128), error_d=FST50, 
side_s=PostingsFormat(name=Memory), truePositive_i=FST50, 
miles_i=BlockTreeOrds(blocksize=128), 
field_s=PostingsFormat(name=LuceneFixedGap), 
price_f=BlockTreeOrds(blocksize=128), a1_s=BlockTreeOrds(blocksize=128), 
join1_i=FST50, join3_i=PostingsFormat(name=Memory), 
test_i=BlockTreeOrds(blocksize=128), 
falsePositive_i=PostingsFormat(name=LuceneFixedGap), 
field_i=BlockTreeOrds(blocksize=128), s_multi=PostingsFormat(name=Memory), 
a_ss=PostingsFormat(name=LuceneFixedGap), col_s=BlockTreeOrds(blocksize=128), 
level2_s=PostingsFormat(name=LuceneFixedGap), 
score_f=PostingsFormat(name=Memory), 
tv_text=PostingsFormat(name=LuceneFixedGap), 
term_s=BlockTreeOrds(blocksize=128), text_s=FST50, 
timestamp=PostingsFormat(name=LuceneFixedGap), 
idfs_ds=PostingsFormat(name=LuceneFixedGap), 
weights_ds=PostingsFormat(name=Memory), 
featureSet_s=BlockTreeOrds(blocksize=128), 
trueNegative_i=PostingsFormat(name=Memory), 
index_i=BlockTreeOrds(blocksize=128), test_dt=FST50, 
out_i=BlockTreeOrds(blocksize=128), whitetok=PostingsFormat(name=Memory), 
_version_=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{expr_s=DocValuesFormat(name=Direct), 
name_s=DocValuesFormat(name=Lucene70), 
iteration_i=DocValuesFormat(name=Direct), 
terms_ss=DocValuesFormat(name=Memory), join2_s=DocValuesFormat(name=Memory), 
field_s=DocValuesFormat(name=Asserting), 
multiDefault=DocValuesFormat(name=Memory), 
price_f=DocValuesFormat(name=Memory), a1_s=DocValuesFormat(name=Memory), 
intDefault=DocValuesFormat(name=Asserting), a_f=DocValuesFormat(name=Memory), 
join1_i=DocValuesFormat(name=Direct), 
falseNegative_i=DocValuesFormat(name=Asserting), 
a2_s=DocValuesFormat(name=Lucene70), a_i=DocValuesFormat(name=Direct), 
join3_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Memory), 
falsePositive_i=DocValuesFormat(name=Asserting), 
field_i=DocValuesFormat(name=Memory), level1_s=DocValuesFormat(name=Direct), 
s_multi=DocValuesFormat(name=Lucene70), a_ss=DocValuesFormat(name=Asserting), 
col_s=DocValuesFormat(name=Memory), level2_s=DocValuesFormat(name=Asserting), 
a_s=DocValuesFormat(name=Lucene70), score_f=DocValuesFormat(name=Lucene70), 
term_s=DocValuesFormat(name=Memory), text_s=DocValuesFormat(name=Direct), 
id=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Asserting), 
i_multi=DocValuesFormat(name=Direct), 
featureSet_s=DocValuesFormat(name=Memory), 
trueNegative_i=DocValuesFormat(name=Lucene70), 
index_i=DocValuesFormat(name=Memory), b_ls=DocValuesFormat(name=Memory), 
test_dt=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Memory), 
checkpoint_ss=DocValuesFormat(name=Lucene70), 
ident_s=DocValuesFormat(name=Memory), order_i=DocValuesFormat(name=Memory), 
_version_=DocValuesFormat(name=Asserting), 
side_s=DocValuesFormat(name=Lucene70), 
truePositive_i=DocValuesFormat(name=Direct), 
miles_i=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=541, 
maxMBSortInHeap=7.829651902864535, sim=RandomSimilarity(queryNorm=true): {}, 
locale=en-ZM, timezone=Australia/ACT
   [junit4]   2> NOTE: Linux 4.10.0-21-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=8,threads=1,free=129222776,total=307757056
   [junit4]   2> NOTE: All tests run in this JVM: [SolrDocumentTest, 
SolrExampleStreamingBinaryTest, TangentEvaluatorTest, GetByIdTest, 
ConcurrentUpdateSolrClientBuilderTest, ShardParamsTest, 
TestDocumentObjectBinder, TestSolrJErrorHandling, TestPathTrie, 
HttpSolrClientConPoolTest, SchemaTest, PowerEvaluatorTest, FacetFieldTest, 
StreamingTest, ArcTangentEvaluatorTest, TestSolrProperties, 
StreamExpressionParserTest, GreaterThanEqualToEvaluatorTest, 
TestToleratedUpdateError, QueryResponseTest, ExclusiveOrEvaluatorTest, 
SolrExampleEmbeddedTest, BasicHttpSolrClientTest, 
HttpSolrClientSSLAuthConPoolTest, GraphTest, JdbcTest, StreamExpressionTest]
   [junit4] Completed [132/132 (1!)] on J1 in 99.92s, 89 tests, 1 failure <<< 
FAILURES!

[...truncated 35502 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to