Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.3/58/

2 tests failed.
FAILED:  
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testClassifyStream

Error Message:
java.io.IOException: org.apache.solr.common.SolrException: Could not load 
collection from ZK: collection1

Stack Trace:
java.io.IOException: java.io.IOException: org.apache.solr.common.SolrException: 
Could not load collection from ZK: collection1
        at 
__randomizedtesting.SeedInfo.seed([4C71E4C1C7041FF9:E9397EF9FE5C066D]:0)
        at 
org.apache.solr.client.solrj.io.stream.TextLogitStream.read(TextLogitStream.java:503)
        at 
org.apache.solr.client.solrj.io.stream.PushBackStream.read(PushBackStream.java:88)
        at 
org.apache.solr.client.solrj.io.stream.UpdateStream.read(UpdateStream.java:111)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.getTuples(StreamExpressionTest.java:10434)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testClassifyStream(StreamExpressionTest.java:4953)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)
Caused by: java.io.IOException: org.apache.solr.common.SolrException: Could not 
load collection from ZK: collection1
        at 
org.apache.solr.client.solrj.io.stream.TextLogitStream.getShardUrls(TextLogitStream.java:368)
        at 
org.apache.solr.client.solrj.io.stream.TextLogitStream.read(TextLogitStream.java:462)
        ... 43 more
Caused by: org.apache.solr.common.SolrException: Could not load collection from 
ZK: collection1
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1237)
        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:675)
        at 
org.apache.solr.common.cloud.ClusterState$CollectionRef.get(ClusterState.java:386)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionsMap(ClusterState.java:164)
        at 
org.apache.solr.client.solrj.io.stream.CloudSolrStream.getSlices(CloudSolrStream.java:331)
        at 
org.apache.solr.client.solrj.io.stream.TextLogitStream.getShardUrls(TextLogitStream.java:344)
        ... 44 more
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for /collections/collection1/state.json
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
        at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215)
        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340)
        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1249)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1235)
        ... 49 more


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

Error Message:
Could not find collection:destination

Stack Trace:
java.lang.AssertionError: Could not find collection:destination
        at 
__randomizedtesting.SeedInfo.seed([4C71E4C1C7041FF9:6EB1653AE46E35E9]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
        at 
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testExecutorStream(StreamExpressionTest.java:9602)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 15777 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
   [junit4]   2> 0    INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/init-core-data-001
   [junit4]   2> 1667 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 2412 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] o.e.j.u.log 
Logging initialized @22971ms to org.eclipse.jetty.util.log.Slf4jLog
   [junit4]   2> 2482 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 2680 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001
   [junit4]   2> 2737 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2772 INFO  (Thread-1) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2773 INFO  (Thread-1) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 3338 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:42850
   [junit4]   2> 4274 ERROR (Thread-1) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 5092 INFO  (zkConnectionManagerCallback-4-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 5493 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101c93af9040000, likely client has closed socket
   [junit4]   2> 7216 INFO  (jetty-launcher-1-thread-1) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 7316 INFO  (jetty-launcher-1-thread-3) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 7316 INFO  (jetty-launcher-1-thread-4) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 7243 INFO  (jetty-launcher-1-thread-2) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 7776 INFO  (jetty-launcher-1-thread-2) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 7776 INFO  (jetty-launcher-1-thread-2) [    ] o.e.j.s.session 
No SessionScavenger set, using defaults
   [junit4]   2> 7855 INFO  (jetty-launcher-1-thread-2) [    ] o.e.j.s.session 
Scavenging every 600000ms
   [junit4]   2> 7870 INFO  (jetty-launcher-1-thread-3) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 7889 INFO  (jetty-launcher-1-thread-3) [    ] o.e.j.s.session 
No SessionScavenger set, using defaults
   [junit4]   2> 7889 INFO  (jetty-launcher-1-thread-3) [    ] o.e.j.s.session 
Scavenging every 660000ms
   [junit4]   2> 7937 INFO  (jetty-launcher-1-thread-4) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 7938 INFO  (jetty-launcher-1-thread-4) [    ] o.e.j.s.session 
No SessionScavenger set, using defaults
   [junit4]   2> 7938 INFO  (jetty-launcher-1-thread-4) [    ] o.e.j.s.session 
Scavenging every 600000ms
   [junit4]   2> 8067 INFO  (jetty-launcher-1-thread-1) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 8067 INFO  (jetty-launcher-1-thread-1) [    ] o.e.j.s.session 
No SessionScavenger set, using defaults
   [junit4]   2> 8083 INFO  (jetty-launcher-1-thread-1) [    ] o.e.j.s.session 
Scavenging every 660000ms
   [junit4]   2> 8108 INFO  (jetty-launcher-1-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@386dcb18{/solr,null,AVAILABLE}
   [junit4]   2> 8128 INFO  (jetty-launcher-1-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5e5388e0{/solr,null,AVAILABLE}
   [junit4]   2> 8128 INFO  (jetty-launcher-1-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@361e286e{/solr,null,AVAILABLE}
   [junit4]   2> 8109 INFO  (jetty-launcher-1-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5ccad9aa{/solr,null,AVAILABLE}
   [junit4]   2> 8329 INFO  (jetty-launcher-1-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@726b95d8{HTTP/1.1,[http/1.1]}{127.0.0.1:46396}
   [junit4]   2> 8329 INFO  (jetty-launcher-1-thread-4) [    ] o.e.j.s.Server 
Started @29016ms
   [junit4]   2> 8330 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46396}
   [junit4]   2> 8331 INFO  (jetty-launcher-1-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@324c80cc{HTTP/1.1,[http/1.1]}{127.0.0.1:37986}
   [junit4]   2> 8429 INFO  (jetty-launcher-1-thread-3) [    ] o.e.j.s.Server 
Started @29116ms
   [junit4]   2> 8430 INFO  (jetty-launcher-1-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@327fd255{HTTP/1.1,[http/1.1]}{127.0.0.1:36452}
   [junit4]   2> 8430 INFO  (jetty-launcher-1-thread-1) [    ] o.e.j.s.Server 
Started @29117ms
   [junit4]   2> 8429 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37986}
   [junit4]   2> 8430 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36452}
   [junit4]   2> 8510 INFO  (jetty-launcher-1-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7623bc8c{HTTP/1.1,[http/1.1]}{127.0.0.1:33510}
   [junit4]   2> 8510 INFO  (jetty-launcher-1-thread-2) [    ] o.e.j.s.Server 
Started @29197ms
   [junit4]   2> 8510 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=33510}
   [junit4]   2> 8852 ERROR (jetty-launcher-1-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 8853 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.1
   [junit4]   2> 8854 ERROR (jetty-launcher-1-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 8854 ERROR (jetty-launcher-1-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 8854 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.1
   [junit4]   2> 8854 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.1
   [junit4]   2> 8887 ERROR (jetty-launcher-1-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 8888 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.1
   [junit4]   2> 8948 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 8948 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 8961 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 8960 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 8961 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 8960 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 8962 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 8981 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 9166 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-05-03T07:47:37.202Z
   [junit4]   2> 9180 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-05-03T07:47:37.202Z
   [junit4]   2> 9180 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-05-03T07:47:37.202Z
   [junit4]   2> 9180 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-05-03T07:47:37.184Z
   [junit4]   2> 9440 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 9623 INFO  (zkConnectionManagerCallback-6-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 9678 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 9734 INFO  (zkConnectionManagerCallback-12-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 9734 INFO  (zkConnectionManagerCallback-8-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 9850 INFO  (zkConnectionManagerCallback-10-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 9852 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 9871 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 9872 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 13114 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101c93af9040001, likely client has closed socket
   [junit4]   2> 16151 INFO  (jetty-launcher-1-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42850/solr
   [junit4]   2> 16168 INFO  (jetty-launcher-1-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42850/solr
   [junit4]   2> 16168 INFO  (jetty-launcher-1-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42850/solr
   [junit4]   2> 16168 INFO  (jetty-launcher-1-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42850/solr
   [junit4]   2> 16585 INFO  (zkConnectionManagerCallback-22-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 16636 INFO  (zkConnectionManagerCallback-28-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 16688 INFO  (zkConnectionManagerCallback-24-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 16728 INFO  (zkConnectionManagerCallback-26-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 16767 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101c93af9040006, likely client has closed socket
   [junit4]   2> 16858 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101c93af9040008, likely client has closed socket
   [junit4]   2> 17277 INFO  
(zkConnectionManagerCallback-30-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 17301 INFO  
(zkConnectionManagerCallback-32-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 17329 INFO  
(zkConnectionManagerCallback-36-thread-1-processing-n:127.0.0.1:36452_solr) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 17467 INFO  
(zkConnectionManagerCallback-34-thread-1-processing-n:127.0.0.1:37986_solr) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 19336 WARN  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 19495 WARN  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 19496 WARN  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 19496 WARN  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 20066 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 20067 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 20100 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 20101 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:33510_solr
   [junit4]   2> 20139 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 20612 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37986_solr
   [junit4]   2> 20614 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.Overseer Overseer 
(id=72560324137713673-127.0.0.1:33510_solr-n_0000000000) starting
   [junit4]   2> 20628 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46396_solr
   [junit4]   2> 20648 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36452_solr
   [junit4]   2> 21250 INFO  
(zkCallback-33-thread-1-processing-n:127.0.0.1:37986_solr) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 21252 INFO  
(zkCallback-31-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 21252 INFO  
(zkCallback-35-thread-1-processing-n:127.0.0.1:36452_solr) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 21252 INFO  
(zkCallback-29-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 23012 INFO  
(zkConnectionManagerCallback-44-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 23191 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 23264 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:42850/solr ready
   [junit4]   2> 26715 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:33510_solr
   [junit4]   2> 26770 INFO  
(zkCallback-35-thread-1-processing-n:127.0.0.1:36452_solr) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 26770 INFO  
(zkCallback-33-thread-1-processing-n:127.0.0.1:37986_solr) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 26806 INFO  
(OverseerStateUpdate-72560324137713673-127.0.0.1:33510_solr-n_0000000000) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 26823 INFO  
(zkCallback-31-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 26823 INFO  
(zkCallback-43-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 32259 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33510.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32424 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37986.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32352 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46396.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32662 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46396.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32675 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36452.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32681 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46396.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32771 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33510.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32771 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33510.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32781 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36452.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32781 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_36452.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32835 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37986.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 32836 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37986.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 33366 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node3/.
   [junit4]   2> 33402 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node4/.
   [junit4]   2> 33403 INFO  (jetty-launcher-1-thread-2) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node2/.
   [junit4]   2> 33404 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node1/.
   [junit4]   2> 33816 INFO  
(zkConnectionManagerCallback-48-thread-1-processing-n:127.0.0.1:36452_solr) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 33871 INFO  
(zkConnectionManagerCallback-53-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 33953 INFO  
(zkConnectionManagerCallback-56-thread-1-processing-n:127.0.0.1:37986_solr) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 34037 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 34050 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 34049 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 34106 INFO  (jetty-launcher-1-thread-3) 
[n:127.0.0.1:37986_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:42850/solr ready
   [junit4]   2> 34126 INFO  (jetty-launcher-1-thread-1) 
[n:127.0.0.1:36452_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:42850/solr ready
   [junit4]   2> 34144 INFO  (jetty-launcher-1-thread-4) 
[n:127.0.0.1:46396_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:42850/solr ready
   [junit4]   2> 40587 INFO  (zkConnectionManagerCallback-58-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 40766 INFO  (zkConnectionManagerCallback-62-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 40770 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 40804 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42850/solr ready
   [junit4]   2> 44532 INFO  (qtp2002128019-35) [n:127.0.0.1:37986_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 44875 INFO  
(OverseerThreadFactory-20-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 45832 INFO  
(OverseerStateUpdate-72560324137713673-127.0.0.1:33510_solr-n_0000000000) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33510/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 45904 INFO  
(OverseerStateUpdate-72560324137713673-127.0.0.1:33510_solr-n_0000000000) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"collection1_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46396/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 46303 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 46320 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 46359 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 46373 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 46777 INFO  
(zkCallback-31-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 46792 INFO  
(zkCallback-29-thread-2-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 46803 INFO  
(zkCallback-29-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 46887 INFO  
(zkCallback-31-thread-2-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 48105 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.1
   [junit4]   2> 48140 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.1
   [junit4]   2> 49192 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
   [junit4]   2> 49480 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
   [junit4]   2> 53931 WARN  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.GeoHashField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 53942 WARN  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.GeoHashField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 54171 WARN  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.LatLonType]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 54185 WARN  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.LatLonType]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 54371 WARN  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 54371 WARN  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 54384 WARN  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 54384 WARN  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 54386 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 54405 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 54600 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 54603 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 54863 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33510.solr.core.collection1.shard1.replica_n1' (registry 
'solr.core.collection1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 54880 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46396.solr.core.collection1.shard2.replica_n2' (registry 
'solr.core.collection1.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5019f3d6
   [junit4]   2> 54916 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 54932 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 54935 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node2/collection1_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
   [junit4]   2> 54953 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node4/collection1_shard2_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001/tempDir-001/node4/./collection1_shard2_replica_n2/data/]
   [junit4]   2> 63838 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 63838 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 63873 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 63981 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 64130 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 64130 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 64144 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 64218 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 64603 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@644c9559[collection1_shard2_replica_n2] main]
   [junit4]   2> 64630 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@354684e[collection1_shard1_replica_n1] main]
   [junit4]   2> 65030 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 65048 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 65266 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 65282 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 65593 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 65611 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 67686 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1599428326490898432
   [junit4]   2> 67848 INFO  
(searcherExecutor-29-thread-1-processing-n:127.0.0.1:46396_solr 
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) 
[n:127.0.0.1:46396_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore 
[collection1_shard2_replica_n2] Registered new searcher 
Searcher@644c9559[collection1_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 68014 INFO  
(searcherExecutor-30-thread-1-processing-n:127.0.0.1:33510_solr 
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) 
[n:127.0.0.1:33510_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@354684e[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 68021 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1599428326842171392
   [junit4]   2> 68204 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
   [junit4]   2> 68482 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
   [junit4]   2> 68847 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 68847 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 68861 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 68861 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 68909 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/
   [junit4]   2> 68911 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 68911 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.SyncStrategy http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/ 
has no replicas
   [junit4]   2> 68911 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 68911 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/
   [junit4]   2> 68984 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 69002 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 69002 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 69388 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/ shard1
   [junit4]   2> 69389 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/ shard2
   [junit4]   2> 69482 INFO  
(zkCallback-29-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 69482 INFO  
(zkCallback-29-thread-2-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 69482 INFO  
(zkCallback-31-thread-2-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 69482 INFO  
(zkCallback-31-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 69594 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 69681 INFO  (qtp158206842-26) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=23397
   [junit4]   2> 70046 INFO  
(zkCallback-31-thread-2-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 70057 INFO  
(zkCallback-31-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 70082 INFO  
(zkCallback-29-thread-2-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 70082 INFO  
(zkCallback-29-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 70134 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 70221 INFO  (qtp439491524-47) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=23972
   [junit4]   2> 70427 INFO  
(zkCallback-29-thread-1-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 70439 INFO  
(zkCallback-29-thread-3-processing-n:127.0.0.1:33510_solr) 
[n:127.0.0.1:33510_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> 70442 INFO  
(zkCallback-31-thread-1-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 70442 INFO  
(zkCallback-31-thread-2-processing-n:127.0.0.1:46396_solr) 
[n:127.0.0.1:46396_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> 71040 INFO  (qtp2002128019-35) [n:127.0.0.1:37986_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> 71076 INFO  (qtp2002128019-35) [n:127.0.0.1:37986_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=26951
   [junit4]   2> 71182 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):90
   [junit4]   2> 71227 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 71848 INFO  
(TEST-StreamExpressionTest.testReducerStream-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReducerStream
   [junit4]   2> 71950 INFO  
(OverseerCollectionConfigSetProcessor-72560324137713673-127.0.0.1:33510_solr-n_0000000000)
 [n:127.0.0.1:33510_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> 73903 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard2 to Terms{values={core_node4=1}, version=1}
   [junit4]   2> 73923 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428332036816896)} 0 984
   [junit4]   2> 74161 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428333280428032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74161 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 74250 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74251 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428333374799872,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 74321 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 74323 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 74336 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 175
   [junit4]   2> 74336 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 87
   [junit4]   2> 74374 INFO  (qtp158206842-31) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard1 to Terms{values={core_node3=1}, version=1}
   [junit4]   2> 74375 INFO  (qtp158206842-31) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428331946639360),commit=} 0 2414
   [junit4]   2> 75993 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&wt=javabin&version=2}{add=[0 
(1599428333771161600), 4 (1599428335183593472), 1 (1599428335185690624), 8 
(1599428335200370688)]} 0 1400
   [junit4]   2> 76030 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&wt=javabin&version=2}{add=[2 
(1599428333715587072), 3 (1599428334995898368), 5 (1599428335184642048), 6 
(1599428335186739200), 7 (1599428335203516416), 9 (1599428335239168000)]} 0 1473
   [junit4]   2> 76102 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428335315714048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 76103 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428335316762624,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 76123 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@e717a6d 
commitCommandVersion:1599428335315714048
   [junit4]   2> 76126 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2daebf57 
commitCommandVersion:1599428335316762624
   [junit4]   2> 78907 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@397a96a8[collection1_shard2_replica_n2] main]
   [junit4]   2> 78929 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@e68df5b[collection1_shard1_replica_n1] main]
   [junit4]   2> 78978 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 79001 INFO  
(searcherExecutor-29-thread-1-processing-n:127.0.0.1:46396_solr 
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) 
[n:127.0.0.1:46396_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore 
[collection1_shard2_replica_n2] Registered new searcher 
Searcher@397a96a8[collection1_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.1):c6)))}
   [junit4]   2> 79035 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 79035 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2932
   [junit4]   2> 79065 INFO  
(searcherExecutor-30-thread-1-processing-n:127.0.0.1:33510_solr 
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) 
[n:127.0.0.1:33510_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@e68df5b[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.1):c4)))}
   [junit4]   2> 79071 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3003
   [junit4]   2> 79089 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{commit=} 0 
3056
   [junit4]   2> 79483 INFO  (zkConnectionManagerCallback-66-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 79500 INFO  
(TEST-StreamExpressionTest.testReducerStream-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 79518 INFO  
(TEST-StreamExpressionTest.testReducerStream-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42850/solr ready
   [junit4]   2> 80198 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_s+asc,+a_f+asc&wt=json&version=2.2}
 hits=4 status=0 QTime=570
   [junit4]   2> 80216 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.S.Request [collection1_shard2_replica_n2]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_s+asc,+a_f+asc&wt=json&version=2.2}
 hits=6 status=0 QTime=600
   [junit4]   2> 80765 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.c.S.Request [collection1_shard2_replica_n2]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_s+asc,+a_f+++++++asc&wt=json&version=2.2}
 hits=6 status=0 QTime=1
   [junit4]   2> 80851 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&fl=id,a_s,a_i,a_f&sort=a_s+asc,+a_f+++++++asc&wt=json&version=2.2}
 hits=4 status=0 QTime=0
   [junit4]   2> 80907 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101c93af9040013, likely client has closed socket
   [junit4]   2> 80967 INFO  
(TEST-StreamExpressionTest.testReducerStream-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testReducerStream
   [junit4]   2> 81202 INFO  
(TEST-StreamExpressionTest.testAkima-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testAkima
   [junit4]   2> 81688 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@26d3acb3[collection1_shard1_replica_n1] realtime]
   [junit4]   2> 81689 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@52f2662f[collection1_shard2_replica_n2] realtime]
   [junit4]   2> 81690 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428340702248960)} 0 451
   [junit4]   2> 81726 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428341212905472,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 81726 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2daebf57 
commitCommandVersion:1599428341212905472
   [junit4]   2> 81727 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428341213954048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 81727 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@e717a6d 
commitCommandVersion:1599428341213954048
   [junit4]   2> 81740 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4d8f3dab[collection1_shard2_replica_n2] main]
   [junit4]   2> 81909 INFO  
(searcherExecutor-29-thread-1-processing-n:127.0.0.1:46396_solr 
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4) 
[n:127.0.0.1:46396_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore 
[collection1_shard2_replica_n2] Registered new searcher 
Searcher@4d8f3dab[collection1_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 82011 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 82016 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@385b90c8[collection1_shard1_replica_n1] main]
   [junit4]   2> 82249 INFO  
(searcherExecutor-30-thread-1-processing-n:127.0.0.1:33510_solr 
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) 
[n:127.0.0.1:33510_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@385b90c8[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 82296 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 82297 INFO  (qtp439491524-49) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 571
   [junit4]   2> 82406 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 679
   [junit4]   2> 82407 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428340685471744),commit=} 0 1185
   [junit4]   2> 82555 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/stream 
params={indent=off&expr=let(echo%3Dtrue,++++a%3Darray(0,1,2,3,4,5,6,7),+++++b%3Darray(1,70,90,10,78,+100,+1,+9),++++fit%3Dakima(a,+b),+++++der%3Dderivative(fit))&wt=json&version=2.2}
 status=0 QTime=75
   [junit4]   2> 83202 INFO  
(TEST-StreamExpressionTest.testAkima-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testAkima
   [junit4]   2> 83649 INFO  
(TEST-StreamExpressionTest.testGeometricDistribution-seed#[4C71E4C1C7041FF9]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Starting testGeometricDistribution
   [junit4]   2> 83767 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428343315300352)} 0 36
   [junit4]   2> 83800 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428343387652096,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 83800 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 83837 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428343426449408,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 83837 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 83838 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 83908 INFO  (qtp439491524-51) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 109
   [junit4]   2> 83909 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 83909 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:46396/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 106
   [junit4]   2> 83927 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428343270211584),commit=} 0 240
   [junit4]   2> 84084 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/stream 
params={indent=off&expr=let(a%3DgeometricDistribution(.2),+++++++++++++++b%3DgeometricDistribution(.5),+++++++++++++++c%3DgeometricDistribution(.8),+++++++++++++++d%3Dsample(a,+10000),+++++++++++++++e%3Dsample(b,+10000),+++++++++++++++f%3Dsample(c,+10000),+++++++++++++++g%3DfreqTable(d),+++++++++++++++h%3DfreqTable(e),+++++++++++++++i%3DfreqTable(f),+++++++++++++++tuple(g%3Dg,+h%3Dh,+i%3Di))&wt=json&version=2.2}
 status=0 QTime=125
   [junit4]   2> 84963 INFO  
(TEST-StreamExpressionTest.testGeometricDistribution-seed#[4C71E4C1C7041FF9]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Ending testGeometricDistribution
   [junit4]   2> 85390 INFO  
(TEST-StreamExpressionTest.testCalculatorStream-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCalculatorStream
   [junit4]   2> 85557 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428345193299968)} 0 35
   [junit4]   2> 85578 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428345252020224,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 85578 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 85626 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 85627 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 49
   [junit4]   2> 85628 INFO  (qtp158206842-31) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428345304449024,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 85629 INFO  (qtp158206842-31) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 85664 INFO  (qtp158206842-31) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 85664 INFO  (qtp158206842-31) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 36
   [junit4]   2> 85747 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428345189105664),commit=} 0 320
   [junit4]   2> 85764 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/stream 
params={indent=off&expr=select(calc(),+add(1,+1)+as+result)&wt=json&version=2.2}
 status=0 QTime=1
   [junit4]   2> 85850 INFO  
(TEST-StreamExpressionTest.testCalculatorStream-seed#[4C71E4C1C7041FF9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testCalculatorStream
   [junit4]   2> 86318 INFO  
(TEST-StreamExpressionTest.testParallelComplementStream-seed#[4C71E4C1C7041FF9])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testParallelComplementStream
   [junit4]   2> 86508 INFO  (qtp439491524-53) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428346166378496)} 0 180
   [junit4]   2> 86553 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428346274381824,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 86553 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 86575 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599428346297450496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 86575 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 86625 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 86625 INFO  (qtp158206842-27) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 71
   [junit4]   2> 86661 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 86661 INFO  (qtp439491524-46) [n:127.0.0.1:46396_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33510/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 87
   [junit4]   2> 86743 INFO  (qtp158206842-33) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1599428346036355072),commit=} 0 417
   [junit4]   2> 86911 INFO  (qtp158206842-30) [n:127.0.0.1:33510_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:5&wt=javabin&version=2}{add=[0 
(1599428346496679936), 4 (1599428346648723456), 8 (1599428346649772032)]} 0 146
   [junit4]   2> 86962 INFO 

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

   [junit4]   2> 578803 INFO  (coreCloseExecutor-209-thread-4) 
[n:127.0.0.1:33510_solr c:mainCorpus s:shard2 r:core_node4 
x:mainCorpus_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3dee3314: rootName = 
solr_33510, domain = solr.core.mainCorpus.shard2.replica_n3, service url = 
null, agent id = null] for registry solr.core.mainCorpus.shard2.replica_n3 / 
com.codahale.metrics.MetricRegistry@5d145533
   [junit4]   2> 578861 INFO  (coreCloseExecutor-209-thread-4) 
[n:127.0.0.1:33510_solr c:mainCorpus s:shard2 r:core_node4 
x:mainCorpus_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.mainCorpus.shard2.leader, tag=491638297
   [junit4]   2> 578931 INFO  (jetty-closer-2-thread-1) [    ] o.a.s.c.Overseer 
Overseer (id=72560324137713673-127.0.0.1:33510_solr-n_0000000000) closing
   [junit4]   2> 578932 INFO  
(OverseerStateUpdate-72560324137713673-127.0.0.1:33510_solr-n_0000000000) 
[n:127.0.0.1:33510_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:33510_solr
   [junit4]   2> 578951 WARN  
(OverseerAutoScalingTriggerThread-72560324137713673-127.0.0.1:33510_solr-n_0000000000)
 [n:127.0.0.1:33510_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 578970 INFO  (jetty-closer-2-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5e5388e0{/solr,null,UNAVAILABLE}
   [junit4]   2> 578970 INFO  (jetty-closer-2-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@386dcb18{/solr,null,UNAVAILABLE}
   [junit4]   2> 578970 INFO  (jetty-closer-2-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5ccad9aa{/solr,null,UNAVAILABLE}
   [junit4]   2> 578982 INFO  (jetty-closer-2-thread-3) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 578983 INFO  (jetty-closer-2-thread-4) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 578984 INFO  (jetty-closer-2-thread-2) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 578984 INFO  (jetty-closer-2-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@361e286e{/solr,null,UNAVAILABLE}
   [junit4]   2> 578985 INFO  (jetty-closer-2-thread-1) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 578988 ERROR 
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 578989 INFO  
(SUITE-StreamExpressionTest-seed#[4C71E4C1C7041FF9]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:42850 42850
   [junit4]   2> 579236 INFO  (Thread-1) [    ] o.a.s.c.ZkTestServer connecting 
to 127.0.0.1:42850 42850
   [junit4]   2> 579238 WARN  (Thread-1) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        111     /solr/aliases.json
   [junit4]   2>        111     /solr/clusterprops.json
   [junit4]   2>        13      
/solr/collections/destinationCollection/terms/shard2
   [junit4]   2>        12      
/solr/collections/destinationCollection/terms/shard1
   [junit4]   2>        10      /solr/configs/conf
   [junit4]   2>        10      
/solr/collections/parallelDestinationCollection1/terms/shard2
   [junit4]   2>        10      
/solr/collections/parallelDestinationCollection1/terms/shard1
   [junit4]   2>        8       /solr/configs/ml
   [junit4]   2>        6       
/solr/collections/parallelDestinationCollection/terms/shard1
   [junit4]   2>        6       
/solr/collections/parallelDestinationCollection/terms/shard2
   [junit4]   2>        4       /solr/collections/workQueue1/terms/shard2
   [junit4]   2>        4       /solr/collections/destination1/terms/shard1
   [junit4]   2>        4       /solr/collections/destination1/terms/shard2
   [junit4]   2>        4       /solr/collections/mainCorpus1/terms/shard1
   [junit4]   2>        4       /solr/security.json
   [junit4]   2>        3       /solr/collections/workQueue1/terms/shard1
   [junit4]   2>        3       /solr/collections/mainCorpus1/terms/shard2
   [junit4]   2>        3       /solr/collections/modelCollection/terms/shard2
   [junit4]   2>        3       /solr/collections/modelCollection/terms/shard1
   [junit4]   2>        3       
/solr/collections/checkpointCollection/terms/shard1
   [junit4]   2>        3       /solr/collections/collection1/terms/shard1
   [junit4]   2>        3       /solr/collections/collection1/terms/shard2
   [junit4]   2>        3       /solr/collections/uknownCollection/terms/shard2
   [junit4]   2>        3       /solr/collections/uknownCollection/terms/shard1
   [junit4]   2>        2       
/solr/collections/checkpointCollection/terms/shard2
   [junit4]   2>        2       /solr/collections/mainCorpus/terms/shard2
   [junit4]   2>        2       /solr/collections/mainCorpus/terms/shard1
   [junit4]   2>        2       /solr/collections/workQueue/terms/shard2
   [junit4]   2>        2       /solr/collections/workQueue/terms/shard1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        111     /solr/clusterstate.json
   [junit4]   2>        42      
/solr/collections/destinationCollection/state.json
   [junit4]   2>        36      
/solr/collections/parallelDestinationCollection1/state.json
   [junit4]   2>        24      
/solr/collections/parallelDestinationCollection/state.json
   [junit4]   2>        18      /solr/collections/uknownCollection/state.json
   [junit4]   2>        18      /solr/collections/mainCorpus/state.json
   [junit4]   2>        16      /solr/collections/collection1/state.json
   [junit4]   2>        16      /solr/collections/modelCollection/state.json
   [junit4]   2>        14      /solr/collections/workQueue/state.json
   [junit4]   2>        14      
/solr/collections/checkpointCollection/state.json
   [junit4]   2>        12      /solr/collections/mainCorpus1/state.json
   [junit4]   2>        12      /solr/collections/destination1/state.json
   [junit4]   2>        12      /solr/collections/workQueue1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        111     /solr/live_nodes
   [junit4]   2>        109     /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.3/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_4C71E4C1C7041FF9-001
   [junit4]   2> May 03, 2018 7:57:07 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{expr_s=PostingsFormat(name=Asserting), name_s=FSTOrd50, 
terms_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
join2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
field_s=Lucene50(blocksize=128), 
multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
subject=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a1_s=Lucene50(blocksize=128), a2_s=PostingsFormat(name=Asserting), 
level1_s=PostingsFormat(name=Asserting), body_t=FSTOrd50, 
s_multi=PostingsFormat(name=Asserting), 
a_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
level2_s=Lucene50(blocksize=128), col_s=Lucene50(blocksize=128), 
a_s=PostingsFormat(name=Asserting), tv_text=Lucene50(blocksize=128), 
term_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
text_s=PostingsFormat(name=Asserting), id=FSTOrd50, 
text=PostingsFormat(name=Asserting), featureSet_s=Lucene50(blocksize=128), 
checkpoint_ss=FSTOrd50, test_t=FSTOrd50, ident_s=Lucene50(blocksize=128), 
whitetok=FSTOrd50, side_s=FSTOrd50}, 
docValues:{expr_s=DocValuesFormat(name=Memory), 
name_s=DocValuesFormat(name=Lucene70), 
iteration_i=DocValuesFormat(name=Lucene70), 
terms_ss=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Direct), 
multiDefault=DocValuesFormat(name=Direct), a_f=DocValuesFormat(name=Asserting), 
intDefault=DocValuesFormat(name=Asserting), 
falseNegative_i=DocValuesFormat(name=Asserting), 
a2_s=DocValuesFormat(name=Memory), a_i=DocValuesFormat(name=Lucene70), 
level1_s=DocValuesFormat(name=Memory), a_s=DocValuesFormat(name=Memory), 
id=DocValuesFormat(name=Lucene70), alpha_d=DocValuesFormat(name=Lucene70), 
i_multi=DocValuesFormat(name=Lucene70), idf_d=DocValuesFormat(name=Asserting), 
b_ls=DocValuesFormat(name=Asserting), 
checkpoint_ss=DocValuesFormat(name=Lucene70), 
ident_s=DocValuesFormat(name=Asserting), order_i=DocValuesFormat(name=Direct), 
error_d=DocValuesFormat(name=Memory), side_s=DocValuesFormat(name=Lucene70), 
truePositive_i=DocValuesFormat(name=Memory), 
miles_i=DocValuesFormat(name=Asserting), 
field_s=DocValuesFormat(name=Asserting), price_f=DocValuesFormat(name=Direct), 
a1_s=DocValuesFormat(name=Asserting), join1_i=DocValuesFormat(name=Memory), 
join3_i=DocValuesFormat(name=Lucene70), test_i=DocValuesFormat(name=Direct), 
falsePositive_i=DocValuesFormat(name=Asserting), 
field_i=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Memory), 
a_ss=DocValuesFormat(name=Direct), level2_s=DocValuesFormat(name=Asserting), 
col_s=DocValuesFormat(name=Asserting), score_f=DocValuesFormat(name=Memory), 
term_s=DocValuesFormat(name=Direct), text_s=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Direct), idfs_ds=DocValuesFormat(name=Direct), 
featureSet_s=DocValuesFormat(name=Asserting), 
weights_ds=DocValuesFormat(name=Lucene70), 
trueNegative_i=DocValuesFormat(name=Memory), 
index_i=DocValuesFormat(name=Asserting), test_dt=DocValuesFormat(name=Memory), 
out_i=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=120, 
maxMBSortInHeap=7.664730538882845, sim=RandomSimilarity(queryNorm=true): {}, 
locale=sr, timezone=America/Creston
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=212440504,total=455081984
   [junit4]   2> NOTE: All tests run in this JVM: [StreamExpressionTest]
   [junit4] Completed [123/146 (1!)] on J0 in 591.10s, 156 tests, 1 failure, 1 
error, 2 skipped <<< FAILURES!

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

Reply via email to