Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19906/ Java: 32bit/jdk1.8.0_131 -server -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testArraySort Error Message: --> http://127.0.0.1:45811/solr/collection1:Invalid stream expression arraySort(array(11.5,12.3,4,3,1,0)) - function 'arraySort' is unknown (not mapped to a valid TupleStream) Stack Trace: java.io.IOException: --> http://127.0.0.1:45811/solr/collection1:Invalid stream expression arraySort(array(11.5,12.3,4,3,1,0)) - function 'arraySort' is unknown (not mapped to a valid TupleStream) at __randomizedtesting.SeedInfo.seed([560B98935779CD52:60BD4EBED0A0312B]:0) at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:219) at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.getTuples(StreamExpressionTest.java:7487) at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testArraySort(StreamExpressionTest.java:5840) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 14078 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/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/init-core-data-001 [junit4] 2> 83973 WARN (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21 [junit4] 2> 83973 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 83974 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 83975 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001 [junit4] 2> 83975 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 83975 INFO (Thread-222) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 83975 INFO (Thread-222) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 83980 ERROR (Thread-222) [ ] 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> 84075 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:40381 [junit4] 2> 84083 INFO (jetty-launcher-202-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 84083 INFO (jetty-launcher-202-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 84084 INFO (jetty-launcher-202-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 84085 INFO (jetty-launcher-202-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 84086 INFO (jetty-launcher-202-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f34312{/solr,null,AVAILABLE} [junit4] 2> 84086 INFO (jetty-launcher-202-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5889b7{HTTP/1.1,[http/1.1]}{127.0.0.1:45811} [junit4] 2> 84086 INFO (jetty-launcher-202-thread-3) [ ] o.e.j.s.Server Started @86804ms [junit4] 2> 84086 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45811} [junit4] 2> 84086 ERROR (jetty-launcher-202-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 84086 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 84086 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 84087 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 84087 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-19T10:24:47.326Z [junit4] 2> 84096 INFO (jetty-launcher-202-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@220318{/solr,null,AVAILABLE} [junit4] 2> 84096 INFO (jetty-launcher-202-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1739a04{/solr,null,AVAILABLE} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1df8c40{/solr,null,AVAILABLE} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@12a01ea{HTTP/1.1,[http/1.1]}{127.0.0.1:44425} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.e.j.s.Server Started @86815ms [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44425} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1961e08{HTTP/1.1,[http/1.1]}{127.0.0.1:34515} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.e.j.s.Server Started @86815ms [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34515} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b5afe{HTTP/1.1,[http/1.1]}{127.0.0.1:34125} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.e.j.s.Server Started @86815ms [junit4] 2> 84097 ERROR (jetty-launcher-202-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34125} [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 84097 ERROR (jetty-launcher-202-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 84097 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-19T10:24:47.336Z [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 84097 ERROR (jetty-launcher-202-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 84097 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-19T10:24:47.336Z [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 84097 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-19T10:24:47.336Z [junit4] 2> 84100 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 84100 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 84100 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 84106 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 84110 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 0x15cbfe222720003, 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> 84110 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 0x15cbfe222720001, 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> 84115 INFO (jetty-launcher-202-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40381/solr [junit4] 2> 84119 INFO (jetty-launcher-202-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40381/solr [junit4] 2> 84128 INFO (jetty-launcher-202-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40381/solr [junit4] 2> 84142 INFO (jetty-launcher-202-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40381/solr [junit4] 2> 84176 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 84183 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45811_solr [junit4] 2> 84184 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.Overseer Overseer (id=98164269856718856-127.0.0.1:45811_solr-n_0000000000) starting [junit4] 2> 84188 INFO (jetty-launcher-202-thread-4) [n:127.0.0.1:34125_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 84188 INFO (jetty-launcher-202-thread-2) [n:127.0.0.1:34515_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 84188 INFO (jetty-launcher-202-thread-4) [n:127.0.0.1:34125_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34125_solr [junit4] 2> 84188 INFO (jetty-launcher-202-thread-2) [n:127.0.0.1:34515_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34515_solr [junit4] 2> 84189 INFO (jetty-launcher-202-thread-1) [n:127.0.0.1:44425_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 84198 INFO (zkCallback-223-thread-1-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 84204 INFO (zkCallback-221-thread-1-processing-n:127.0.0.1:45811_solr) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 84204 INFO (zkCallback-222-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 84210 INFO (jetty-launcher-202-thread-1) [n:127.0.0.1:44425_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44425_solr [junit4] 2> 84213 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:44425_solr) [n:127.0.0.1:44425_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 84213 INFO (zkCallback-223-thread-1-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 84213 INFO (zkCallback-222-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 84226 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:44425_solr) [n:127.0.0.1:44425_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 84242 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45811_solr [junit4] 2> 84243 INFO (OverseerStateUpdate-98164269856718856-127.0.0.1:45811_solr-n_0000000000) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 84245 INFO (zkCallback-223-thread-1-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 84245 INFO (zkCallback-222-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 84245 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:44425_solr) [n:127.0.0.1:44425_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 84245 INFO (zkCallback-221-thread-1-processing-n:127.0.0.1:45811_solr) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 84374 INFO (jetty-launcher-202-thread-4) [n:127.0.0.1:34125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84375 INFO (jetty-launcher-202-thread-1) [n:127.0.0.1:44425_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44425.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84376 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84392 INFO (jetty-launcher-202-thread-4) [n:127.0.0.1:34125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84393 INFO (jetty-launcher-202-thread-1) [n:127.0.0.1:44425_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44425.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84393 INFO (jetty-launcher-202-thread-1) [n:127.0.0.1:44425_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44425.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84394 INFO (jetty-launcher-202-thread-1) [n:127.0.0.1:44425_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node1/. [junit4] 2> 84395 INFO (jetty-launcher-202-thread-4) [n:127.0.0.1:34125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84396 INFO (jetty-launcher-202-thread-4) [n:127.0.0.1:34125_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node4/. [junit4] 2> 84403 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84404 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84404 INFO (jetty-launcher-202-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node3/. [junit4] 2> 84410 INFO (jetty-launcher-202-thread-2) [n:127.0.0.1:34515_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34515.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84413 INFO (jetty-launcher-202-thread-2) [n:127.0.0.1:34515_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34515.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84413 INFO (jetty-launcher-202-thread-2) [n:127.0.0.1:34515_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34515.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 84413 INFO (jetty-launcher-202-thread-2) [n:127.0.0.1:34515_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node2/. [junit4] 2> 84509 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 84510 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40381/solr ready [junit4] 2> 84516 INFO (qtp16643626-1261) [n:127.0.0.1:44425_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> 84522 INFO (OverseerThreadFactory-571-thread-1-processing-n:127.0.0.1:45811_solr) [n:127.0.0.1:45811_solr ] o.a.s.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 84722 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 84722 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 84726 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&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> 84726 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 84829 INFO (zkCallback-223-thread-1-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_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> 84829 INFO (zkCallback-222-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_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> 85745 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 85751 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=test [junit4] 2> 85760 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 85768 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test [junit4] 2> 85807 WARN (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 85807 WARN (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 85808 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 85808 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using configuration from collection collection1, trusted=true [junit4] 2> 85808 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34515.solr.core.collection1.shard2.replica_n1' (registry 'solr.core.collection1.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 85808 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 85808 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node2/collection1_shard2_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node2/./collection1_shard2_replica_n1/data/] [junit4] 2> 85833 WARN (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 85834 WARN (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 85834 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 85834 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 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> 85835 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@110c59 [junit4] 2> 85835 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 85835 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 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/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node4/collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001/tempDir-001/node4/./collection1_shard1_replica_n1/data/] [junit4] 2> 85859 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 85859 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 85860 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 85860 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 85862 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a3e9b1[collection1_shard2_replica_n1] main] [junit4] 2> 85863 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 85863 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 85864 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 85864 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570628319684067328 [junit4] 2> 85866 INFO (searcherExecutor-580-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@1a3e9b1[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 85869 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 85869 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 85869 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/ [junit4] 2> 85869 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 85869 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/ has no replicas [junit4] 2> 85870 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 85872 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/ shard2 [junit4] 2> 85877 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 85877 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 85878 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 85878 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 85879 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4fb460[collection1_shard1_replica_n1] main] [junit4] 2> 85880 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 85880 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 85881 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 85881 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570628319701893120 [junit4] 2> 85882 INFO (searcherExecutor-581-thread-1-processing-n:127.0.0.1:34125_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@4fb460[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 85885 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 85885 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 85886 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/ [junit4] 2> 85886 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 85886 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/ has no replicas [junit4] 2> 85886 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 85887 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/ shard1 [junit4] 2> 85989 INFO (zkCallback-222-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_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> 85989 INFO (zkCallback-223-thread-1-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_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> 86023 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 86025 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1303 [junit4] 2> 86038 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 86040 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&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=1314 [junit4] 2> 86044 INFO (qtp16643626-1261) [n:127.0.0.1:44425_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> 86141 INFO (zkCallback-223-thread-1-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_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> 86141 INFO (zkCallback-222-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_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> 86523 INFO (OverseerCollectionConfigSetProcessor-98164269856718856-127.0.0.1:45811_solr-n_0000000000) [n:127.0.0.1:45811_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> 87045 INFO (qtp16643626-1261) [n:127.0.0.1:44425_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=2528 [junit4] 2> 87045 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):90 [junit4] 2> 87046 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1 [junit4] 2> 87064 INFO (TEST-StreamExpressionTest.testStatsStream-seed#[560B98935779CD52]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStatsStream [junit4] 2> 87070 INFO (qtp17322912-1277) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1570628320946552832)} 0 1 [junit4] 2> 87073 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628320951795712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87073 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 87076 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87076 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3 [junit4] 2> 87076 INFO (qtp17322912-1250) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628320954941440,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87076 INFO (qtp17322912-1250) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 87076 INFO (qtp17322912-1250) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87077 INFO (qtp17322912-1250) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 87077 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1570628320944455680),commit=} 0 11 [junit4] 2> 87083 INFO (qtp28071416-1246) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0 (1570628320958087168), 4 (1570628320962281472), 1 (1570628320962281473), 8 (1570628320962281474)]} 0 4 [junit4] 2> 87083 INFO (qtp17322912-1262) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2 (1570628320959135744), 3 (1570628320962281472), 5 (1570628320962281473), 6 (1570628320962281474), 7 (1570628320962281475), 9 (1570628320962281476)]} 0 3 [junit4] 2> 87085 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628320964378624,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87085 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628320964378624,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87085 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dac5c5 commitCommandVersion:1570628320964378624 [junit4] 2> 87086 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@24d6b2 commitCommandVersion:1570628320964378624 [junit4] 2> 87088 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e6c486[collection1_shard2_replica_n1] main] [junit4] 2> 87088 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87089 INFO (searcherExecutor-580-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@1e6c486[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C6)))} [junit4] 2> 87089 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 4 [junit4] 2> 87092 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11bf8ef[collection1_shard1_replica_n1] main] [junit4] 2> 87093 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87094 INFO (searcherExecutor-581-thread-1-processing-n:127.0.0.1:34125_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@11bf8ef[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4)))} [junit4] 2> 87094 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 8 [junit4] 2> 87101 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 17 [junit4] 2> 87131 INFO (TEST-StreamExpressionTest.testStatsStream-seed#[560B98935779CD52]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 87131 INFO (TEST-StreamExpressionTest.testStatsStream-seed#[560B98935779CD52]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40381/solr ready [junit4] 2> 87157 INFO (qtp17322912-1277) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497867890375&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=6 status=0 QTime=2 [junit4] 2> 87157 INFO (qtp28071416-1272) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497867890375&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=4 status=0 QTime=2 [junit4] 2> 87161 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=collection1:3&stats=true&rows=0&wt=javabin&version=2&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=10 status=0 QTime=25 [junit4] 2> 87165 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497867890403&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=6 status=0 QTime=0 [junit4] 2> 87165 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497867890403&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=4 status=0 QTime=0 [junit4] 2> 87174 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={q=*:*&shards=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/,http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&distrib=true&stats=true&rows=0&wt=javabin&version=2&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=10 status=0 QTime=10 [junit4] 2> 87177 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/stream params={myCollection.shards=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/,http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&expr=stats(myCollection,+q%3D*:*,+sum(a_i),+sum(a_f),+min(a_i),+min(a_f),+max(a_i),+max(a_f),+avg(a_i),+avg(a_f),+count(*))&wt=json&version=2.2} status=0 QTime=0 [junit4] 2> 87181 INFO (qtp17322912-1277) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497867890418&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=6 status=0 QTime=0 [junit4] 2> 87182 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={distrib=false&df=text&fl=id&fl=score&shards.purpose=516&start=0&fsv=true&shard.url=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&stats=true&NOW=1497867890418&isShard=true&wt=javabin&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=4 status=0 QTime=0 [junit4] 2> 87183 INFO (qtp17322912-1250) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={q=*:*&shards=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/,http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&distrib=true&stats=true&rows=0&wt=javabin&version=2&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_f&stats.field={!sum%3Dtrue+min%3Dtrue+max%3Dtrue+mean%3Dtrue+}a_i} hits=10 status=0 QTime=4 [junit4] 2> 87186 INFO (qtp17322912-1276) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/stream params={expr=stats(myCollection,+q%3D*:*,+sum(a_i),+sum(a_f),+min(a_i),+min(a_f),+max(a_i),+max(a_f),+avg(a_i),+avg(a_f),+count(*))&wt=json&version=2.2} status=0 QTime=0 [junit4] 2> 87188 INFO (qtp17322912-1276) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 87188 INFO (qtp17322912-1276) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40381/solr ready [junit4] 2> 87189 ERROR (qtp17322912-1276) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.s.i.s.ExceptionStream java.io.IOException: org.apache.solr.common.SolrException: Collection not found: myCollection [junit4] 2> at org.apache.solr.client.solrj.io.stream.StatsStream.open(StatsStream.java:214) [junit4] 2> at org.apache.solr.client.solrj.io.stream.ExceptionStream.open(ExceptionStream.java:51) [junit4] 2> at org.apache.solr.handler.StreamHandler$TimerStream.open(StreamHandler.java:558) [junit4] 2> at org.apache.solr.client.solrj.io.stream.TupleStream.writeMap(TupleStream.java:83) [junit4] 2> at org.apache.solr.response.JSONWriter.writeMap(JSONResponseWriter.java:547) [junit4] 2> at org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:198) [junit4] 2> at org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:209) [junit4] 2> at org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:325) [junit4] 2> at org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:120) [junit4] 2> at org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:71) [junit4] 2> at org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:808) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:537) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: org.apache.solr.common.SolrException: Collection not found: myCollection [junit4] 2> at org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1153) [junit4] 2> at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:836) [junit4] 2> at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] 2> at org.apache.solr.client.solrj.io.stream.StatsStream.open(StatsStream.java:211) [junit4] 2> ... 38 more [junit4] 2> [junit4] 2> 87190 INFO (TEST-StreamExpressionTest.testStatsStream-seed#[560B98935779CD52]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStatsStream [junit4] 2> 87210 INFO (TEST-StreamExpressionTest.testConvolution-seed#[560B98935779CD52]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConvolution [junit4] 2> 87214 INFO (qtp17322912-1277) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10a90ed[collection1_shard2_replica_n1] realtime] [junit4] 2> 87216 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1996c7e[collection1_shard1_replica_n1] realtime] [junit4] 2> 87216 INFO (qtp17322912-1277) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1570628321097547776)} 0 4 [junit4] 2> 87218 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321103839232,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87218 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dac5c5 commitCommandVersion:1570628321103839232 [junit4] 2> 87219 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d2e7ef[collection1_shard2_replica_n1] main] [junit4] 2> 87219 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87220 INFO (searcherExecutor-580-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@1d2e7ef[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 87220 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2 [junit4] 2> 87221 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321106984960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87221 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@24d6b2 commitCommandVersion:1570628321106984960 [junit4] 2> 87222 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1376b2f[collection1_shard1_replica_n1] main] [junit4] 2> 87222 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87224 INFO (searcherExecutor-581-thread-1-processing-n:127.0.0.1:34125_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@1376b2f[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 87224 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2 [junit4] 2> 87224 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1570628321096499200),commit=} 0 13 [junit4] 2> 87252 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_1 (1570628321113276416), id_3 (1570628321115373568), id_4 (1570628321115373569), id_5 (1570628321115373570), id_6 (1570628321115373571), id_7 (1570628321116422144), id_8 (1570628321116422145), id_9 (1570628321116422146), id_11 (1570628321116422147), id_12 (1570628321116422148), ... (125 adds)]} 0 25 [junit4] 2> 87264 INFO (qtp28071416-1272) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_2 (1570628321112227840), id_10 (1570628321115373568), id_13 (1570628321115373569), id_14 (1570628321115373570), id_16 (1570628321116422144), id_17 (1570628321116422145), id_18 (1570628321116422146), id_21 (1570628321116422147), id_22 (1570628321116422148), id_23 (1570628321116422149), ... (125 adds)]} 0 37 [junit4] 2> 87274 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321162559488,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87274 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@24d6b2 commitCommandVersion:1570628321162559488 [junit4] 2> 87278 INFO (qtp17322912-1366) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321166753792,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87278 INFO (qtp17322912-1366) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dac5c5 commitCommandVersion:1570628321166753792 [junit4] 2> 87278 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b65ae3[collection1_shard1_replica_n1] main] [junit4] 2> 87280 INFO (searcherExecutor-581-thread-1-processing-n:127.0.0.1:34125_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@1b65ae3[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C125)))} [junit4] 2> 87280 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87280 INFO (qtp28071416-1273) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6 [junit4] 2> 87282 INFO (qtp17322912-1366) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ecb52[collection1_shard2_replica_n1] main] [junit4] 2> 87283 INFO (qtp17322912-1366) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87283 INFO (searcherExecutor-580-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@4ecb52[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C125)))} [junit4] 2> 87284 INFO (qtp17322912-1366) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6 [junit4] 2> 87285 INFO (qtp28071416-1272) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 12 [junit4] 2> 87290 INFO (qtp1788311-1256) [n:127.0.0.1:45811_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 87295 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={expr=let(a%3Dtimeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),+b%3Dselect(timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f)),mult(2,+count(*))+as+nvalue),+c%3Dcol(a,+count(*)),+d%3Dcol(b,+nvalue),+tuple(colc%3Dc,+cold%3Dd,+conv%3Dconv(c,d)))&wt=json&version=2.2} status=0 QTime=2 [junit4] 2> 87335 INFO (qtp28071416-1246) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497867890536&isShard=true&wt=javabin} hits=125 status=0 QTime=17 [junit4] 2> 87336 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497867890536&isShard=true&wt=javabin} hits=125 status=0 QTime=17 [junit4] 2> 87342 INFO (qtp17322912-1277) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&rows=0&wt=javabin&version=2} hits=250 status=0 QTime=45 [junit4] 2> 87348 INFO (qtp17322912-1367) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497867890582&isShard=true&wt=javabin} hits=125 status=0 QTime=3 [junit4] 2> 87350 INFO (qtp28071416-1275) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497867890582&isShard=true&wt=javabin} hits=125 status=0 QTime=4 [junit4] 2> 87350 INFO (qtp28071416-1272) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&_stateVer_=collection1:3&field=test_dt&rows=0&wt=javabin&version=2} hits=250 status=0 QTime=7 [junit4] 2> 87372 INFO (TEST-StreamExpressionTest.testConvolution-seed#[560B98935779CD52]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConvolution [junit4] 2> 87394 INFO (TEST-StreamExpressionTest.testTimeSeriesStream-seed#[560B98935779CD52]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTimeSeriesStream [junit4] 2> 87396 INFO (qtp28071416-1246) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dae95e[collection1_shard1_replica_n1] realtime] [junit4] 2> 87397 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1675c9f[collection1_shard2_replica_n1] realtime] [junit4] 2> 87397 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1570628321290485760)} 0 1 [junit4] 2> 87399 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321293631488,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87399 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@24d6b2 commitCommandVersion:1570628321293631488 [junit4] 2> 87399 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321293631488,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87399 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dac5c5 commitCommandVersion:1570628321293631488 [junit4] 2> 87399 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@10dbb1a[collection1_shard1_replica_n1] main] [junit4] 2> 87400 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87400 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ef1e[collection1_shard2_replica_n1] main] [junit4] 2> 87400 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87401 INFO (searcherExecutor-581-thread-1-processing-n:127.0.0.1:34125_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@10dbb1a[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 87401 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2 [junit4] 2> 87401 INFO (searcherExecutor-580-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@4ef1e[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 87401 INFO (qtp17322912-1265) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34125/solr/collection1_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2 [junit4] 2> 87402 INFO (qtp28071416-1246) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1570628321289437184),commit=} 0 7 [junit4] 2> 87484 INFO (qtp28071416-1378) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_2 (1570628321298874368), id_10 (1570628321299922944), id_13 (1570628321299922945), id_14 (1570628321300971520), id_16 (1570628321300971521), id_17 (1570628321300971522), id_18 (1570628321300971523), id_21 (1570628321300971524), id_22 (1570628321300971525), id_23 (1570628321300971526), ... (125 adds)]} 0 80 [junit4] 2> 87484 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[id_1 (1570628321298874368), id_3 (1570628321299922944), id_4 (1570628321299922945), id_5 (1570628321300971520), id_6 (1570628321300971521), id_7 (1570628321300971522), id_8 (1570628321300971523), id_9 (1570628321300971524), id_11 (1570628321300971525), id_12 (1570628321300971526), ... (125 adds)]} 0 79 [junit4] 2> 87486 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321384857600,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87486 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@24d6b2 commitCommandVersion:1570628321384857600 [junit4] 2> 87487 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570628321385906176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 87487 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dac5c5 commitCommandVersion:1570628321385906176 [junit4] 2> 87490 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b859d9[collection1_shard1_replica_n1] main] [junit4] 2> 87490 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@144c5cc[collection1_shard2_replica_n1] main] [junit4] 2> 87491 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87492 INFO (searcherExecutor-581-thread-1-processing-n:127.0.0.1:34125_solr x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@1b859d9[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C125)))} [junit4] 2> 87493 INFO (searcherExecutor-580-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@144c5cc[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C125)))} [junit4] 2> 87493 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 87493 INFO (qtp17322912-1254) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6 [junit4] 2> 87494 INFO (qtp28071416-1253) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 7 [junit4] 2> 87494 INFO (qtp17322912-1366) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 10 [junit4] 2> 87499 INFO (qtp28071416-1272) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={expr=timeseries(collection1,+q%3D"*:*",+start%3D"2013-01-01T01:00:00.000Z",+end%3D"2016-12-01T01:00:00.000Z",+gap%3D"%2B1YEAR",+field%3D"test_dt",+count(*),+sum(price_f),+max(price_f),+min(price_f))&wt=json&version=2.2} status=0 QTime=0 [junit4] 2> 87502 INFO (qtp17322912-1269) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.S.Request [collection1_shard2_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:34515/solr/collection1_shard2_replica_n1/&rows=0&version=2&q=*:*&json.facet={"timeseries":{"type":"range","field":"test_dt","start":"2013-01-01T01:00:00.000Z","end":"2016-12-01T01:00:00.000Z","gap":"%2B1YEAR","facet":{"facet_0":"sum(price_f)","facet_1":"max(price_f)","facet_2":"min(price_f)"}}}&field=test_dt&NOW=1497867890738&isShard=true&wt=javabin} hits=125 status=0 QTime=1 [junit4] 2> 87503 INFO (qtp28071416-1270) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:3&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&shard.url=http://127.0.0.1:341 [...truncated too long message...] 2116 [junit4] 2> 185387 INFO (jetty-closer-203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 185387 INFO (jetty-closer-203-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 185387 WARN (zkCallback-221-thread-2-processing-n:127.0.0.1:45811_solr) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 185387 INFO (zkCallback-223-thread-2-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 185387 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:44425_solr) [n:127.0.0.1:44425_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 185390 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 185390 INFO (zkCallback-222-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 185390 INFO (jetty-closer-203-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@f34312{/solr,null,UNAVAILABLE} [junit4] 2> 185391 INFO (zkCallback-222-thread-3-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34125_solr [junit4] 2> 185392 INFO (jetty-closer-203-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 185393 INFO (jetty-closer-203-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 185394 INFO (jetty-closer-203-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 185394 INFO (jetty-closer-203-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 185395 WARN (zkCallback-225-thread-1-processing-n:127.0.0.1:44425_solr) [n:127.0.0.1:44425_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 185395 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:34515_solr x:collection1_shard2_replica_n1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 185395 INFO (zkCallback-223-thread-2-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 185395 INFO (jetty-closer-203-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1739a04{/solr,null,UNAVAILABLE} [junit4] 2> 185396 INFO (jetty-closer-203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 185396 INFO (zkCallback-222-thread-3-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 185398 INFO (jetty-closer-203-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 185398 INFO (jetty-closer-203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 185398 INFO (coreCloseExecutor-772-thread-1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@12aaa14 [junit4] 2> 185398 INFO (jetty-closer-203-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 185402 INFO (coreCloseExecutor-773-thread-1) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@18b2c94 [junit4] 2> 185402 INFO (coreCloseExecutor-772-thread-1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n1, tag=19573268 [junit4] 2> 185402 INFO (coreCloseExecutor-773-thread-1) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n1, tag=25898132 [junit4] 2> 185405 INFO (coreCloseExecutor-772-thread-1) [n:127.0.0.1:34515_solr c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader, tag=19573268 [junit4] 2> 185405 INFO (coreCloseExecutor-773-thread-1) [n:127.0.0.1:34125_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=25898132 [junit4] 2> 186906 WARN (zkCallback-222-thread-3-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 186906 WARN (zkCallback-223-thread-2-processing-n:127.0.0.1:34515_solr) [n:127.0.0.1:34515_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 186906 INFO (jetty-closer-203-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1df8c40{/solr,null,UNAVAILABLE} [junit4] 2> 186907 INFO (jetty-closer-203-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@220318{/solr,null,UNAVAILABLE} [junit4] 2> 186907 ERROR (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-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> 186907 INFO (SUITE-StreamExpressionTest-seed#[560B98935779CD52]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40381 40381 [junit4] 2> 188433 INFO (Thread-222) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40381 40381 [junit4] 2> 188434 WARN (Thread-222) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 92 /solr/aliases.json [junit4] 2> 92 /solr/clusterprops.json [junit4] 2> 14 /solr/configs/conf [junit4] 2> 8 /solr/configs/ml [junit4] 2> 4 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 92 /solr/clusterstate.json [junit4] 2> 6 /solr/collections/destinationCollection/state.json [junit4] 2> 4 /solr/collections/destination/state.json [junit4] 2> 4 /solr/collections/parallelDestinationCollection/state.json [junit4] 2> 4 /solr/collections/parallelDestinationCollection1/state.json [junit4] 2> 4 /solr/collections/mainCorpus/state.json [junit4] 2> 2 /solr/collections/collection1/state.json [junit4] 2> 2 /solr/collections/workQueue/state.json [junit4] 2> 2 /solr/collections/uknownCollection/state.json [junit4] 2> 2 /solr/collections/modelCollection/state.json [junit4] 2> 2 /solr/collections/checkpointCollection/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 92 /solr/live_nodes [junit4] 2> 92 /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/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_560B98935779CD52-001 [junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=es, timezone=Europe/Helsinki [junit4] 2> NOTE: Linux 4.10.0-21-generic i386/Oracle Corporation 1.8.0_131 (32-bit)/cpus=8,threads=1,free=180655296,total=398458880 [junit4] 2> NOTE: All tests run in this JVM: [LessThanEqualToEvaluatorTest, ArcCosineEvaluatorTest, TestRetryUtil, ConcurrentUpdateSolrClientTest, SolrExampleXMLTest, DocumentAnalysisResponseTest, BasicHttpSolrClientTest, SolrExampleJettyTest, GreaterThanEqualToEvaluatorTest, JsonValidatorTest, JettyWebappTest, SubtractEvaluatorTest, UuidEvaluatorTest, CompoundEvaluatorTest, LargeVolumeJettyTest, CloudSolrClientMultiConstructorTest, HyperbolicSineEvaluatorTest, TestBatchUpdate, RoundEvaluatorTest, OrEvaluatorTest, SolrZkClientTest, StreamExpressionToExpessionTest, SolrExampleStreamingTest, SolrExampleBinaryTest, MergeIndexesEmbeddedTest, TestCoreAdmin, CollectionAdminRequestRequiredParamsTest, SolrSchemalessExampleTest, CloudSolrClientBuilderTest, CloudSolrClientCacheTest, HttpSolrClientBuilderTest, HttpSolrClientSSLAuthConPoolTest, LBHttpSolrClientBuilderTest, GraphTest, JDBCStreamTest, StreamExpressionTest] [junit4] Completed [135/135 (1!)] on J2 in 104.51s, 89 tests, 1 error <<< FAILURES! [...truncated 38433 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
