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

2 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([D1263D4B5F39571E:F1CC5F4BC378BA52]: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:3972)
        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:745)


FAILED:  
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelUpdateStream

Error Message:
Error from server at http://127.0.0.1:51189/solr: collection already exists: 
parallelDestinationCollection

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:51189/solr: collection already exists: 
parallelDestinationCollection
        at 
__randomizedtesting.SeedInfo.seed([D1263D4B5F39571E:B2C87EA9030DAC75]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:435)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:387)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1358)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1109)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1042)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:177)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelUpdateStream(StreamExpressionTest.java:3473)
        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:745)




Build Log:
[...truncated 13873 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/init-core-data-001
   [junit4]   2> 154133 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 154134 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 154135 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001
   [junit4]   2> 154135 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 154137 INFO  (Thread-575) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 154137 INFO  (Thread-575) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 154235 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:35705
   [junit4]   2> 154244 INFO  (jetty-launcher-615-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 154244 INFO  (jetty-launcher-615-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 154244 INFO  (jetty-launcher-615-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 154245 INFO  (jetty-launcher-615-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 154245 INFO  (jetty-launcher-615-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@135538d6{/solr,null,AVAILABLE}
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@26d44871{HTTP/1.1,[http/1.1]}{127.0.0.1:63575}
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.e.j.s.Server Started @157666ms
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=63575}
   [junit4]   2> 154248 ERROR (jetty-launcher-615-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 154248 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-22T22:49:16.587Z
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@33880d0d{/solr,null,AVAILABLE}
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1d45cf36{HTTP/1.1,[http/1.1]}{127.0.0.1:51189}
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.e.j.s.Server Started @157668ms
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=51189}
   [junit4]   2> 154249 ERROR (jetty-launcher-615-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 154249 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-22T22:49:16.588Z
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@aa0a6b{/solr,null,AVAILABLE}
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6f521f4b{HTTP/1.1,[http/1.1]}{127.0.0.1:50996}
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.e.j.s.Server Started @157669ms
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50996}
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1ed9ca69{/solr,null,AVAILABLE}
   [junit4]   2> 154250 ERROR (jetty-launcher-615-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 154250 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-22T22:49:16.590Z
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7b704b85{HTTP/1.1,[http/1.1]}{127.0.0.1:38029}
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.e.j.s.Server Started @157669ms
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38029}
   [junit4]   2> 154251 ERROR (jetty-launcher-615-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 154251 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-22T22:49:16.590Z
   [junit4]   2> 154257 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 154258 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 154258 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 154262 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 154263 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 0x15a6803afbd0003, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 154266 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 154267 INFO  (jetty-launcher-615-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35705/solr
   [junit4]   2> 154269 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 154269 INFO  (jetty-launcher-615-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35705/solr
   [junit4]   2> 154275 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 154275 INFO  (jetty-launcher-615-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35705/solr
   [junit4]   2> 154276 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 154277 INFO  (jetty-launcher-615-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35705/solr
   [junit4]   2> 154280 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 0x15a6803afbd0008, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 154315 INFO  (jetty-launcher-615-thread-3) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 154315 INFO  (jetty-launcher-615-thread-2) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 154315 INFO  (jetty-launcher-615-thread-1) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 154316 INFO  (jetty-launcher-615-thread-3) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:51189_solr
   [junit4]   2> 154317 INFO  (jetty-launcher-615-thread-1) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:63575_solr
   [junit4]   2> 154318 INFO  (jetty-launcher-615-thread-3) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.Overseer Overseer 
(id=97504706984476683-127.0.0.1:51189_solr-n_0000000000) starting
   [junit4]   2> 154318 INFO  (jetty-launcher-615-thread-2) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50996_solr
   [junit4]   2> 154321 INFO  
(zkCallback-636-thread-1-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 154322 INFO  
(zkCallback-637-thread-1-processing-n:127.0.0.1:50996_solr) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 154322 INFO  
(zkCallback-628-thread-1-processing-n:127.0.0.1:63575_solr) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 154324 INFO  (jetty-launcher-615-thread-4) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 154324 INFO  
(zkCallback-635-thread-1-processing-n:127.0.0.1:38029_solr) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 154329 INFO  (jetty-launcher-615-thread-4) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38029_solr
   [junit4]   2> 154340 INFO  
(zkCallback-636-thread-1-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 154340 INFO  
(zkCallback-628-thread-1-processing-n:127.0.0.1:63575_solr) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 154344 INFO  
(zkCallback-637-thread-1-processing-n:127.0.0.1:50996_solr) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 154346 INFO  
(zkCallback-635-thread-1-processing-n:127.0.0.1:38029_solr) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 154364 INFO  (jetty-launcher-615-thread-3) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:51189_solr
   [junit4]   2> 154385 INFO  
(zkCallback-636-thread-1-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 154387 INFO  
(zkCallback-637-thread-1-processing-n:127.0.0.1:50996_solr) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 154389 INFO  
(zkCallback-635-thread-1-processing-n:127.0.0.1:38029_solr) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 154411 INFO  
(zkCallback-628-thread-1-processing-n:127.0.0.1:63575_solr) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 154427 INFO  (jetty-launcher-615-thread-4) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node4/.
   [junit4]   2> 154437 INFO  (jetty-launcher-615-thread-1) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node1/.
   [junit4]   2> 154548 INFO  (jetty-launcher-615-thread-3) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node3/.
   [junit4]   2> 154556 INFO  (jetty-launcher-615-thread-2) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node2/.
   [junit4]   2> 154567 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 0x15a6803afbd000d, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 154572 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 154574 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35705/solr ready
   [junit4]   2> 154589 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 154594 INFO  
(OverseerThreadFactory-816-thread-1-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 154720 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2
   [junit4]   2> 154721 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2
   [junit4]   2> 154834 INFO  
(zkCallback-636-thread-1-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 154834 INFO  
(zkCallback-635-thread-1-processing-n:127.0.0.1:38029_solr) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 155743 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 155745 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 155752 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.s.IndexSchema 
[collection1_shard1_replica1] Schema name=test
   [junit4]   2> 155753 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.s.IndexSchema 
[collection1_shard2_replica1] Schema name=test
   [junit4]   2> 155801 WARN  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.s.IndexSchema 
[collection1_shard1_replica1] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 155801 WARN  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.s.IndexSchema 
[collection1_shard2_replica1] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 155802 WARN  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.s.IndexSchema 
Field lowerfilt1and2 is not multivalued and destination for multiple copyFields 
(2)
   [junit4]   2> 155802 WARN  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.s.IndexSchema 
Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 155802 WARN  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.s.IndexSchema 
Field lowerfilt1and2 is not multivalued and destination for multiple copyFields 
(2)
   [junit4]   2> 155802 WARN  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.s.IndexSchema 
Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 155802 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 155802 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 155804 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica1' using configuration from 
collection collection1
   [junit4]   2> 155804 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard2_replica1' using configuration from 
collection collection1
   [junit4]   2> 155804 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.SolrCore 
[[collection1_shard1_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node4/collection1_shard1_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node4/./collection1_shard1_replica1/data/]
   [junit4]   2> 155804 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.SolrCore 
[[collection1_shard2_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node3/collection1_shard2_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node3/./collection1_shard2_replica1/data/]
   [junit4]   2> 155860 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 155860 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 155860 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 155860 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 155861 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 155861 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 155861 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 155861 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 155861 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@ded4d43[collection1_shard2_replica1] main]
   [junit4]   2> 155861 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@46a7f9de[collection1_shard1_replica1] main]
   [junit4]   2> 155864 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 155864 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 155865 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 155865 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 155865 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 155865 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 155865 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1560075313562517504
   [junit4]   2> 155865 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1560075313562517504
   [junit4]   2> 155867 INFO  
(searcherExecutor-826-thread-1-processing-n:127.0.0.1:51189_solr 
x:collection1_shard2_replica1 s:shard2 c:collection1) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.SolrCore 
[collection1_shard2_replica1] Registered new searcher 
Searcher@ded4d43[collection1_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 155867 INFO  
(searcherExecutor-825-thread-1-processing-n:127.0.0.1:38029_solr 
x:collection1_shard1_replica1 s:shard1 c:collection1) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.SolrCore 
[collection1_shard1_replica1] Registered new searcher 
Searcher@46a7f9de[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 155879 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 155879 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 155879 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:38029/solr/collection1_shard1_replica1/
   [junit4]   2> 155879 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 155879 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.SyncStrategy 
http://127.0.0.1:38029/solr/collection1_shard1_replica1/ has no replicas
   [junit4]   2> 155879 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 155879 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 155879 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 155879 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:51189/solr/collection1_shard2_replica1/
   [junit4]   2> 155879 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 155879 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.SyncStrategy 
http://127.0.0.1:51189/solr/collection1_shard2_replica1/ has no replicas
   [junit4]   2> 155879 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 155889 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:38029/solr/collection1_shard1_replica1/ shard1
   [junit4]   2> 155891 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:51189/solr/collection1_shard2_replica1/ shard2
   [junit4]   2> 155997 INFO  
(zkCallback-636-thread-1-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 155997 INFO  
(zkCallback-635-thread-1-processing-n:127.0.0.1:38029_solr) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 156052 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 156052 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 156056 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1  x:collection1_shard1_replica1] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2}
 status=0 QTime=1341
   [junit4]   2> 156056 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2  x:collection1_shard2_replica1] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2}
 status=0 QTime=1336
   [junit4]   2> 156062 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_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> 156166 INFO  
(zkCallback-635-thread-1-processing-n:127.0.0.1:38029_solr) 
[n:127.0.0.1:38029_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 156166 INFO  
(zkCallback-636-thread-2-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 156600 INFO  
(OverseerCollectionConfigSetProcessor-97504706984476683-127.0.0.1:51189_solr-n_0000000000)
 [n:127.0.0.1:51189_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> 157063 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=2475
   [junit4]   2> 157064 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):90
   [junit4]   2> 157065 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 157097 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCloudSolrStreamWithZkHost
   [junit4]   2> 157113 INFO  (qtp1333076012-2285) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:51189/solr/collection1_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1560075314864848896)} 0 6
   [junit4]   2> 157117 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157117 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 157118 INFO  (qtp1617257867-2295) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157118 INFO  (qtp1617257867-2295) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 157118 INFO  (qtp1617257867-2295) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157119 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157119 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:51189/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 157119 INFO  (qtp1617257867-2295) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:51189/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 157120 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1]  webapp=/solr 
path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1560075314858557440),commit=} 0 19
   [junit4]   2> 157127 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0 
(1560075314883723264), 4 (1560075314885820416), 1 (1560075314885820417)]} 0 2
   [junit4]   2> 157129 INFO  (qtp1617257867-2293) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1]  webapp=/solr 
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2 
(1560075314886868992), 3 (1560075314887917568)]} 0 1
   [junit4]   2> 157139 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157139 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157139 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@301b5318
   [junit4]   2> 157139 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b6107
   [junit4]   2> 157146 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@59208d7a[collection1_shard1_replica1] main]
   [junit4]   2> 157146 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157147 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@58962f6a[collection1_shard2_replica1] main]
   [junit4]   2> 157148 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157148 INFO  
(searcherExecutor-825-thread-1-processing-n:127.0.0.1:38029_solr 
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node1) 
[n:127.0.0.1:38029_solr c:collection1 s:shard1 r:core_node1 
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] 
Registered new searcher Searcher@59208d7a[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C3)))}
   [junit4]   2> 157148 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:38029/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 9
   [junit4]   2> 157149 INFO  
(searcherExecutor-826-thread-1-processing-n:127.0.0.1:51189_solr 
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node2) 
[n:127.0.0.1:51189_solr c:collection1 s:shard2 r:core_node2 
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] 
Registered new searcher Searcher@58962f6a[collection1_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C2)))}
   [junit4]   2> 157149 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:38029/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 10
   [junit4]   2> 157150 INFO  (qtp1333076012-2290) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 17
   [junit4]   2> 157169 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157172 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:35705/solr ready
   [junit4]   2> 157181 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=1
   [junit4]   2> 157181 INFO  (qtp1333076012-2285) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=1
   [junit4]   2> 157190 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157191 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:35705/solr ready
   [junit4]   2> 157194 INFO  (qtp1617257867-2293) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=1
   [junit4]   2> 157195 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=0
   [junit4]   2> 157202 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157204 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:35705/solr ready
   [junit4]   2> 157207 INFO  (qtp1617257867-2300) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/select 
params={q=id:(0+3+4)&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=1 status=0 QTime=0
   [junit4]   2> 157208 INFO  (qtp1333076012-2285) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/select 
params={q=id:(0+3+4)&distrib=false&fl=id,a_s,a_i,a_f&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=1
   [junit4]   2> 157216 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157217 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:35705/solr ready
   [junit4]   2> 157222 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:hello0&fq=a_s:hello1&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=0 status=0 QTime=1
   [junit4]   2> 157222 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:hello0&fq=a_s:hello1&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=0 status=0 QTime=1
   [junit4]   2> 157230 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157233 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:35705/solr ready
   [junit4]   2> 157237 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:(hello0+OR+hello1)&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=0 status=0 QTime=0
   [junit4]   2> 157237 INFO  (qtp1333076012-2290) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/select 
params={q=id:(*)&distrib=false&fl=id,a_s,a_i,a_f&fq=a_s:(hello0+OR+hello1)&sort=a_f+asc,+a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 157241 INFO  
(TEST-StreamExpressionTest.testCloudSolrStreamWithZkHost-seed#[D1263D4B5F39571E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCloudSolrStreamWithZkHost
   [junit4]   2> 157280 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testNulls
   [junit4]   2> 157290 INFO  (qtp1617257867-2300) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3aa59ccb[collection1_shard2_replica1] realtime]
   [junit4]   2> 157293 INFO  (qtp1333076012-2285) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@393034d9[collection1_shard1_replica1] realtime]
   [junit4]   2> 157293 INFO  (qtp1333076012-2285) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:51189/solr/collection1_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1560075315055689728)} 0 3
   [junit4]   2> 157296 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157296 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b6107
   [junit4]   2> 157296 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157296 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@301b5318
   [junit4]   2> 157296 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@77ebbc31[collection1_shard1_replica1] main]
   [junit4]   2> 157296 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@227d1b71[collection1_shard2_replica1] main]
   [junit4]   2> 157296 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157296 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157296 INFO  
(searcherExecutor-825-thread-1-processing-n:127.0.0.1:38029_solr 
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node1) 
[n:127.0.0.1:38029_solr c:collection1 s:shard1 r:core_node1 
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] 
Registered new searcher Searcher@77ebbc31[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 157297 INFO  
(searcherExecutor-826-thread-1-processing-n:127.0.0.1:51189_solr 
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node2) 
[n:127.0.0.1:51189_solr c:collection1 s:shard2 r:core_node2 
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] 
Registered new searcher Searcher@227d1b71[collection1_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 157297 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:51189/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 157297 INFO  (qtp1617257867-2297) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:51189/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 157297 INFO  (qtp1617257867-2300) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1]  webapp=/solr 
path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1560075315053592576),commit=} 0 10
   [junit4]   2> 157302 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1]  webapp=/solr 
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2 
(1560075315067224064), 3 (1560075315069321216)]} 0 2
   [junit4]   2> 157302 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0 
(1560075315067224064), 4 (1560075315069321216), 1 (1560075315069321217)]} 0 2
   [junit4]   2> 157307 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157307 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@301b5318
   [junit4]   2> 157312 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1094dec2[collection1_shard2_replica1] main]
   [junit4]   2> 157312 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157312 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b6107
   [junit4]   2> 157312 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157312 INFO  
(searcherExecutor-826-thread-1-processing-n:127.0.0.1:51189_solr 
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node2) 
[n:127.0.0.1:51189_solr c:collection1 s:shard2 r:core_node2 
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] 
Registered new searcher Searcher@1094dec2[collection1_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.5.0):C2)))}
   [junit4]   2> 157312 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:38029/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 5
   [junit4]   2> 157318 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5ec11df9[collection1_shard1_replica1] main]
   [junit4]   2> 157318 INFO  
(searcherExecutor-825-thread-1-processing-n:127.0.0.1:38029_solr 
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node1) 
[n:127.0.0.1:38029_solr c:collection1 s:shard1 r:core_node1 
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] 
Registered new searcher Searcher@5ec11df9[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.5.0):C3)))}
   [junit4]   2> 157318 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157318 INFO  (qtp1333076012-2289) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:38029/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 157319 INFO  (qtp1333076012-2276) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 14
   [junit4]   2> 157324 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157325 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35705/solr ready
   [junit4]   2> 157329 INFO  (qtp1617257867-2296) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/export 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f,+s_multi,+i_multi&sort=a_i+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 157329 INFO  (qtp1333076012-2288) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/export 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f,+s_multi,+i_multi&sort=a_i+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=0
   [junit4]   2> 157340 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157342 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35705/solr ready
   [junit4]   2> 157346 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/export 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f,+s_multi,+i_multi&sort=a_s+asc&wt=json&version=2.2}
 hits=3 status=0 QTime=0
   [junit4]   2> 157346 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/export 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f,+s_multi,+i_multi&sort=a_s+asc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 157355 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 157357 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35705/solr ready
   [junit4]   2> 157360 INFO  (qtp1617257867-2280) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.c.S.Request [collection1_shard2_replica1]  webapp=/solr path=/export 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f,+s_multi,+i_multi&sort=a_s+desc&wt=json&version=2.2}
 hits=2 status=0 QTime=0
   [junit4]   2> 157360 INFO  (qtp1333076012-2290) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.c.S.Request [collection1_shard1_replica1]  webapp=/solr path=/export 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f,+s_multi,+i_multi&sort=a_s+desc&wt=json&version=2.2}
 hits=3 status=0 QTime=0
   [junit4]   2> 157366 INFO  
(TEST-StreamExpressionTest.testNulls-seed#[D1263D4B5F39571E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testNulls
   [junit4]   2> 157400 INFO  
(TEST-StreamExpressionTest.testParallelDaemonUpdateStream-seed#[D1263D4B5F39571E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testParallelDaemonUpdateStream
   [junit4]   2> 157405 INFO  (qtp1617257867-2300) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1fd1c791[collection1_shard2_replica1] realtime]
   [junit4]   2> 157408 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4ebf13cd[collection1_shard1_replica1] realtime]
   [junit4]   2> 157408 INFO  (qtp1617257867-2300) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:38029/solr/collection1_shard1_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1560075315176275968)} 0 3
   [junit4]   2> 157416 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157416 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b6107
   [junit4]   2> 157417 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 157417 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@301b5318
   [junit4]   2> 157417 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2351a106[collection1_shard2_replica1] main]
   [junit4]   2> 157417 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2853c40f[collection1_shard1_replica1] main]
   [junit4]   2> 157417 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157417 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 157417 INFO  
(searcherExecutor-826-thread-1-processing-n:127.0.0.1:51189_solr 
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node2) 
[n:127.0.0.1:51189_solr c:collection1 s:shard2 r:core_node2 
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1] 
Registered new searcher Searcher@2351a106[collection1_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 157417 INFO  
(searcherExecutor-825-thread-1-processing-n:127.0.0.1:38029_solr 
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node1) 
[n:127.0.0.1:38029_solr c:collection1 s:shard1 r:core_node1 
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1] 
Registered new searcher Searcher@2853c40f[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 157417 INFO  (qtp1617257867-2299) [n:127.0.0.1:51189_solr 
c:collection1 s:shard2 r:core_node2 x:collection1_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:38029/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 157418 INFO  (qtp1333076012-2286) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_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:38029/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 157418 INFO  (qtp1333076012-2287) [n:127.0.0.1:38029_solr 
c:collection1 s:shard1 r:core_node1 x:collection1_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1560075315174178816),commit=} 0 16
   [junit4]   2> 157428 INFO  (qtp1617257867-2293) [n:127.0.0.1:51189_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=parallelDestinationCollection1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 157431 INFO  
(OverseerThreadFactory-816-thread-2-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
parallelDestinationCollection1
   [junit4]   2> 157642 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=parallelDestinationCollection1_shard2_replica1&action=CREATE&numShards=2&collection=parallelDestinationCollection1&shard=shard2&wt=javabin&version=2
   [junit4]   2> 157642 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=parallelDestinationCollection1_shard1_replica1&action=CREATE&numShards=2&collection=parallelDestinationCollection1&shard=shard1&wt=javabin&version=2
   [junit4]   2> 157762 INFO  
(zkCallback-637-thread-1-processing-n:127.0.0.1:50996_solr) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/parallelDestinationCollection1/state.json] for collection 
[parallelDestinationCollection1] has occurred - updating... (live nodes size: 
[4])
   [junit4]   2> 157762 INFO  
(zkCallback-628-thread-1-processing-n:127.0.0.1:63575_solr) 
[n:127.0.0.1:63575_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/parallelDestinationCollection1/state.json] for collection 
[parallelDestinationCollection1] has occurred - updating... (live nodes size: 
[4])
   [junit4]   2> 158662 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 158670 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.s.IndexSchema 
[parallelDestinationCollection1_shard2_replica1] Schema name=test
   [junit4]   2> 158686 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 158736 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.s.IndexSchema 
[parallelDestinationCollection1_shard1_replica1] Schema name=test
   [junit4]   2> 158747 WARN  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.s.IndexSchema 
[parallelDestinationCollection1_shard2_replica1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 158748 WARN  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 158748 WARN  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.s.IndexSchema Field 
text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 158748 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 158753 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'parallelDestinationCollection1_shard2_replica1' using 
configuration from collection parallelDestinationCollection1
   [junit4]   2> 158753 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.SolrCore 
[[parallelDestinationCollection1_shard2_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node2/parallelDestinationCollection1_shard2_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node2/./parallelDestinationCollection1_shard2_replica1/data/]
   [junit4]   2> 158784 WARN  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.s.IndexSchema 
[parallelDestinationCollection1_shard1_replica1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 158785 WARN  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.s.IndexSchema Field 
lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 158785 WARN  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.s.IndexSchema Field 
text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 158785 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 158786 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'parallelDestinationCollection1_shard1_replica1' using 
configuration from collection parallelDestinationCollection1
   [junit4]   2> 158786 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.c.SolrCore 
[[parallelDestinationCollection1_shard1_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node1/parallelDestinationCollection1_shard1_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001/tempDir-001/node1/./parallelDestinationCollection1_shard1_replica1/data/]
   [junit4]   2> 158810 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 158810 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 158811 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 158811 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 158812 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@6595fa7b[parallelDestinationCollection1_shard2_replica1] main]
   [junit4]   2> 158814 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 158814 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 158814 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 158814 INFO  
(searcherExecutor-835-thread-1-processing-n:127.0.0.1:50996_solr 
x:parallelDestinationCollection1_shard2_replica1 s:shard2 
c:parallelDestinationCollection1) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.SolrCore 
[parallelDestinationCollection1_shard2_replica1] Registered new searcher 
Searcher@6595fa7b[parallelDestinationCollection1_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 158815 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1560075316655816704
   [junit4]   2> 158825 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 158825 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 158825 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.SyncStrategy Sync 
replicas to 
http://127.0.0.1:50996/solr/parallelDestinationCollection1_shard2_replica1/
   [junit4]   2> 158825 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 158825 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] o.a.s.c.SyncStrategy 
http://127.0.0.1:50996/solr/parallelDestinationCollection1_shard2_replica1/ has 
no replicas
   [junit4]   2> 158826 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 158833 INFO  (qtp278683132-2277) [n:127.0.0.1:50996_solr 
c:parallelDestinationCollection1 s:shard2  
x:parallelDestinationCollection1_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:50996/solr/parallelDestinationCollection1_shard2_replica1/ 
shard2
   [junit4]   2> 158836 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 158836 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 158837 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 158837 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 158838 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@3a1a7f61[parallelDestinationCollection1_shard1_replica1] main]
   [junit4]   2> 158839 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 158840 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 158840 INFO  (qtp411391374-2264) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 158840 INFO  
(searcherExecutor-836-thread-1-processing-n:127.0.0.1:63575_solr 
x:parallelDestinationCollection1_shard1_replica1 s:shard1 
c:parallelDestinationCollection1) [n:127.0.0.1:63575_solr 
c:parallelDestinationCollection1 s:shard1  
x:parallelDestinationCollection1_shard1_replica1] o.a.s.c.SolrCore 
[parallelDestinationCollection1_shard1_replica1] Registered new searcher 
Searcher@3a1a7f6

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

estinationCollection.shard2.replica1
   [junit4]   2> 252040 INFO  (coreCloseExecutor-1005-thread-1) 
[n:127.0.0.1:51189_solr c:collection1 s:shard2 r:core_node2 
x:collection1_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.core.collection1.shard2.replica1
   [junit4]   2> 252048 INFO  (jetty-closer-616-thread-3) [    ] 
o.a.s.c.Overseer Overseer 
(id=97504706984476683-127.0.0.1:51189_solr-n_0000000000) closing
   [junit4]   2> 252049 INFO  
(OverseerStateUpdate-97504706984476683-127.0.0.1:51189_solr-n_0000000000) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:51189_solr
   [junit4]   2> 252052 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 0x15a6803afbd0007, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 252052 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 0x15a6803afbd000a, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 252055 INFO  (jetty-closer-616-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 252056 INFO  (jetty-closer-616-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1ed9ca69{/solr,null,UNAVAILABLE}
   [junit4]   2> 252057 INFO  (jetty-closer-616-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 252059 INFO  
(zkCallback-637-thread-3-processing-n:127.0.0.1:50996_solr) 
[n:127.0.0.1:50996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (2)
   [junit4]   2> 252059 INFO  (jetty-closer-616-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@135538d6{/solr,null,UNAVAILABLE}
   [junit4]   2> 252070 INFO  
(zkCallback-636-thread-9-processing-n:127.0.0.1:51189_solr) 
[n:127.0.0.1:51189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (2)
   [junit4]   2> 252070 INFO  (coreCloseExecutor-1008-thread-1) 
[n:127.0.0.1:50996_solr c:parallelDestinationCollection s:shard1 r:core_node2 
x:parallelDestinationCollection_shard1_replica1] o.a.s.m.SolrMetricManager 
Closing metric reporters for: 
solr.core.parallelDestinationCollection.shard1.replica1
   [junit4]   2> 252080 INFO  (jetty-closer-616-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 252080 INFO  (jetty-closer-616-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@aa0a6b{/solr,null,UNAVAILABLE}
   [junit4]   2> 252083 INFO  (jetty-closer-616-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 252084 INFO  (jetty-closer-616-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@33880d0d{/solr,null,UNAVAILABLE}
   [junit4]   2> 252089 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35705 35705
   [junit4]   2> 252123 INFO  (Thread-575) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35705 35705
   [junit4]   2> 252131 WARN  (Thread-575) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        170     /solr/aliases.json
   [junit4]   2>        170     /solr/clusterprops.json
   [junit4]   2>        13      /solr/configs/conf
   [junit4]   2>        7       /solr/configs/ml
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        170     /solr/clusterstate.json
   [junit4]   2>        6       
/solr/collections/destinationCollection/state.json
   [junit4]   2>        4       /solr/collections/destination/state.json
   [junit4]   2>        4       /solr/collections/mainCorpus/state.json
   [junit4]   2>        3       
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2>        2       /solr/collections/collection1/state.json
   [junit4]   2>        2       
/solr/collections/parallelDestinationCollection/state.json
   [junit4]   2>        2       /solr/collections/workQueue/state.json
   [junit4]   2>        2       /solr/collections/uknownCollection/state.json
   [junit4]   2>        2       /solr/collections/modelCollection/state.json
   [junit4]   2>        2       
/solr/collections/checkpointCollection/state.json
   [junit4]   2>        2       
/solr/overseer_elect/election/97504706984476683-127.0.0.1:51189_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        170     /solr/live_nodes
   [junit4]   2>        170     /solr/collections
   [junit4]   2>        163     /solr/overseer/queue
   [junit4]   2>        69      /solr/overseer/collection-queue-work
   [junit4]   2>        29      /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 252131 INFO  
(SUITE-StreamExpressionTest-seed#[D1263D4B5F39571E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_D1263D4B5F39571E-001
   [junit4]   2> Feb 22, 2017 10:50:54 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 5 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{expr_s=Lucene50(blocksize=128), name_s=FST50, iteration_i=FST50, 
terms_ss=BlockTreeOrds(blocksize=128), join2_s=BlockTreeOrds(blocksize=128), 
field_s=PostingsFormat(name=Asserting), 
multiDefault=BlockTreeOrds(blocksize=128), 
subject=BlockTreeOrds(blocksize=128), a_f=PostingsFormat(name=Asserting), 
intDefault=PostingsFormat(name=Asserting), join1_i=Lucene50(blocksize=128), 
falseNegative_i=PostingsFormat(name=Asserting), a_i=FST50, join3_i=FST50, 
falsePositive_i=PostingsFormat(name=Asserting), 
field_i=BlockTreeOrds(blocksize=128), level1_s=Lucene50(blocksize=128), 
body_t=FST50, s_multi=Lucene50(blocksize=128), 
level2_s=PostingsFormat(name=Asserting), a_s=Lucene50(blocksize=128), 
score_f=Lucene50(blocksize=128), tv_text=PostingsFormat(name=Asserting), 
term_s=BlockTreeOrds(blocksize=128), text_s=Lucene50(blocksize=128), id=FST50, 
text=Lucene50(blocksize=128), timestamp=BlockTreeOrds(blocksize=128), 
idfs_ds=BlockTreeOrds(blocksize=128), alpha_d=FST50, i_multi=FST50, 
weights_ds=FST50, featureSet_s=PostingsFormat(name=Asserting), 
idf_d=PostingsFormat(name=Asserting), trueNegative_i=Lucene50(blocksize=128), 
index_i=PostingsFormat(name=Asserting), out_i=PostingsFormat(name=Asserting), 
checkpoint_ss=FST50, ident_s=PostingsFormat(name=Asserting), whitetok=FST50, 
_version_=PostingsFormat(name=Asserting), error_d=Lucene50(blocksize=128), 
side_s=FST50, truePositive_i=Lucene50(blocksize=128)}, 
docValues:{expr_s=DocValuesFormat(name=Lucene54), 
name_s=DocValuesFormat(name=Lucene54), 
iteration_i=DocValuesFormat(name=Lucene54), 
terms_ss=DocValuesFormat(name=Asserting), 
join2_s=DocValuesFormat(name=Asserting), field_s=DocValuesFormat(name=Direct), 
multiDefault=DocValuesFormat(name=Asserting), a_f=DocValuesFormat(name=Direct), 
intDefault=DocValuesFormat(name=Direct), 
join1_i=DocValuesFormat(name=Lucene54), 
falseNegative_i=DocValuesFormat(name=Direct), 
a_i=DocValuesFormat(name=Lucene54), join3_i=DocValuesFormat(name=Lucene54), 
falsePositive_i=DocValuesFormat(name=Direct), 
field_i=DocValuesFormat(name=Asserting), 
level1_s=DocValuesFormat(name=Lucene54), 
s_multi=DocValuesFormat(name=Lucene54), level2_s=DocValuesFormat(name=Direct), 
a_s=DocValuesFormat(name=Lucene54), score_f=DocValuesFormat(name=Lucene54), 
term_s=DocValuesFormat(name=Asserting), text_s=DocValuesFormat(name=Lucene54), 
id=DocValuesFormat(name=Lucene54), i_multi=DocValuesFormat(name=Lucene54), 
featureSet_s=DocValuesFormat(name=Direct), 
trueNegative_i=DocValuesFormat(name=Lucene54), 
index_i=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Direct), 
checkpoint_ss=DocValuesFormat(name=Lucene54), 
ident_s=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Direct), 
side_s=DocValuesFormat(name=Lucene54), 
truePositive_i=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=524, 
maxMBSortInHeap=6.900911803501932, 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=uk, timezone=UCT
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=172235312,total=364904448
   [junit4]   2> NOTE: All tests run in this JVM: [JdbcTest, StreamingTest, 
TestDelegationTokenRequest, CommonAdminParamsTest, TestUpdateRequestCodec, 
CloudSolrClientCacheTest, GetByIdTest, StreamExpressionToExpessionTest, 
TestFastInputStream, SolrExampleStreamingBinaryTest, SolrExampleBinaryTest, 
BasicHttpSolrClientTest, QueryResponseTest, GraphTest, MultiplyEvaluatorTest, 
TestCollectionAdminRequest, CloudSolrClientTest, SchemaTest, ClientUtilsTest, 
HttpSolrClientSSLAuthConPoolTest, GreaterThanEqualToEvaluatorTest, 
HttpSolrClientBuilderTest, TestNamedListCodec, TestRetryUtil, FacetFieldTest, 
CloudSolrClientBuilderTest, ConcurrentUpdateSolrClientBuilderTest, 
ConcurrentUpdateSolrClientTest, HttpSolrClientConPoolTest, JdbcDriverTest, 
JDBCStreamTest, StreamExpressionTest]
   [junit4] Completed [108/108 (1!)] on J1 in 98.78s, 52 tests, 1 failure, 1 
error <<< FAILURES!

[...truncated 61952 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to