Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21062/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseG1GC
1 tests failed.
FAILED:
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testGammaDistribution
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([8C885F48BC33BCC9:B1F274E69F4B16DE]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testGammaDistribution(StreamExpressionTest.java:7483)
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 15001 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/init-core-data-001
[junit4] 2> 83788 WARN
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 83788 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 83789 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 83790 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 83790 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001
[junit4] 2> 83790 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 83791 INFO (Thread-270) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 83791 INFO (Thread-270) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 83793 ERROR (Thread-270) [ ] 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> 83892 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:37503
[junit4] 2> 83908 INFO (zkConnectionManagerCallback-914-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83919 INFO (jetty-launcher-911-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 83919 INFO (jetty-launcher-911-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 83921 INFO (jetty-launcher-911-thread-3) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 83922 INFO (jetty-launcher-911-thread-4) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 83937 INFO (jetty-launcher-911-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1608562{/solr,null,AVAILABLE}
[junit4] 2> 83941 INFO (jetty-launcher-911-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@9c6aab{SSL,[ssl,
http/1.1]}{127.0.0.1:40315}
[junit4] 2> 83941 INFO (jetty-launcher-911-thread-3) [ ]
o.e.j.s.Server Started @86094ms
[junit4] 2> 83941 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40315}
[junit4] 2> 83941 ERROR (jetty-launcher-911-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 83942 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 83942 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 83942 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 83942 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-10T09:43:31.409Z
[junit4] 2> 83984 INFO (jetty-launcher-911-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1f69cdf{/solr,null,AVAILABLE}
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@11156c9{SSL,[ssl,
http/1.1]}{127.0.0.1:37311}
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.e.j.s.Server Started @86138ms
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37311}
[junit4] 2> 83985 ERROR (jetty-launcher-911-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 83985 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-10T09:43:31.452Z
[junit4] 2> 83994 INFO (zkConnectionManagerCallback-916-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83997 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 84017 INFO (jetty-launcher-911-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@ab7479{/solr,null,AVAILABLE}
[junit4] 2> 84017 INFO (jetty-launcher-911-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1579f90{SSL,[ssl,
http/1.1]}{127.0.0.1:46819}
[junit4] 2> 84019 INFO (jetty-launcher-911-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@f1c810{/solr,null,AVAILABLE}
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@77abdd{SSL,[ssl,
http/1.1]}{127.0.0.1:39887}
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.e.j.s.Server Started @86173ms
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39887}
[junit4] 2> 84020 ERROR (jetty-launcher-911-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 84020 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-10T09:43:31.487Z
[junit4] 2> 84033 INFO (jetty-launcher-911-thread-1) [ ]
o.e.j.s.Server Started @86171ms
[junit4] 2> 84033 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46819}
[junit4] 2> 84034 ERROR (jetty-launcher-911-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 84034 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 84034 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 84034 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 84034 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-12-10T09:43:31.501Z
[junit4] 2> 84041 INFO (zkConnectionManagerCallback-918-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84041 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 84048 INFO (zkConnectionManagerCallback-920-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84050 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 84052 INFO (zkConnectionManagerCallback-922-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84053 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 84077 INFO (jetty-launcher-911-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37503/solr
[junit4] 2> 84079 INFO (zkConnectionManagerCallback-926-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84081 INFO (jetty-launcher-911-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37503/solr
[junit4] 2> 84081 INFO (jetty-launcher-911-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37503/solr
[junit4] 2> 84122 INFO (jetty-launcher-911-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37503/solr
[junit4] 2> 84130 INFO (zkConnectionManagerCallback-937-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84131 INFO (zkConnectionManagerCallback-940-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84131 INFO
(zkConnectionManagerCallback-933-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84140 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1603fcee2bb0008, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 84142 INFO (zkConnectionManagerCallback-935-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84144 INFO
(zkConnectionManagerCallback-942-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84145 INFO
(zkConnectionManagerCallback-944-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84163 INFO
(zkConnectionManagerCallback-946-thread-1-processing-n:127.0.0.1:37311_solr)
[n:127.0.0.1:37311_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84174 WARN (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_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> 84177 WARN (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_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> 84179 WARN (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_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> 84179 WARN (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_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> 84181 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 84182 INFO (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 84182 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:40315_solr
[junit4] 2> 84182 INFO (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46819_solr
[junit4] 2> 84182 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.c.Overseer Overseer
(id=99149349601869830-127.0.0.1:40315_solr-n_0000000000) starting
[junit4] 2> 84193 INFO
(zkCallback-943-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 84195 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 84220 INFO
(zkCallback-931-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 84233 INFO
(zkCallback-945-thread-1-processing-n:127.0.0.1:37311_solr)
[n:127.0.0.1:37311_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 84234 INFO (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 84234 INFO (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 84237 INFO (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37311_solr
[junit4] 2> 84237 INFO (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39887_solr
[junit4] 2> 84243 INFO
(zkCallback-931-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 84243 INFO
(zkCallback-943-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 84243 INFO
(zkCallback-945-thread-1-processing-n:127.0.0.1:37311_solr)
[n:127.0.0.1:37311_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 84243 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 84243 INFO
(zkCallback-943-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 84256 INFO
(zkCallback-945-thread-1-processing-n:127.0.0.1:37311_solr)
[n:127.0.0.1:37311_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 84256 INFO
(zkCallback-931-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 84256 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 84265 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40315_solr
[junit4] 2> 84266 INFO
(zkCallback-943-thread-1-processing-n:127.0.0.1:46819_solr)
[n:127.0.0.1:46819_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 84266 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 84266 INFO
(zkCallback-945-thread-1-processing-n:127.0.0.1:37311_solr)
[n:127.0.0.1:37311_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 84267 INFO
(zkCallback-931-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 84337 INFO (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39887.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84350 INFO (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39887.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84350 INFO (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39887.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84360 INFO (jetty-launcher-911-thread-4)
[n:127.0.0.1:39887_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node4/.
[junit4] 2> 84368 INFO (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37311.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84370 INFO (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46819.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84377 INFO (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37311.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84377 INFO (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37311.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84378 INFO (jetty-launcher-911-thread-2)
[n:127.0.0.1:37311_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node2/.
[junit4] 2> 84379 INFO (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46819.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84379 INFO (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46819.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84380 INFO (jetty-launcher-911-thread-1)
[n:127.0.0.1:46819_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node1/.
[junit4] 2> 84504 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40315.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84563 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40315.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84563 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40315.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 84564 INFO (jetty-launcher-911-thread-3)
[n:127.0.0.1:40315_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node3/.
[junit4] 2> 84607 INFO (zkConnectionManagerCallback-953-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84616 INFO (zkConnectionManagerCallback-957-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84617 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 84617 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37503/solr ready
[junit4] 2> 84665 INFO (qtp8600091-1875) [n:127.0.0.1:46819_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> 84668 INFO
(OverseerThreadFactory-422-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 84781 INFO
(OverseerStateUpdate-99149349601869830-127.0.0.1:40315_solr-n_0000000000)
[n:127.0.0.1:40315_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":"https://127.0.0.1:40315/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 84782 INFO
(OverseerStateUpdate-99149349601869830-127.0.0.1:40315_solr-n_0000000000)
[n:127.0.0.1:40315_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":"https://127.0.0.1:39887/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 85015 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 85015 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 85016 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 85016 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 85120 INFO
(zkCallback-931-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_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> 85120 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_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> 85120 INFO
(zkCallback-931-thread-2-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_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> 85120 INFO
(zkCallback-941-thread-2-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_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> 86026 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 86031 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 86036 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86042 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86119 WARN (qtp19905452-1878) [n:127.0.0.1:39887_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> 86119 WARN (qtp19905452-1878) [n:127.0.0.1:39887_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> 86119 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86119 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86119 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39887.solr.core.collection1.shard2.replica_n2' (registry
'solr.core.collection1.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 86120 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 86120 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node4/collection1_shard2_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node4/./collection1_shard2_replica_n2/data/]
[junit4] 2> 86120 WARN (qtp22071663-1865) [n:127.0.0.1:40315_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> 86120 WARN (qtp22071663-1865) [n:127.0.0.1:40315_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> 86120 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86120 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86121 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40315.solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@13976e8
[junit4] 2> 86121 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 86121 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node3/collection1_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001/tempDir-001/node3/./collection1_shard1_replica_n1/data/]
[junit4] 2> 86165 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86165 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86166 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 86166 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 86166 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86166 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86168 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 86168 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 86168 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1fd6a39[collection1_shard2_replica_n2] main]
[junit4] 2> 86169 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86169 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86170 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@75be34[collection1_shard1_replica_n1] main]
[junit4] 2> 86170 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86171 INFO
(searcherExecutor-431-thread-1-processing-n:127.0.0.1:39887_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:39887_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@1fd6a39[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 86171 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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 1586389596124479488
[junit4] 2> 86171 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86171 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86172 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86173 INFO
(searcherExecutor-432-thread-1-processing-n:127.0.0.1:40315_solr
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3)
[n:127.0.0.1:40315_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@75be34[collection1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 86173 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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 1586389596126576640
[junit4] 2> 86175 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86175 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86175 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/
[junit4] 2> 86175 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86175 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SyncStrategy
https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 86175 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86177 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86177 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86177 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/
[junit4] 2> 86177 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86177 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SyncStrategy
https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 86177 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86177 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 86179 INFO (qtp22071663-1865) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 86280 INFO
(zkCallback-931-thread-2-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_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> 86280 INFO
(zkCallback-931-thread-1-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_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> 86280 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_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> 86280 INFO
(zkCallback-941-thread-2-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_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> 86328 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 86330 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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> 86337 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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=1322
[junit4] 2> 86337 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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=1321
[junit4] 2> 86340 INFO (qtp8600091-1875) [n:127.0.0.1:46819_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> 86432 INFO
(zkCallback-931-thread-2-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_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> 86432 INFO
(zkCallback-941-thread-1-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_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> 86432 INFO
(zkCallback-931-thread-3-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_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> 86432 INFO
(zkCallback-941-thread-2-processing-n:127.0.0.1:39887_solr)
[n:127.0.0.1:39887_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> 86671 INFO
(OverseerCollectionConfigSetProcessor-99149349601869830-127.0.0.1:40315_solr-n_0000000000)
[n:127.0.0.1:40315_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> 87341 INFO (qtp8600091-1875) [n:127.0.0.1:46819_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=2676
[junit4] 2> 87342 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):90
[junit4] 2> 87342 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 87364 INFO
(TEST-StreamExpressionTest.fakeTest-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting fakeTest
[junit4] 2> 87385 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597394305024)} 0 3
[junit4] 2> 87388 INFO (qtp22071663-1872) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597400596480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87388 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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> 87388 INFO (qtp22071663-1872) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87388 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87391 INFO (qtp19905452-1883) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597403742208,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87391 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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> 87391 INFO (qtp19905452-1883) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87391 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87392 INFO (qtp19905452-1900) [n:127.0.0.1:39887_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:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597379624960),commit=} 0 24
[junit4] 2> 87400 INFO
(TEST-StreamExpressionTest.fakeTest-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending fakeTest
[junit4] 2> 87418 INFO
(TEST-StreamExpressionTest.testEBESubtract-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEBESubtract
[junit4] 2> 87425 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597434150912)} 0 5
[junit4] 2> 87427 INFO (qtp22071663-1866) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597441490944,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87427 INFO (qtp19905452-1883) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597441490944,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87427 INFO (qtp22071663-1866) [n:127.0.0.1:40315_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> 87427 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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> 87427 INFO (qtp22071663-1866) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87427 INFO (qtp19905452-1883) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87427 INFO (qtp22071663-1866) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87427 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87428 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597433102336),commit=} 0 9
[junit4] 2> 87432 INFO (qtp19905452-1885) [n:127.0.0.1:39887_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=/stream
params={indent=off&expr=ebeSubtract(array(2,4,6,8,10,12),array(1,2,3,4,5,6))&wt=json&version=2.2}
status=0 QTime=1
[junit4] 2> 87441 INFO
(TEST-StreamExpressionTest.testEBESubtract-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testEBESubtract
[junit4] 2> 87464 INFO
(TEST-StreamExpressionTest.testMinMaxScale-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testMinMaxScale
[junit4] 2> 87468 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597483433984)} 0 1
[junit4] 2> 87469 INFO (qtp22071663-1872) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597485531136,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87469 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597485531136,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87470 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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> 87470 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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> 87470 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87470 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87470 INFO (qtp22071663-1872) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87470 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87472 INFO (qtp19905452-1899) [n:127.0.0.1:39887_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:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597481336832),commit=} 0 6
[junit4] 2> 87477 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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=/stream
params={indent=off&expr=let(echo%3Dtrue,+a%3DminMaxScale(matrix(array(1,2,3,4,5),+array(10,20,30,40,50))),+b%3DminMaxScale(matrix(array(1,2,3,4,5),+array(10,20,30,40,50)),+0,+100),c%3DminMaxScale(array(1,2,3,4,5)),d%3DminMaxScale(array(1,2,3,4,5),+0,+100))&wt=json&version=2.2}
status=0 QTime=1
[junit4] 2> 87481 INFO
(TEST-StreamExpressionTest.testMinMaxScale-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testMinMaxScale
[junit4] 2> 87504 INFO
(TEST-StreamExpressionTest.testCellStream-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testCellStream
[junit4] 2> 87506 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597523279872)} 0 0
[junit4] 2> 87507 INFO (qtp22071663-1866) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597525377024,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87507 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597525377024,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87507 INFO (qtp22071663-1866) [n:127.0.0.1:40315_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> 87508 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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> 87508 INFO (qtp22071663-1866) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87508 INFO (qtp22071663-1866) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87508 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87508 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87509 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597523279872),commit=} 0 4
[junit4] 2> 87515 INFO (qtp19905452-1885) [n:127.0.0.1:39887_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:4&wt=javabin&version=2}{add=[hello
(1586389597528522752), hello1 (1586389597533765632)]} 0 4
[junit4] 2> 87517 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597535862784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87517 INFO (qtp22071663-1871) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597535862784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87517 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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@976e88
commitCommandVersion:1586389597535862784
[junit4] 2> 87517 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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> 87517 INFO (qtp22071663-1871) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87518 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87523 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1598fc4[collection1_shard2_replica_n2] main]
[junit4] 2> 87524 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87525 INFO
(searcherExecutor-431-thread-1-processing-n:127.0.0.1:39887_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:39887_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@1598fc4[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 87525 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 8
[junit4] 2> 87526 INFO (qtp19905452-1899) [n:127.0.0.1:39887_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:4&commit=true&wt=javabin&version=2}{commit=} 0 10
[junit4] 2> 87533 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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=/stream
params={indent=off&expr=cell(results,search(collection1,+q%3D"*:*",+fl%3D"id,test_t",+sort%3D"id+desc"))&wt=json&version=2.2}
status=0 QTime=0
[junit4] 2> 87539 INFO
(zkConnectionManagerCallback-962-thread-1-processing-n:127.0.0.1:39887_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:39887_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.c.c.ConnectionManager zkClient has
connected
[junit4] 2> 87539 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 87541 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37503/solr ready
[junit4] 2> 87552 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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,test_t&sort=id+desc&wt=json&version=2.2}
hits=2 status=0 QTime=0
[junit4] 2> 87555 INFO (qtp22071663-1866) [n:127.0.0.1:40315_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,test_t&sort=id+desc&wt=json&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 87557 INFO
(TEST-StreamExpressionTest.testCellStream-seed#[8C885F48BC33BCC9]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testCellStream
[junit4] 2> 87579 INFO
(TEST-StreamExpressionTest.testParallelTopicStream-seed#[8C885F48BC33BCC9]) [
] o.a.s.SolrTestCaseJ4 ###Starting testParallelTopicStream
[junit4] 2> 87586 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@425c3f[collection1_shard2_replica_n2] realtime]
[junit4] 2> 87587 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597607165952)} 0 1
[junit4] 2> 87590 INFO (qtp19905452-1900) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597612408832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87590 INFO (qtp19905452-1900) [n:127.0.0.1:39887_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@976e88
commitCommandVersion:1586389597612408832
[junit4] 2> 87590 INFO (qtp19905452-1900) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@223f78[collection1_shard2_replica_n2] main]
[junit4] 2> 87591 INFO (qtp19905452-1900) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87592 INFO (qtp22071663-1872) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597614505984,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87592 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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> 87592 INFO (qtp22071663-1872) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87592 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87592 INFO
(searcherExecutor-431-thread-1-processing-n:127.0.0.1:39887_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:39887_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@223f78[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 87592 INFO (qtp19905452-1900) [n:127.0.0.1:39887_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 87592 INFO (qtp22071663-1866) [n:127.0.0.1:40315_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:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1586389597605068800),commit=} 0 10
[junit4] 2> 87598 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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:4&wt=javabin&version=2}{add=[0
(1586389597619748864), 4 (1586389597620797440), 1 (1586389597620797441), 8
(1586389597620797442)]} 0 1
[junit4] 2> 87598 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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:4&wt=javabin&version=2}{add=[2
(1586389597617651712), 3 (1586389597619748864), 5 (1586389597620797440), 6
(1586389597620797441), 7 (1586389597620797442), 9 (1586389597620797443)]} 0 3
[junit4] 2> 87600 INFO (qtp22071663-1868) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597622894592,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87600 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597622894592,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87600 INFO (qtp22071663-1868) [n:127.0.0.1:40315_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@1f2cdf
commitCommandVersion:1586389597622894592
[junit4] 2> 87600 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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@976e88
commitCommandVersion:1586389597622894592
[junit4] 2> 87602 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4e1a63[collection1_shard2_replica_n2] main]
[junit4] 2> 87603 INFO (qtp19905452-1879) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87604 INFO
(searcherExecutor-431-thread-1-processing-n:127.0.0.1:39887_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:39887_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@4e1a63[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C6)))}
[junit4] 2> 87604 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 4
[junit4] 2> 87605 INFO (qtp22071663-1868) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@92f227[collection1_shard1_replica_n1] main]
[junit4] 2> 87605 INFO (qtp22071663-1868) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87606 INFO
(searcherExecutor-432-thread-1-processing-n:127.0.0.1:40315_solr
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3)
[n:127.0.0.1:40315_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@92f227[collection1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C4)))}
[junit4] 2> 87606 INFO (qtp22071663-1868) [n:127.0.0.1:40315_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 5
[junit4] 2> 87607 INFO (qtp22071663-1867) [n:127.0.0.1:40315_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:4&commit=true&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 87612 INFO (zkConnectionManagerCallback-967-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 87613 INFO
(TEST-StreamExpressionTest.testParallelTopicStream-seed#[8C885F48BC33BCC9]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 87613 INFO
(TEST-StreamExpressionTest.testParallelTopicStream-seed#[8C885F48BC33BCC9]) [
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37503/solr
ready
[junit4] 2> 87619 INFO (qtp19905452-1879) [n:127.0.0.1:39887_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=/stream
params={distrib=false&workerID=0&indent=off&numWorkers=2&expr=topic(collection1,collection1,q%3D"a_s:hello",fl%3Did,partitionKeys%3Did,rows%3D500,zkHost%3D"127.0.0.1:37503/solr",id%3D1000000,checkpointEvery%3D-1)&wt=json&version=2.2}
status=0 QTime=0
[junit4] 2> 87619 INFO (qtp22071663-1865) [n:127.0.0.1:40315_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={distrib=false&workerID=1&indent=off&numWorkers=2&expr=topic(collection1,collection1,q%3D"a_s:hello",fl%3Did,partitionKeys%3Did,rows%3D500,zkHost%3D"127.0.0.1:37503/solr",id%3D1000000,checkpointEvery%3D-1)&wt=json&version=2.2}
status=0 QTime=0
[junit4] 2> 87621 INFO (qtp19905452-1900) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.h.c.RealTimeGetComponent
LOOKUP_SLICE:shard2=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/
[junit4] 2> 87621 INFO (qtp19905452-1899) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.h.c.RealTimeGetComponent
LOOKUP_SLICE:shard2=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/
[junit4] 2> 87625 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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=/get
params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1512899015088&ids=1000000_1&isShard=true&shard.url=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&wt=javabin&version=2&shards.qt=/get}
status=0 QTime=0
[junit4] 2> 87626 INFO (qtp19905452-1899) [n:127.0.0.1:39887_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=/get
params={qt=/get&ids=1000000_1&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 87627 INFO (qtp19905452-1899) [n:127.0.0.1:39887_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=/get
params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1512899015088&ids=1000000_0&isShard=true&shard.url=https://127.0.0.1:39887/solr/collection1_shard2_replica_n2/&wt=javabin&version=2&shards.qt=/get}
status=0 QTime=0
[junit4] 2> 87627 INFO (qtp19905452-1883) [n:127.0.0.1:39887_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&sort=_version_+desc&rows=1&wt=json&version=2.2}
hits=6 status=0 QTime=0
[junit4] 2> 87627 INFO (qtp19905452-1900) [n:127.0.0.1:39887_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=/get
params={qt=/get&ids=1000000_0&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 87630 INFO (qtp22071663-1868) [n:127.0.0.1:40315_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&sort=_version_+desc&rows=1&wt=json&version=2.2}
hits=4 status=0 QTime=0
[junit4] 2> 87630 INFO (qtp19905452-1885) [n:127.0.0.1:39887_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&sort=_version_+desc&rows=1&wt=json&version=2.2}
hits=6 status=0 QTime=0
[junit4] 2> 87634 INFO (qtp22071663-1868) [n:127.0.0.1:40315_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&sort=_version_+desc&rows=1&wt=json&version=2.2}
hits=4 status=0 QTime=0
[junit4] 2> 87635 INFO (qtp19905452-2019) [n:127.0.0.1:39887_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=a_s:hello&distrib=false&fl=id,_version_&partitionKeys=id&sort=_version_+asc&fq={!hash+workers%3D2+worker%3D1}&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1586389597620797443}_version_&rows=500&wt=json&version=2.2}
hits=0 status=0 QTime=1
[junit4] 2> 87635 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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=a_s:hello&distrib=false&fl=id,_version_&partitionKeys=id&sort=_version_+asc&fq={!hash+workers%3D2+worker%3D1}&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1586389597620797442}_version_&rows=500&wt=json&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 87639 INFO (qtp19905452-1893) [n:127.0.0.1:39887_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=a_s:hello&distrib=false&fl=id,_version_&partitionKeys=id&sort=_version_+asc&fq={!hash+workers%3D2+worker%3D0}&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1586389597620797443}_version_&rows=500&wt=json&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 87639 INFO (qtp22071663-1868) [n:127.0.0.1:40315_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=a_s:hello&distrib=false&fl=id,_version_&partitionKeys=id&sort=_version_+asc&fq={!hash+workers%3D2+worker%3D0}&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1586389597620797442}_version_&rows=500&wt=json&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 87645 INFO (qtp19905452-1899) [n:127.0.0.1:39887_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:4&collection=collection1&wt=javabin&version=2}{add=[1000000_1
(1586389597667983360)]} 0 2
[junit4] 2> 87646 INFO (qtp19905452-2019) [n:127.0.0.1:39887_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:4&collection=collection1&wt=javabin&version=2}{add=[1000000_0
(1586389597669031936)]} 0 2
[junit4] 2> 87649 INFO (qtp22071663-1871) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597674274816,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87649 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1586389597674274816,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 87649 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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> 87649 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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@976e88
commitCommandVersion:1586389597674274816
[junit4] 2> 87649 INFO (qtp22071663-1871) [n:127.0.0.1:40315_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87649 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 87655 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2d2a77[collection1_shard2_replica_n2] main]
[junit4] 2> 87656 INFO (qtp19905452-1878) [n:127.0.0.1:39887_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 87657 INFO
(searcherExecutor-431-thread-1-processing-n:127.0.0.1:39887_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:39887_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@2d2a77[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C6)
Uninverting(_2(8.0.0):C1) Uninverting(_3(8.0.0):C1)))}
[junit4] 2> 87657 INFO (qtp19905452-1878) [n:127.0.0.1:39887_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=https://127.0.0.1:40315/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 8
[junit4] 2> 87658 INFO (qtp22071663-1872) [n:127.0.0.1:40315_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:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 10
[junit4] 2> 87670 INFO (qtp22071663-1871) [n:127.0.0.1:40315_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=id:1000000*&distrib=false&fl=id,+checkpoint_ss,+_version_&sort=id+asc&wt=json&version=2.2}
hits=0 status=0 QTime=4
[junit4] 2> 87671 INFO (qtp19905452-1899) [n:127.0.0.1:39887_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
[...truncated too long message...]
27.0.0.1:39887_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 194184 INFO
(zkCallback-931-thread-3-processing-n:127.0.0.1:40315_solr)
[n:127.0.0.1:40315_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 194184 INFO (coreCloseExecutor-624-thread-1)
[n:127.0.0.1:39887_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore
[collection1_shard2_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@1081915
[junit4] 2> 194184 INFO (coreCloseExecutor-624-thread-1)
[n:127.0.0.1:39887_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard2.replica_n2, tag=17307925
[junit4] 2> 194184 INFO (coreCloseExecutor-623-thread-1)
[n:127.0.0.1:40315_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@241f3
[junit4] 2> 194184 INFO (coreCloseExecutor-624-thread-1)
[n:127.0.0.1:39887_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2da022: rootName =
solr_39887, domain = solr.core.collection1.shard2.replica_n2, service url =
null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@d75006
[junit4] 2> 194184 INFO (coreCloseExecutor-623-thread-1)
[n:127.0.0.1:40315_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard1.replica_n1, tag=147955
[junit4] 2> 194184 INFO (coreCloseExecutor-623-thread-1)
[n:127.0.0.1:40315_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@9ea2db: rootName =
solr_40315, domain = solr.core.collection1.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.collection1.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@cdb3ed
[junit4] 2> 194192 INFO (coreCloseExecutor-624-thread-1)
[n:127.0.0.1:39887_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard2.leader, tag=17307925
[junit4] 2> 194192 INFO (coreCloseExecutor-623-thread-1)
[n:127.0.0.1:40315_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard1.leader, tag=147955
[junit4] 2> 194193 INFO (jetty-closer-912-thread-4) [ ]
o.a.s.c.Overseer Overseer
(id=99149349601869830-127.0.0.1:40315_solr-n_0000000000) closing
[junit4] 2> 194194 INFO
(OverseerStateUpdate-99149349601869830-127.0.0.1:40315_solr-n_0000000000)
[n:127.0.0.1:40315_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40315_solr
[junit4] 2> 194194 WARN
(OverseerAutoScalingTriggerThread-99149349601869830-127.0.0.1:40315_solr-n_0000000000)
[n:127.0.0.1:40315_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 194196 INFO (jetty-closer-912-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@f1c810{/solr,null,UNAVAILABLE}
[junit4] 2> 194197 INFO (jetty-closer-912-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1608562{/solr,null,UNAVAILABLE}
[junit4] 2> 195678 WARN
(zkCallback-945-thread-2-processing-n:127.0.0.1:37311_solr)
[n:127.0.0.1:37311_solr ] o.a.s.c.ZkController Unable to register nodeLost
path for 127.0.0.1:37311_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:37311_solr
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:364)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:364)
[junit4] 2> at
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:879)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:705)
[junit4] 2> at
java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:704)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.access$900(ZkStateReader.java:74)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1105)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1100)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:268)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 195679 INFO (jetty-closer-912-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1f69cdf{/solr,null,UNAVAILABLE}
[junit4] 2> 195679 ERROR
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-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> 195679 INFO
(SUITE-StreamExpressionTest-seed#[8C885F48BC33BCC9]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37503 37503
[junit4] 2> 199829 INFO (Thread-270) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:37503 37503
[junit4] 2> 199830 WARN (Thread-270) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 99 /solr/aliases.json
[junit4] 2> 14 /solr/configs/conf
[junit4] 2> 7 /solr/configs/ml
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 99 /solr/clusterprops.json
[junit4] 2> 99 /solr/clusterstate.json
[junit4] 2> 40
/solr/collections/destinationCollection/state.json
[junit4] 2> 26
/solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 20
/solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus1/state.json
[junit4] 2> 10 /solr/collections/destination/state.json
[junit4] 2> 10 /solr/collections/destination1/state.json
[junit4] 2> 10 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/collections/workQueue1/state.json
[junit4] 2> 10 /solr/collections/workQueue/state.json
[junit4] 2> 10 /solr/collections/uknownCollection/state.json
[junit4] 2> 10 /solr/collections/modelCollection/state.json
[junit4] 2> 10
/solr/collections/checkpointCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus/state.json
[junit4] 2> 2
/solr/overseer_elect/election/99149349601869830-127.0.0.1:40315_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 99 /solr/live_nodes
[junit4] 2> 99 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_8C885F48BC33BCC9-001
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@419303),
locale=ar-DZ, timezone=Pacific/Gambier
[junit4] 2> NOTE: Linux 4.10.0-40-generic i386/Oracle Corporation
1.8.0_144 (32-bit)/cpus=8,threads=1,free=261304472,total=419430400
[junit4] 2> NOTE: All tests run in this JVM: [AppendEvaluatorTest,
ConcurrentUpdateSolrClientTest, FloorEvaluatorTest,
TestDelegationTokenResponse, TestDocumentObjectBinder, TestRetryUtil,
UniformDistributionEvaluatorTest, SolrDocumentTest, ClientUtilsTest,
SolrExampleStreamingBinaryTest, RecursiveEvaluatorTest,
TestDelegationTokenRequest, SubtractEvaluatorTest,
CollectionAdminRequestRequiredParamsTest, SolrZkClientTest,
ConcatOperationTest, RoundEvaluatorTest, QueryResponseTest, SolrPingTest,
CommonAdminParamsTest, TestSolrProperties, CumulativeProbabilityEvaluatorTest,
ModuloEvaluatorTest, BasicHttpSolrClientTest, SolrExampleStreamingTest,
SolrExampleXMLTest, TestBatchUpdate, JettyWebappTest, LargeVolumeEmbeddedTest,
SolrParamTest, MergeIndexesEmbeddedTest, TestFastInputStream,
SolrExceptionTest, FacetFieldTest, TestLBHttpSolrClient, GraphTest, JdbcTest,
StreamExpressionTest]
[junit4] Completed [146/146 (1!)] on J1 in 116.07s, 139 tests, 1 failure <<<
FAILURES!
[...truncated 42200 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
