Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/890/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC

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

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

Stack Trace:
java.lang.AssertionError: expected:<5> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([17F9173E2B67B476:3713753EB726593A]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13815 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/init-core-data-001
   [junit4]   2> 60200 WARN  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=35 numCloses=35
   [junit4]   2> 60200 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 60202 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 60202 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001
   [junit4]   2> 60203 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 60203 INFO  (Thread-99) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 60203 INFO  (Thread-99) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 60206 ERROR (Thread-99) [    ] 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> 60303 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:52245
   [junit4]   2> 60320 INFO  (jetty-launcher-160-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 60321 INFO  (jetty-launcher-160-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 60322 INFO  (jetty-launcher-160-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 60322 INFO  (jetty-launcher-160-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 60324 INFO  (jetty-launcher-160-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@259602d2{/solr,null,AVAILABLE}
   [junit4]   2> 60327 INFO  (jetty-launcher-160-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4e4ee63b{/solr,null,AVAILABLE}
   [junit4]   2> 60329 INFO  (jetty-launcher-160-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@26fc9e81{HTTP/1.1,[http/1.1]}{127.0.0.1:37882}
   [junit4]   2> 60329 INFO  (jetty-launcher-160-thread-3) [    ] 
o.e.j.s.Server Started @63411ms
   [junit4]   2> 60329 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37882}
   [junit4]   2> 60330 ERROR (jetty-launcher-160-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 60330 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 60330 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 60330 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 60330 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T16:44:06.770Z
   [junit4]   2> 60331 INFO  (jetty-launcher-160-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@10605532{/solr,null,AVAILABLE}
   [junit4]   2> 60333 INFO  (jetty-launcher-160-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@37559ff4{/solr,null,AVAILABLE}
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@142229c5{HTTP/1.1,[http/1.1]}{127.0.0.1:59928}
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3b03b85c{HTTP/1.1,[http/1.1]}{127.0.0.1:50450}
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.e.j.s.Server Started @63416ms
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.e.j.s.Server Started @63416ms
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=59928}
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50450}
   [junit4]   2> 60334 ERROR (jetty-launcher-160-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T16:44:06.774Z
   [junit4]   2> 60334 ERROR (jetty-launcher-160-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 60334 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T16:44:06.774Z
   [junit4]   2> 60335 INFO  (jetty-launcher-160-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@76353cce{HTTP/1.1,[http/1.1]}{127.0.0.1:41613}
   [junit4]   2> 60335 INFO  (jetty-launcher-160-thread-4) [    ] 
o.e.j.s.Server Started @63417ms
   [junit4]   2> 60335 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41613}
   [junit4]   2> 60336 ERROR (jetty-launcher-160-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 60336 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 60336 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 60336 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 60336 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T16:44:06.776Z
   [junit4]   2> 60343 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 60346 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 60348 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 60358 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 60359 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 60359 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 60360 INFO  (jetty-launcher-160-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52245/solr
   [junit4]   2> 60364 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 60367 INFO  (jetty-launcher-160-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52245/solr
   [junit4]   2> 60368 INFO  (jetty-launcher-160-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52245/solr
   [junit4]   2> 60370 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 60372 INFO  (jetty-launcher-160-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52245/solr
   [junit4]   2> 60381 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 0x15c9d30e6750006, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 60415 INFO  (jetty-launcher-160-thread-3) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 60416 INFO  (jetty-launcher-160-thread-3) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:37882_solr
   [junit4]   2> 60418 INFO  (jetty-launcher-160-thread-3) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.Overseer Overseer 
(id=98126125245726730-127.0.0.1:37882_solr-n_0000000000) starting
   [junit4]   2> 60423 INFO  (jetty-launcher-160-thread-1) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 60425 INFO  (jetty-launcher-160-thread-2) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 60427 INFO  (jetty-launcher-160-thread-4) 
[n:127.0.0.1:41613_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 60429 INFO  (jetty-launcher-160-thread-4) 
[n:127.0.0.1:41613_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41613_solr
   [junit4]   2> 60435 INFO  (jetty-launcher-160-thread-2) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:59928_solr
   [junit4]   2> 60436 INFO  
(zkCallback-182-thread-1-processing-n:127.0.0.1:41613_solr) 
[n:127.0.0.1:41613_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 60438 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 60438 INFO  (jetty-launcher-160-thread-1) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50450_solr
   [junit4]   2> 60451 INFO  
(zkCallback-179-thread-1-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 60455 INFO  
(zkCallback-180-thread-1-processing-n:127.0.0.1:37882_solr) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 60456 INFO  
(zkCallback-182-thread-1-processing-n:127.0.0.1:41613_solr) 
[n:127.0.0.1:41613_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (3)
   [junit4]   2> 60460 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (3)
   [junit4]   2> 60465 INFO  
(zkCallback-180-thread-2-processing-n:127.0.0.1:37882_solr) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 60468 INFO  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (3)
   [junit4]   2> 60496 INFO  (jetty-launcher-160-thread-1) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node1/.
   [junit4]   2> 60496 INFO  (jetty-launcher-160-thread-3) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37882_solr
   [junit4]   2> 60508 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 60511 INFO  
(zkCallback-180-thread-2-processing-n:127.0.0.1:37882_solr) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 60524 INFO  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 60524 INFO  
(zkCallback-182-thread-1-processing-n:127.0.0.1:41613_solr) 
[n:127.0.0.1:41613_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 60548 INFO  (jetty-launcher-160-thread-4) 
[n:127.0.0.1:41613_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node3/.
   [junit4]   2> 60595 INFO  (jetty-launcher-160-thread-2) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node2/.
   [junit4]   2> 60647 INFO  (jetty-launcher-160-thread-3) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node4/.
   [junit4]   2> 60662 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 60664 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52245/solr ready
   [junit4]   2> 60680 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection1_collection&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 60685 INFO  
(OverseerThreadFactory-401-thread-1-processing-n:127.0.0.1:37882_solr) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1_collection
   [junit4]   2> 60793 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 60793 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 60793 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 60793 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 60903 INFO  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 60903 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 61825 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 61825 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 61840 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.IndexSchema [collection1_collection_shard2_replica1] Schema name=test
   [junit4]   2> 61840 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.IndexSchema [collection1_collection_shard1_replica1] Schema name=test
   [junit4]   2> 61901 WARN  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 61902 WARN  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 61903 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 61903 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 
'collection1_collection_shard1_replica1' using configuration from collection 
collection1_collection, trusted=true
   [junit4]   2> 61903 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 61903 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SolrCore [[collection1_collection_shard1_replica1] ] Opening new 
SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node2/collection1_collection_shard1_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node2/./collection1_collection_shard1_replica1/data/]
   [junit4]   2> 61909 WARN  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 61911 WARN  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 61911 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 61911 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 
'collection1_collection_shard2_replica1' using configuration from collection 
collection1_collection, trusted=true
   [junit4]   2> 61912 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 61912 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SolrCore [[collection1_collection_shard2_replica1] ] Opening new 
SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node1/collection1_collection_shard2_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001/tempDir-001/node1/./collection1_collection_shard2_replica1/data/]
   [junit4]   2> 61945 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 61945 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 61946 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 61946 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 61947 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@35975ed7[collection1_collection_shard1_replica1] main]
   [junit4]   2> 61948 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 61949 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 61949 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 61949 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570018005760344064
   [junit4]   2> 61949 INFO  
(searcherExecutor-410-thread-1-processing-n:127.0.0.1:59928_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection) 
[n:127.0.0.1:59928_solr c:collection1_collection s:shard1  
x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@35975ed7[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 61954 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 61954 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 61955 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 61955 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 61955 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/
   [junit4]   2> 61955 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 61956 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 61956 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/ has no 
replicas
   [junit4]   2> 61956 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 61956 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 61956 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6327cfa9[collection1_collection_shard2_replica1] main]
   [junit4]   2> 61958 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 61958 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 61958 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 61959 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570018005770829824
   [junit4]   2> 61959 INFO  
(searcherExecutor-411-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection) 
[n:127.0.0.1:50450_solr c:collection1_collection s:shard2  
x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@6327cfa9[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 61963 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/ shard1
   [junit4]   2> 61968 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 61968 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 61968 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/
   [junit4]   2> 61968 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 61969 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/ has no 
replicas
   [junit4]   2> 61969 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 61975 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/ shard2
   [junit4]   2> 62079 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 62079 INFO  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 62122 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 62126 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard1_replica1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2}
 status=0 QTime=1333
   [junit4]   2> 62127 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 62130 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_collection_shard2_replica1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2}
 status=0 QTime=1337
   [junit4]   2> 62135 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 62236 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 62236 INFO  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1_collection/state.json] for collection 
[collection1_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 62686 INFO  
(OverseerCollectionConfigSetProcessor-98126125245726730-127.0.0.1:37882_solr-n_0000000000)
 [n:127.0.0.1:37882_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 63136 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection1_collection&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=2456
   [junit4]   2> 63137 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1_collection failOnTimeout:true timeout (sec):90
   [junit4]   2> 63138 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: 
collection1_collection
   [junit4]   2> 63144 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params 
collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 63254 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2}
 status=0 QTime=109
   [junit4]   2> 63279 INFO  
(TEST-StreamExpressionTest.testComplementStream-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testComplementStream
   [junit4]   2> 63290 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007162290176)} 0 3
   [junit4]   2> 63293 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63293 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 63294 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63294 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 63294 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63294 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 63295 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63295 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 63296 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007159144448),commit=} 0 14
   [junit4]   2> 63304 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[0 
(1570018007178018816), 4 (1570018007180115968), 8 (1570018007180115969)]} 0 2
   [junit4]   2> 63306 INFO  (qtp898352896-679) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[2 
(1570018007180115968), 3 (1570018007183261696), 5 (1570018007183261697), 6 
(1570018007183261698), 9 (1570018007183261699), 7 (1570018007183261700)]} 0 3
   [junit4]   2> 63311 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63312 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@306e0e61
   [junit4]   2> 63312 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63313 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d8f2627
   [junit4]   2> 63316 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5082002b[collection1_collection_shard2_replica1] main]
   [junit4]   2> 63318 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63318 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2c3bb14b[collection1_collection_shard1_replica1] main]
   [junit4]   2> 63319 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63319 INFO  
(searcherExecutor-411-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:50450_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@5082002b[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C6)))}
   [junit4]   2> 63319 INFO  
(searcherExecutor-410-thread-1-processing-n:127.0.0.1:59928_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:59928_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@2c3bb14b[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C3)))}
   [junit4]   2> 63319 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 8
   [junit4]   2> 63319 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 7
   [junit4]   2> 63321 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 63328 INFO  
(TEST-StreamExpressionTest.testComplementStream-seed#[17F9173E2B67B476]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 63329 INFO  
(TEST-StreamExpressionTest.testComplementStream-seed#[17F9173E2B67B476]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52245/solr ready
   [junit4]   2> 63336 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=a_s:(setA+||+setAB)&distrib=false&fl=id,a_s,a_i&sort=a_i+asc,+a_s+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=2
   [junit4]   2> 63336 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=a_s:(setA+||+setAB)&distrib=false&fl=id,a_s,a_i&sort=a_i+asc,+a_s+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=2
   [junit4]   2> 63341 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=a_s:(setB+||+setAB)&distrib=false&fl=id,a_s,a_i&sort=a_i+asc&wt=json&version=2.2}
 hits=1 status=0 QTime=0
   [junit4]   2> 63342 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=a_s:(setB+||+setAB)&distrib=false&fl=id,a_s,a_i&sort=a_i+asc&wt=json&version=2.2}
 hits=4 status=0 QTime=0
   [junit4]   2> 63345 INFO  
(TEST-StreamExpressionTest.testComplementStream-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testComplementStream
   [junit4]   2> 63346 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 0x15c9d30e675000f, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 63373 INFO  
(TEST-StreamExpressionTest.testArraySort-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testArraySort
   [junit4]   2> 63378 INFO  (qtp898352896-706) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@725c11a5[collection1_collection_shard2_replica1] realtime]
   [junit4]   2> 63379 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@41fc9b3e[collection1_collection_shard1_replica1] realtime]
   [junit4]   2> 63380 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007257710592)} 0 3
   [junit4]   2> 63382 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63382 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d8f2627
   [junit4]   2> 63383 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@53a4ed6[collection1_collection_shard2_replica1] main]
   [junit4]   2> 63383 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63383 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@306e0e61
   [junit4]   2> 63383 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63383 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1e4b9afa[collection1_collection_shard1_replica1] main]
   [junit4]   2> 63383 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63383 INFO  
(searcherExecutor-410-thread-1-processing-n:127.0.0.1:59928_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:59928_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@1e4b9afa[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 63383 INFO  
(searcherExecutor-411-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:50450_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@53a4ed6[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 63384 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 63384 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 63385 INFO  (qtp898352896-706) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007255613440),commit=} 0 10
   [junit4]   2> 63388 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/stream 
params={expr=arraySort(array(11.5,+12.3,+4,+3,+1,+0))&wt=json&version=2.2} 
status=0 QTime=1
   [junit4]   2> 63390 INFO  
(TEST-StreamExpressionTest.testArraySort-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testArraySort
   [junit4]   2> 63416 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[17F9173E2B67B476])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCloudSolrStreamWithZkHost
   [junit4]   2> 63420 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007302799360)} 0 0
   [junit4]   2> 63423 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63423 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 63423 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63423 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 63423 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63423 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63423 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 63424 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 63425 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007300702208),commit=} 0 6
   [junit4]   2> 63428 INFO  (qtp898352896-706) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[2 
(1570018007310139392), 3 (1570018007311187968)]} 0 2
   [junit4]   2> 63429 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[0 
(1570018007310139392), 4 (1570018007312236544), 1 (1570018007312236545)]} 0 2
   [junit4]   2> 63434 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63434 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d8f2627
   [junit4]   2> 63434 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7c69c4bf[collection1_collection_shard2_replica1] main]
   [junit4]   2> 63434 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63434 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63434 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@306e0e61
   [junit4]   2> 63440 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7cd38a36[collection1_collection_shard1_replica1] main]
   [junit4]   2> 63440 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63440 INFO  
(searcherExecutor-411-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:50450_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@7c69c4bf[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C2)))}
   [junit4]   2> 63440 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 63440 INFO  
(searcherExecutor-410-thread-1-processing-n:127.0.0.1:59928_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:59928_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@7cd38a36[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.7.0):C3)))}
   [junit4]   2> 63440 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 5
   [junit4]   2> 63441 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{commit=}
 0 10
   [junit4]   2> 63445 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[17F9173E2B67B476])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 63447 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[17F9173E2B67B476])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:52245/solr ready
   [junit4]   2> 63449 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 63449 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=0
   [junit4]   2> 63453 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=0
   [junit4]   2> 63453 INFO  (qtp898352896-679) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 63456 INFO  (qtp898352896-679) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=id:(0+3+4)&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=1 status=0 QTime=0
   [junit4]   2> 63456 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=id:(0+3+4)&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 63460 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:hello0&fq=a_s:hello1&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=0 status=0 QTime=1
   [junit4]   2> 63461 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:hello0&fq=a_s:hello1&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=0 status=0 QTime=0
   [junit4]   2> 63464 INFO  (qtp898352896-706) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:(hello0+OR+hello1)&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=0 status=0 QTime=0
   [junit4]   2> 63465 INFO  (qtp780146536-673) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:(hello0+OR+hello1)&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 63466 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 0x15c9d30e6750010, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 63467 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[17F9173E2B67B476])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCloudSolrStreamWithZkHost
   [junit4]   2> 63490 INFO  
(TEST-StreamExpressionTest.testConvolution-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testConvolution
   [junit4]   2> 63493 INFO  (qtp898352896-706) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7f3156fa[collection1_collection_shard2_replica1] realtime]
   [junit4]   2> 63494 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@30a8e666[collection1_collection_shard1_replica1] realtime]
   [junit4]   2> 63495 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007380393984)} 0 1
   [junit4]   2> 63497 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63497 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d8f2627
   [junit4]   2> 63497 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63497 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@306e0e61
   [junit4]   2> 63498 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2635c8ed[collection1_collection_shard2_replica1] main]
   [junit4]   2> 63498 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4debdd68[collection1_collection_shard1_replica1] main]
   [junit4]   2> 63498 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63498 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63498 INFO  
(searcherExecutor-411-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:50450_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@2635c8ed[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 63498 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 63499 INFO  
(searcherExecutor-410-thread-1-processing-n:127.0.0.1:59928_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:59928_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@4debdd68[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 63499 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 63500 INFO  (qtp898352896-706) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007378296832),commit=} 0 7
   [junit4]   2> 63513 INFO  (qtp898352896-703) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[id_1 
(1570018007388782592), id_3 (1570018007389831168), id_4 (1570018007389831169), 
id_5 (1570018007389831170), id_6 (1570018007389831171), id_7 
(1570018007389831172), id_8 (1570018007389831173), id_9 (1570018007390879744), 
id_11 (1570018007390879745), id_12 (1570018007390879746), ... (125 adds)]} 0 11
   [junit4]   2> 63524 INFO  (qtp780146536-671) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&wt=javabin&version=2}{add=[id_2 
(1570018007396122624), id_10 (1570018007402414080), id_13 
(1570018007402414081), id_14 (1570018007402414082), id_16 
(1570018007402414083), id_17 (1570018007402414084), id_18 
(1570018007402414085), id_21 (1570018007402414086), id_22 
(1570018007402414087), id_23 (1570018007402414088), ... (125 adds)]} 0 16
   [junit4]   2> 63528 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63528 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63528 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d8f2627
   [junit4]   2> 63528 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@306e0e61
   [junit4]   2> 63531 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@30bf6f46[collection1_collection_shard2_replica1] main]
   [junit4]   2> 63532 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63532 INFO  
(searcherExecutor-411-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:50450_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@30bf6f46[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):C125)))}
   [junit4]   2> 63532 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 4
   [junit4]   2> 63533 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@40e40a2d[collection1_collection_shard1_replica1] main]
   [junit4]   2> 63533 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 63534 INFO  
(searcherExecutor-410-thread-1-processing-n:127.0.0.1:59928_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:59928_solr c:collection1_collection s:shard1 
r:core_node2 x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@40e40a2d[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):C125)))}
   [junit4]   2> 63534 INFO  (qtp780146536-672) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 63535 INFO  (qtp898352896-679) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard2_replica1]  webapp=/solr path=/update 
params={_stateVer_=collection1_collection:3&commit=true&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 63539 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  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> 63543 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.c.ZkStateReader Updated live 
nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 63545 INFO  (qtp898352896-704) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52245/solr ready
   [junit4]   2> 63570 INFO  (qtp780146536-669) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1_collection:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&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=1497285849986&isShard=true&wt=javabin}
 hits=125 status=0 QTime=9
   [junit4]   2> 63570 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1_collection:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&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=1497285849986&isShard=true&wt=javabin}
 hits=125 status=0 QTime=9
   [junit4]   2> 63571 INFO  (qtp780146536-667) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  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_collection:3&field=test_dt&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=24
   [junit4]   2> 63577 INFO  (qtp898352896-680) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1_collection:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&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=1497285850013&isShard=true&wt=javabin}
 hits=125 status=0 QTime=1
   [junit4]   2> 63577 INFO  (qtp780146536-670) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.S.Request 
[collection1_collection_shard1_replica1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1_collection:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&collection=collection1_collection&shard.url=http://127.0.0.1:59928/solr/collection1_collection_shard1_replica1/&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=1497285850013&isShard=true&wt=javabin}
 hits=125 status=0 QTime=1
   [junit4]   2> 63578 INFO  (qtp898352896-705) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.S.Request 
[collection1_collection_shard2_replica1]  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_collection:3&field=test_dt&rows=0&wt=javabin&version=2}
 hits=250 status=0 QTime=5
   [junit4]   2> 63593 INFO  
(TEST-StreamExpressionTest.testConvolution-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testConvolution
   [junit4]   2> 63614 INFO  
(TEST-StreamExpressionTest.testCopyOfRange-seed#[17F9173E2B67B476]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCopyOfRange
   [junit4]   2> 63617 INFO  (qtp898352896-681) [n:127.0.0.1:50450_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@30d26c76[collection1_collection_shard2_replica1] realtime]
   [junit4]   2> 63619 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1e0f312f[collection1_collection_shard1_replica1] realtime]
   [junit4]   2> 63619 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory 
[collection1_collection_shard1_replica1]  webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:50450/solr/collection1_collection_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1570018007510417408)} 0 1
   [junit4]   2> 63621 INFO  (qtp780146536-674) [n:127.0.0.1:59928_solr 
c:collection1_collection s:shard1 r:

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

estCumulativeProbability-seed#[17F9173E2B67B476]) [    ] o.a.s.SolrTestCaseJ4 
###Ending testCumulativeProbability
   [junit4]   2> 134390 INFO  (jetty-closer-161-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@3b03b85c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 134390 INFO  (jetty-closer-161-thread-3) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@142229c5{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 134390 INFO  (jetty-closer-161-thread-2) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@76353cce{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1513940282
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-3) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1510911256
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-2) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=440782375
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 134391 INFO  (jetty-closer-161-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 134392 INFO  (jetty-closer-161-thread-4) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@26fc9e81{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 134393 INFO  (jetty-closer-161-thread-4) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2032941185
   [junit4]   2> 134393 INFO  (jetty-closer-161-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 134393 INFO  (jetty-closer-161-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 134393 INFO  (jetty-closer-161-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 134394 INFO  (coreCloseExecutor-540-thread-1) 
[n:127.0.0.1:59928_solr c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@78f03380
   [junit4]   2> 134395 INFO  (coreCloseExecutor-541-thread-1) 
[n:127.0.0.1:50450_solr c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@ed48877
   [junit4]   2> 134400 INFO  (coreCloseExecutor-540-thread-1) 
[n:127.0.0.1:59928_solr c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.m.SolrMetricManager Closing 
metric reporters for: solr.core.collection1_collection.shard1.replica1
   [junit4]   2> 134401 INFO  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (3)
   [junit4]   2> 134401 INFO  
(zkCallback-180-thread-2-processing-n:127.0.0.1:37882_solr) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (3)
   [junit4]   2> 134403 INFO  
(zkCallback-181-thread-2-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (3)
   [junit4]   2> 134403 INFO  (jetty-closer-161-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@10605532{/solr,null,UNAVAILABLE}
   [junit4]   2> 134403 INFO  (coreCloseExecutor-541-thread-1) 
[n:127.0.0.1:50450_solr c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.m.SolrMetricManager Closing 
metric reporters for: solr.core.collection1_collection.shard2.replica1
   [junit4]   2> 134405 INFO  
(zkCallback-1661-thread-1-processing-n:127.0.0.1:50450_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:50450_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.c.ZkStateReader 
Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 134406 INFO  (jetty-closer-161-thread-4) [    ] 
o.a.s.c.Overseer Overseer 
(id=98126125245726730-127.0.0.1:37882_solr-n_0000000000) closing
   [junit4]   2> 134406 INFO  
(OverseerStateUpdate-98126125245726730-127.0.0.1:37882_solr-n_0000000000) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:37882_solr
   [junit4]   2> 134417 INFO  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (1)
   [junit4]   2> 134417 INFO  
(zkCallback-181-thread-2-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:59928_solr
   [junit4]   2> 135917 WARN  
(zkCallback-179-thread-2-processing-n:127.0.0.1:50450_solr) 
[n:127.0.0.1:50450_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 135917 WARN  
(zkCallback-180-thread-2-processing-n:127.0.0.1:37882_solr) 
[n:127.0.0.1:37882_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 135918 INFO  (jetty-closer-161-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@37559ff4{/solr,null,UNAVAILABLE}
   [junit4]   2> 135918 INFO  (jetty-closer-161-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4e4ee63b{/solr,null,UNAVAILABLE}
   [junit4]   2> 135922 WARN  
(zkCallback-181-thread-1-processing-n:127.0.0.1:59928_solr) 
[n:127.0.0.1:59928_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 135922 INFO  (jetty-closer-161-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@259602d2{/solr,null,UNAVAILABLE}
   [junit4]   2> 135924 ERROR 
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-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> 135924 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:52245 52245
   [junit4]   2> 136739 INFO  (Thread-99) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:52245 52245
   [junit4]   2> 136739 WARN  (Thread-99) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        72      /solr/aliases.json
   [junit4]   2>        72      /solr/clusterprops.json
   [junit4]   2>        12      /solr/configs/conf
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        72      /solr/clusterstate.json
   [junit4]   2>        4       /solr/collections/destination/state.json
   [junit4]   2>        4       
/solr/collections/parallelDestinationCollection/state.json
   [junit4]   2>        4       
/solr/collections/destinationCollection/state.json
   [junit4]   2>        4       /solr/collections/mainCorpus/state.json
   [junit4]   2>        3       
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2>        2       
/solr/collections/collection1_collection/state.json
   [junit4]   2>        2       /solr/collections/workQueue/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        72      /solr/live_nodes
   [junit4]   2>        72      /solr/collections
   [junit4]   2> 
   [junit4]   2> 136740 INFO  
(SUITE-StreamExpressionTest-seed#[17F9173E2B67B476]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_17F9173E2B67B476-001
   [junit4]   2> NOTE: test params are: codec=Lucene62, 
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ar-BH, 
timezone=America/Miquelon
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=181475808,total=306184192
   [junit4]   2> NOTE: All tests run in this JVM: [SineEvaluatorTest, 
SolrExampleStreamingTest, TestCoreAdmin, CommonParamsTest, 
CompoundEvaluatorTest, CloudSolrClientBuilderTest, TermsResponseTest, 
SolrExampleEmbeddedTest, RawValueEvaluatorTest, SolrPingTest, 
GreaterThanEvaluatorTest, SolrExampleXMLTest, CosineEvaluatorTest, 
TestSolrJErrorHandling, NaturalLogEvaluatorTest, 
StreamExpressionToExplanationTest, TestXMLEscaping, 
LBHttpSolrClientBuilderTest, GraphExpressionTest, StreamExpressionTest]
   [junit4] Completed [53/130 (1!)] on J0 in 76.56s, 89 tests, 1 failure, 9 
skipped <<< FAILURES!

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

Reply via email to