Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3468/
Java: 32bit/jdk1.8.0_131 -server -XX:+UseParallelGC

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

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

Stack Trace:
java.lang.AssertionError: expected:<5> but was:<3>
        at 
__randomizedtesting.SeedInfo.seed([7A688049FBE94827:5A82E24967A8A56B]: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:4490)
        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 14189 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/init-core-data-001
   [junit4]   2> 76723 WARN  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=36 numCloses=36
   [junit4]   2> 76723 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 76724 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 76724 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001
   [junit4]   2> 76724 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 76724 INFO  (Thread-447) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 76724 INFO  (Thread-447) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 76726 ERROR (Thread-447) [    ] 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> 76825 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:42956
   [junit4]   2> 76829 INFO  (jetty-launcher-138-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 76829 INFO  (jetty-launcher-138-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 76829 INFO  (jetty-launcher-138-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 76829 INFO  (jetty-launcher-138-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 76831 INFO  (jetty-launcher-138-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@675952{/solr,null,AVAILABLE}
   [junit4]   2> 76832 INFO  (jetty-launcher-138-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1915731{/solr,null,AVAILABLE}
   [junit4]   2> 76832 INFO  (jetty-launcher-138-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@17da83f{/solr,null,AVAILABLE}
   [junit4]   2> 76832 INFO  (jetty-launcher-138-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@48fae1{/solr,null,AVAILABLE}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@11c64a{HTTP/1.1,[http/1.1]}{127.0.0.1:40807}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2dd078{HTTP/1.1,[http/1.1]}{127.0.0.1:44579}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@190fda2{HTTP/1.1,[http/1.1]}{127.0.0.1:36600}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-4) [    ] 
o.e.j.s.Server Started @78704ms
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.e.j.s.Server Started @78704ms
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=44579}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36600}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-1) [    ] 
o.e.j.s.Server Started @78704ms
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@15061cb{HTTP/1.1,[http/1.1]}{127.0.0.1:39664}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=40807}
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-2) [    ] 
o.e.j.s.Server Started @78705ms
   [junit4]   2> 76833 ERROR (jetty-launcher-138-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 76833 ERROR (jetty-launcher-138-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39664}
   [junit4]   2> 76833 ERROR (jetty-launcher-138-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 76833 ERROR (jetty-launcher-138-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T14:49:33.039Z
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 76833 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T14:49:33.039Z
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T14:49:33.040Z
   [junit4]   2> 76834 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T14:49:33.040Z
   [junit4]   2> 76837 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 76837 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 76837 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 76838 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 76848 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 0x15be88970030001, 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> 76849 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 0x15be88970030004, 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> 76850 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 76850 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 76850 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 76851 INFO  (jetty-launcher-138-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42956/solr
   [junit4]   2> 76851 INFO  (jetty-launcher-138-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42956/solr
   [junit4]   2> 76851 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 76852 INFO  (jetty-launcher-138-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42956/solr
   [junit4]   2> 76852 INFO  (jetty-launcher-138-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42956/solr
   [junit4]   2> 76879 INFO  (jetty-launcher-138-thread-2) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 76880 INFO  (jetty-launcher-138-thread-4) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 76880 INFO  (jetty-launcher-138-thread-1) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 76880 INFO  (jetty-launcher-138-thread-3) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 76880 INFO  (jetty-launcher-138-thread-2) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:39664_solr
   [junit4]   2> 76881 INFO  (jetty-launcher-138-thread-4) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:44579_solr
   [junit4]   2> 76881 INFO  (jetty-launcher-138-thread-1) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40807_solr
   [junit4]   2> 76881 INFO  (jetty-launcher-138-thread-3) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36600_solr
   [junit4]   2> 76882 INFO  
(zkCallback-160-thread-1-processing-n:127.0.0.1:36600_solr) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 76882 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 76883 INFO  
(zkCallback-157-thread-1-processing-n:127.0.0.1:40807_solr) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 76883 INFO  
(zkCallback-160-thread-1-processing-n:127.0.0.1:36600_solr) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 76883 INFO  (jetty-launcher-138-thread-2) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.Overseer Overseer 
(id=97927493906006027-127.0.0.1:39664_solr-n_0000000000) starting
   [junit4]   2> 76884 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 76884 INFO  
(zkCallback-159-thread-1-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 76889 INFO  (jetty-launcher-138-thread-2) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39664_solr
   [junit4]   2> 76891 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 76892 INFO  
(zkCallback-159-thread-1-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 76898 INFO  
(zkCallback-157-thread-1-processing-n:127.0.0.1:40807_solr) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 76899 INFO  
(zkCallback-160-thread-1-processing-n:127.0.0.1:36600_solr) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 76970 INFO  (jetty-launcher-138-thread-2) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node2/.
   [junit4]   2> 76970 INFO  (jetty-launcher-138-thread-1) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node1/.
   [junit4]   2> 76973 INFO  (jetty-launcher-138-thread-3) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node3/.
   [junit4]   2> 77193 INFO  (jetty-launcher-138-thread-4) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node4/.
   [junit4]   2> 77204 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 77213 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42956/solr ready
   [junit4]   2> 77223 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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> 77224 INFO  
(OverseerThreadFactory-641-thread-1-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1_collection
   [junit4]   2> 77337 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 77338 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 77338 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 77339 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 77443 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_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> 77443 INFO  
(zkCallback-159-thread-1-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_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> 78351 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 78357 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 78362 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78369 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78823 WARN  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78823 WARN  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78824 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78824 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78824 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 78825 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SolrCore [[collection1_collection_shard1_replica1] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node4/collection1_collection_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node4/./collection1_collection_shard1_replica1/data/]
   [junit4]   2> 78831 WARN  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78831 WARN  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78831 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78831 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78832 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 78832 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SolrCore [[collection1_collection_shard2_replica1] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node2/collection1_collection_shard2_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node2/./collection1_collection_shard2_replica1/data/]
   [junit4]   2> 78866 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78866 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78866 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78866 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78867 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 78867 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 78867 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 78867 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 78868 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@eac256[collection1_collection_shard1_replica1] main]
   [junit4]   2> 78868 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2a6e6e[collection1_collection_shard2_replica1] main]
   [junit4]   2> 78870 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 78870 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 78871 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78871 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78871 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 78871 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 78872 INFO  
(searcherExecutor-651-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection) 
[n:127.0.0.1:39664_solr c:collection1_collection s:shard2  
x:collection1_collection_shard2_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard2_replica1] Registered new searcher 
Searcher@2a6e6e[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 78872 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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 1566839904747388928
   [junit4]   2> 78872 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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 1566839904747388928
   [junit4]   2> 78872 INFO  
(searcherExecutor-650-thread-1-processing-n:127.0.0.1:44579_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection) 
[n:127.0.0.1:44579_solr c:collection1_collection s:shard1  
x:collection1_collection_shard1_replica1] o.a.s.c.SolrCore 
[collection1_collection_shard1_replica1] Registered new searcher 
Searcher@eac256[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 78879 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 78879 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78879 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:44579/solr/collection1_collection_shard1_replica1/
   [junit4]   2> 78879 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78879 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1  x:collection1_collection_shard1_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:44579/solr/collection1_collection_shard1_replica1/ has no 
replicas
   [junit4]   2> 78879 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 78879 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 78879 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78879 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:39664/solr/collection1_collection_shard2_replica1/
   [junit4]   2> 78879 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78879 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2  x:collection1_collection_shard2_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:39664/solr/collection1_collection_shard2_replica1/ has no 
replicas
   [junit4]   2> 78879 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 78884 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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:39664/solr/collection1_collection_shard2_replica1/ shard2
   [junit4]   2> 78884 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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:44579/solr/collection1_collection_shard1_replica1/ shard1
   [junit4]   2> 78987 INFO  
(zkCallback-159-thread-1-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_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> 78987 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_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> 79035 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 79035 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 79037 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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=1698
   [junit4]   2> 79037 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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=1699
   [junit4]   2> 79041 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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> 79141 INFO  
(zkCallback-159-thread-1-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_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> 79141 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_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> 79226 INFO  
(OverseerCollectionConfigSetProcessor-97927493906006027-127.0.0.1:39664_solr-n_0000000000)
 [n:127.0.0.1:39664_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> 80041 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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=2818
   [junit4]   2> 80047 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1_collection failOnTimeout:true timeout (sec):90
   [junit4]   2> 80047 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: 
collection1_collection
   [junit4]   2> 80050 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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> 80155 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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=104
   [junit4]   2> 80177 INFO  
(TEST-StreamExpressionTest.testSignificantTermsStream-seed#[7A688049FBE94827]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testSignificantTermsStream
   [junit4]   2> 80199 INFO  (qtp18269522-1224) [n:127.0.0.1:44579_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:39664/solr/collection1_collection_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1566839906136752128)} 0 3
   [junit4]   2> 80203 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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> 80203 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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> 80203 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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> 80204 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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> 80204 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 80204 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 80204 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 80204 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 80205 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_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=*:*
 (-1566839906131509248),commit=} 0 24
   [junit4]   2> 80742 INFO  (qtp18269522-1245) [n:127.0.0.1:44579_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=[a2 
(1566839906190229504), a6 (1566839906205958144), a7 (1566839906211201024), a8 
(1566839906214346752), a9 (1566839906215395328), a11 (1566839906215395329), a13 
(1566839906216443904), a15 (1566839906218541056), a16 (1566839906218541057), 
a17 (1566839906219589632), ... (5900 adds)]} 0 494
   [junit4]   2> 80750 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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=[a0 
(1566839906190229504), a1 (1566839906205958144), a3 (1566839906211201024), a4 
(1566839906214346752), a5 (1566839906214346753), a10 (1566839906215395328), a12 
(1566839906215395329), a14 (1566839906216443904), a18 (1566839906218541056), 
a20 (1566839906218541057), ... (6100 adds)]} 0 502
   [junit4]   2> 80755 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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> 80755 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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> 80756 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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@10feff8
   [junit4]   2> 80756 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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@1eea5b5
   [junit4]   2> 80918 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@30ae93[collection1_collection_shard1_replica1] main]
   [junit4]   2> 80919 INFO  
(searcherExecutor-650-thread-1-processing-n:127.0.0.1:44579_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:44579_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@30ae93[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C5900)))}
   [junit4]   2> 80919 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 80919 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 163
   [junit4]   2> 80924 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2b5eb[collection1_collection_shard2_replica1] main]
   [junit4]   2> 80925 INFO  
(searcherExecutor-651-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:39664_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@2b5eb[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C6100)))}
   [junit4]   2> 80925 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 80925 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 170
   [junit4]   2> 80927 INFO  (qtp13967333-1225) [n:127.0.0.1:39664_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 174
   [junit4]   2> 80932 INFO  
(TEST-StreamExpressionTest.testSignificantTermsStream-seed#[7A688049FBE94827]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 80933 INFO  
(TEST-StreamExpressionTest.testSignificantTermsStream-seed#[7A688049FBE94827]) 
[    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42956/solr 
ready
   [junit4]   2> 80956 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_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={minDocFreq=5.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=18
   [junit4]   2> 80959 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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={minDocFreq=5.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=16
   [junit4]   2> 80968 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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={minDocFreq=5.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=2650.0&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=3
   [junit4]   2> 80972 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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={minDocFreq=5.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=2650.0&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=3
   [junit4]   2> 80983 INFO  (qtp13967333-1225) [n:127.0.0.1:39664_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={minDocFreq=5.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.45&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=6
   [junit4]   2> 80983 INFO  (qtp18269522-1245) [n:127.0.0.1:44579_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={minDocFreq=5.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.45&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=6
   [junit4]   2> 80990 INFO  (qtp13967333-1229) [n:127.0.0.1:39664_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=3
   [junit4]   2> 80996 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=5
   [junit4]   2> 81005 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_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={minDocFreq=0.478&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=4
   [junit4]   2> 81006 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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={minDocFreq=0.478&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=15&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=5
   [junit4]   2> 81014 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=4
   [junit4]   2> 81015 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=4
   [junit4]   2> 81022 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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={minDocFreq=2700.0&minTermLength=2&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.3&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=3
   [junit4]   2> 81022 INFO  (qtp13967333-1225) [n:127.0.0.1:39664_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={minDocFreq=2700.0&minTermLength=2&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.3&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=3
   [junit4]   2> 81035 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=8
   [junit4]   2> 81035 INFO  (qtp13967333-1229) [n:127.0.0.1:39664_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=8
   [junit4]   2> 81043 INFO  (qtp13967333-1217) [n:127.0.0.1:39664_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={myCollection.shards=http://127.0.0.1:39664/solr/collection1_collection_shard2_replica1/,http://127.0.0.1:44579/solr/collection1_collection_shard1_replica1/&expr=significantTerms(myCollection,+q%3D"id:a*",++field%3D"test_t",+limit%3D2,+minDocFreq%3D"2700",+minTermLength%3D1,+maxDocFreq%3D".5")&wt=json&version=2.2}
 status=0 QTime=2
   [junit4]   2> 81053 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2493 status=0 QTime=5
   [junit4]   2> 81053 INFO  (qtp13967333-1229) [n:127.0.0.1:39664_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={minDocFreq=2700.0&minTermLength=1&q=id:a*&distrib=false&field=test_t&numTerms=10&maxDocFreq=0.5&fq={!sigificantTerms}&wt=javabin&version=2}
 hits=2507 status=0 QTime=6
   [junit4]   2> 81059 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_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=significantTerms(myCollection,+q%3D"id:a*",++field%3D"test_t",+limit%3D2,+minDocFreq%3D"2700",+minTermLength%3D1,+maxDocFreq%3D".5")&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 81062 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_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> 81063 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_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:42956/solr ready
   [junit4]   2> 81064 ERROR (qtp13967333-1249) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.c.s.i.s.ExceptionStream 
java.io.IOException: java.io.IOException: Slices not found for myCollection
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.SignificantTermsStream.read(SignificantTermsStream.java:338)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.ExceptionStream.read(ExceptionStream.java:68)
   [junit4]   2>        at 
org.apache.solr.handler.StreamHandler$TimerStream.read(StreamHandler.java:554)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.TupleStream.lambda$writeMap$0(TupleStream.java:82)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeIterator(JSONResponseWriter.java:523)
   [junit4]   2>        at 
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:175)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter$2.put(JSONResponseWriter.java:559)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.TupleStream.writeMap(TupleStream.java:79)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeMap(JSONResponseWriter.java:547)
   [junit4]   2>        at 
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:193)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:209)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:325)
   [junit4]   2>        at 
org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:120)
   [junit4]   2>        at 
org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:71)
   [junit4]   2>        at 
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:809)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:538)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:305)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: java.io.IOException: Slices not found for 
myCollection
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.TupleStream.getSlices(TupleStream.java:179)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.TupleStream.getShards(TupleStream.java:122)
   [junit4]   2>        at 
org.apache.solr.client.solrj.io.stream.SignificantTermsStream.read(SignificantTermsStream.java:285)
   [junit4]   2>        ... 42 more
   [junit4]   2> 
   [junit4]   2> 81067 INFO  
(TEST-StreamExpressionTest.testSignificantTermsStream-seed#[7A688049FBE94827]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testSignificantTermsStream
   [junit4]   2> 81096 INFO  
(TEST-StreamExpressionTest.testParallelNullStream-seed#[7A688049FBE94827]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testParallelNullStream
   [junit4]   2> 81120 INFO  (qtp18269522-1224) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@d8badb[collection1_collection_shard1_replica1] realtime]
   [junit4]   2> 81120 INFO  (qtp13967333-1229) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1a95c03[collection1_collection_shard2_replica1] realtime]
   [junit4]   2> 81120 INFO  (qtp13967333-1229) [n:127.0.0.1:39664_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:44579/solr/collection1_collection_shard1_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1566839907086761984)} 0 19
   [junit4]   2> 81123 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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> 81123 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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@1eea5b5
   [junit4]   2> 81125 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@126e240[collection1_collection_shard1_replica1] main]
   [junit4]   2> 81125 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 81126 INFO  
(searcherExecutor-650-thread-1-processing-n:127.0.0.1:44579_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:44579_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@126e240[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 81126 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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:44579/solr/collection1_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 4
   [junit4]   2> 81128 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 81128 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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@10feff8
   [junit4]   2> 81128 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@e902b9[collection1_collection_shard2_replica1] main]
   [junit4]   2> 81129 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 81129 INFO  
(searcherExecutor-651-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:39664_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@e902b9[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 81129 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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:44579/solr/collection1_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 81130 INFO  (qtp18269522-1224) [n:127.0.0.1:44579_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=*:*
 (-1566839907082567680),commit=} 0 31
   [junit4]   2> 81141 INFO  (qtp13967333-1243) [n:127.0.0.1:39664_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 
(1566839907119267840), 3 (1566839907126607872), 5 (1566839907126607873)]} 0 9
   [junit4]   2> 81141 INFO  (qtp18269522-1235) [n:127.0.0.1:44579_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 
(1566839907118219264), 4 (1566839907126607872), 1 (1566839907126607873)]} 0 8
   [junit4]   2> 81146 INFO  (qtp13967333-1217) [n:127.0.0.1:39664_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> 81146 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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> 81146 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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@1eea5b5
   [junit4]   2> 81146 INFO  (qtp13967333-1217) [n:127.0.0.1:39664_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@10feff8
   [junit4]   2> 81149 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@13ae47d[collection1_collection_shard1_replica1] main]
   [junit4]   2> 81150 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 81150 INFO  
(searcherExecutor-650-thread-1-processing-n:127.0.0.1:44579_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:44579_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@13ae47d[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):C3)))}
   [junit4]   2> 81150 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3
   [junit4]   2> 81151 INFO  (qtp13967333-1217) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1ba9cf4[collection1_collection_shard2_replica1] main]
   [junit4]   2> 81151 INFO  
(searcherExecutor-651-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:39664_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@1ba9cf4[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.6.0):C3)))}
   [junit4]   2> 81151 INFO  (qtp13967333-1217) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 81151 INFO  (qtp13967333-1217) [n:127.0.0.1:39664_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 5
   [junit4]   2> 81153 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_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> 81166 INFO  
(TEST-StreamExpressionTest.testParallelNullStream-seed#[7A688049FBE94827]) [    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 81166 INFO  
(TEST-StreamExpressionTest.testParallelNullStream-seed#[7A688049FBE94827]) [    
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42956/solr ready
   [junit4]   2> 81169 INFO  (qtp13967333-1229) [n:127.0.0.1:39664_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={distrib=false&workerID=0&numWorkers=2&expr=null(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:42956/solr"))&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 81170 INFO  (qtp18269522-1224) [n:127.0.0.1:44579_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=/stream 
params={distrib=false&workerID=1&numWorkers=2&expr=null(search(collection1,q%3D"*:*",fl%3D"id,a_s,a_i,a_f",sort%3D"a_f+asc",partitionKeys%3Did,zkHost%3D"127.0.0.1:42956/solr"))&wt=json&version=2.2}
 status=0 QTime=0
   [junit4]   2> 81181 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_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&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2}
 hits=2 status=0 QTime=8
   [junit4]   2> 81181 INFO  (qtp18269522-1219) [n:127.0.0.1:44579_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&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2}
 hits=1 status=0 QTime=8
   [junit4]   2> 81182 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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&partitionKeys=id&fq={!hash+workers%3D2+worker%3D1}&wt=json&version=2.2}
 hits=2 status=0 QTime=8
   [junit4]   2> 81182 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_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&partitionKeys=id&fq={!hash+workers%3D2+worker%3D0}&wt=json&version=2.2}
 hits=1 status=0 QTime=4
   [junit4]   2> 81205 INFO  
(TEST-StreamExpressionTest.testParallelNullStream-seed#[7A688049FBE94827]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testParallelNullStream
   [junit4]   2> 81229 INFO  
(TEST-StreamExpressionTest.testParallelExecutorStream-seed#[7A688049FBE94827]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testParallelExecutorStream
   [junit4]   2> 81232 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@bbb69c[collection1_collection_shard2_replica1] realtime]
   [junit4]   2> 81235 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5ddda5[collection1_collection_shard1_replica1] realtime]
   [junit4]   2> 81235 INFO  (qtp18269522-1228) [n:127.0.0.1:44579_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:39664/solr/collection1_collection_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1566839907223076864)} 0 2
   [junit4]   2> 81239 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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> 81239 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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@10feff8
   [junit4]   2> 81239 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_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> 81239 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_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@1eea5b5
   [junit4]   2> 81239 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@19249c9[collection1_collection_shard1_replica1] main]
   [junit4]   2> 81240 INFO  
(searcherExecutor-650-thread-1-processing-n:127.0.0.1:44579_solr 
x:collection1_collection_shard1_replica1 s:shard1 c:collection1_collection 
r:core_node2) [n:127.0.0.1:44579_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@19249c9[collection1_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 81240 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_solr 
c:collection1_collection s:shard1 r:core_node2 
x:collection1_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 81240 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@634b62[collection1_collection_shard2_replica1] main]
   [junit4]   2> 81240 INFO  
(searcherExecutor-651-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:39664_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@634b62[collection1_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 81241 INFO  (qtp18269522-1230) [n:127.0.0.1:44579_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 81240 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_solr 
c:collection1_collection s:shard2 r:core_node1 
x:collection1_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 81241 INFO  (qtp13967333-1221) [n:127.0.0.1:39664_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:39664/solr/collection1_collection_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 81242 INFO  (qtp13967333-1246) [n:127.0.0.1:39664_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=*:*
 (-1566839907220979712),commit=} 0 11
   [junit4]   2> 81244 INFO  (qtp13967333-1249) [n:127.0.0.1:39664_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=workQueue&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 81245 INFO  
(OverseerThreadFactory-641-thread-3-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
workQueue
   [junit4]   2> 81246 INFO  
(OverseerCollectionConfigSetProcessor-97927493906006027-127.0.0.1:39664_solr-n_0000000000)
 [n:127.0.0.1:39664_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 81352 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=workQueue_shard2_replica1&action=CREATE&numShards=2&collection=workQueue&shard=shard2&wt=javabin&version=2
   [junit4]   2> 81352 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=workQueue_shard1_replica1&action=CREATE&numShards=2&collection=workQueue&shard=shard1&wt=javabin&version=2
   [junit4]   2> 81352 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 81457 INFO  
(zkCallback-160-thread-1-processing-n:127.0.0.1:36600_solr) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/workQueue/state.json] for collection [workQueue] has occurred 
- updating... (live nodes size: [4])
   [junit4]   2> 81457 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/workQueue/state.json] for collection [workQueue] has occurred 
- updating... (live nodes size: [4])
   [junit4]   2> 82361 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.6.0
   [junit4]   2> 82361 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.6.0
   [junit4]   2> 82369 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.s.IndexSchema 
[workQueue_shard2_replica1] Schema name=test
   [junit4]   2> 82370 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.s.IndexSchema 
[workQueue_shard1_replica1] Schema name=test
   [junit4]   2> 82427 WARN  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 82427 WARN  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.s.IndexSchema Field 
text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 82428 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 82428 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'workQueue_shard1_replica1' using configuration from 
collection workQueue, trusted=true
   [junit4]   2> 82428 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 82428 INFO  (qtp18269522-1238) [n:127.0.0.1:44579_solr 
c:workQueue s:shard1  x:workQueue_shard1_replica1] o.a.s.c.SolrCore 
[[workQueue_shard1_replica1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node4/workQueue_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node4/./workQueue_shard1_replica1/data/]
   [junit4]   2> 82437 WARN  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 82437 WARN  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.s.IndexSchema Field 
text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 82437 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 82437 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'workQueue_shard2_replica1' using configuration from 
collection workQueue, trusted=true
   [junit4]   2> 82438 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 82438 INFO  (qtp6751995-1237) [n:127.0.0.1:36600_solr 
c:workQueue s:shard2  x:workQueue_shard2_replica1] o.a.s.c.SolrCore 
[[workQueue_shard2_replica1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001/tempDir-001/node3/workQueue_shard2_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest

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

298281
   [junit4]   2> 143287 INFO  (jetty-closer-139-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 143287 INFO  (jetty-closer-139-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 143287 INFO  (jetty-closer-139-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 143287 INFO  (jetty-closer-139-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 143287 INFO  (jetty-closer-139-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 143289 INFO  (coreCloseExecutor-781-thread-1) 
[n:127.0.0.1:44579_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@1923ed3
   [junit4]   2> 143290 INFO  
(zkCallback-159-thread-2-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (3)
   [junit4]   2> 143290 INFO  (coreCloseExecutor-781-thread-1) 
[n:127.0.0.1:44579_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> 143290 INFO  (coreCloseExecutor-783-thread-1) 
[n:127.0.0.1:39664_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@1128376
   [junit4]   2> 143291 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (3)
   [junit4]   2> 143291 INFO  
(zkCallback-1609-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:39664_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> 143291 INFO  
(zkCallback-157-thread-1-processing-n:127.0.0.1:40807_solr) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (3)
   [junit4]   2> 143292 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (2)
   [junit4]   2> 143292 INFO  (coreCloseExecutor-783-thread-1) 
[n:127.0.0.1:39664_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> 143293 INFO  
(zkCallback-159-thread-2-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (2)
   [junit4]   2> 143395 INFO  
(zkCallback-159-thread-2-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_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: [2])
   [junit4]   2> 143395 INFO  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_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: [2])
   [junit4]   2> 144207 INFO  
(OverseerCollectionConfigSetProcessor-97927493906006027-127.0.0.1:39664_solr-n_0000000000)
 [n:127.0.0.1:39664_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000050 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 144790 WARN  
(zkCallback-160-thread-1-processing-n:127.0.0.1:36600_solr) 
[n:127.0.0.1:36600_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 144790 INFO  (jetty-closer-139-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@48fae1{/solr,null,UNAVAILABLE}
   [junit4]   2> 144791 WARN  
(zkCallback-1609-thread-1-processing-n:127.0.0.1:39664_solr 
x:collection1_collection_shard2_replica1 s:shard2 c:collection1_collection 
r:core_node1) [n:127.0.0.1:39664_solr c:collection1_collection s:shard2 
r:core_node1 x:collection1_collection_shard2_replica1] o.a.s.c.c.ZkStateReader 
ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = 
Session expired for /live_nodes]
   [junit4]   2> 144791 WARN  
(zkCallback-157-thread-1-processing-n:127.0.0.1:40807_solr) 
[n:127.0.0.1:40807_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 144792 INFO  (jetty-closer-139-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@675952{/solr,null,UNAVAILABLE}
   [junit4]   2> 144792 INFO  (jetty-closer-139-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=97927493906006027-127.0.0.1:39664_solr-n_0000000000) closing
   [junit4]   2> 144792 INFO  
(OverseerStateUpdate-97927493906006027-127.0.0.1:39664_solr-n_0000000000) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:39664_solr
   [junit4]   2> 144793 INFO  
(zkCallback-159-thread-2-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 146293 WARN  
(zkCallback-158-thread-1-processing-n:127.0.0.1:44579_solr) 
[n:127.0.0.1:44579_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 146293 INFO  (jetty-closer-139-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1915731{/solr,null,UNAVAILABLE}
   [junit4]   2> 146293 WARN  
(zkCallback-159-thread-2-processing-n:127.0.0.1:39664_solr) 
[n:127.0.0.1:39664_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 146293 INFO  (jetty-closer-139-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@17da83f{/solr,null,UNAVAILABLE}
   [junit4]   2> 146294 ERROR 
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-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> 146294 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:42956 42956
   [junit4]   2> 146316 INFO  (Thread-447) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:42956 42956
   [junit4]   2> 146317 WARN  (Thread-447) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        67      /solr/aliases.json
   [junit4]   2>        67      /solr/clusterprops.json
   [junit4]   2>        10      /solr/configs/conf
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        67      /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>        2       
/solr/collections/collection1_collection/state.json
   [junit4]   2>        2       /solr/collections/workQueue/state.json
   [junit4]   2>        2       
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        67      /solr/live_nodes
   [junit4]   2>        67      /solr/collections
   [junit4]   2> 
   [junit4]   2> 146317 INFO  
(SUITE-StreamExpressionTest-seed#[7A688049FBE94827]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_7A688049FBE94827-001
   [junit4]   2> May 08, 2017 2:50:42 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: 
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=20340, maxDocsPerChunk=1, blockSize=8), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=20340, blockSize=8)), 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=es-CO, timezone=MIT
   [junit4]   2> NOTE: Linux 4.4.0-75-generic i386/Oracle Corporation 1.8.0_131 
(32-bit)/cpus=12,threads=1,free=86529176,total=263192576
   [junit4]   2> NOTE: All tests run in this JVM: [TestConfigSetAdminRequest, 
SolrExampleXMLTest, NoOpResponseParserTest, TestCollectionStateWatchers, 
TestJavaBinCodec, PowerEvaluatorTest, SolrExceptionTest, NamedListTest, 
TestSuggesterResponse, TestRetryUtil, HyperbolicSineEvaluatorTest, 
CompoundEvaluatorTest, SchemaTest, StreamExpressionTest]
   [junit4] Completed [121/130 (1!)] on J2 in 70.12s, 68 tests, 1 failure, 9 
skipped <<< FAILURES!

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

Reply via email to