Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 68FD0200D6B for ; Sun, 17 Dec 2017 00:32:33 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 5E47D160C27; Sat, 16 Dec 2017 23:32:33 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id B31AC160C11 for ; Sun, 17 Dec 2017 00:32:30 +0100 (CET) Received: (qmail 82210 invoked by uid 500); 16 Dec 2017 23:32:29 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 82200 invoked by uid 99); 16 Dec 2017 23:32:29 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 16 Dec 2017 23:32:29 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id 980241A02A8 for ; Sat, 16 Dec 2017 23:32:27 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.3 X-Spam-Level: X-Spam-Status: No, score=-0.3 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id cw6M7DPeYxQu for ; Sat, 16 Dec 2017 23:32:11 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id EB57A5F20B for ; Sat, 16 Dec 2017 23:32:10 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id B5AF310800D0 for ; Sat, 16 Dec 2017 23:32:09 +0000 (UTC) Date: Sat, 16 Dec 2017 23:30:47 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <77284992.101.1513467129745.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <152698455.81.1513420688590.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <152698455.81.1513420688590.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 344 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_100_1122963442.1513467129713" X-Jenkins-Job: Lucene-Solr-7.x-MacOSX X-Jenkins-Result: UNSTABLE archived-at: Sat, 16 Dec 2017 23:32:33 -0000 ------=_Part_100_1122963442.1513467129713 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/344/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC 1 tests failed. FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testDi= stributions Error Message: Stack Trace: java.lang.AssertionError =09at __randomizedtesting.SeedInfo.seed([7709C33AFEFFF840:C8F68290200518DC]= :0) =09at org.junit.Assert.fail(Assert.java:92) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.junit.Assert.assertTrue(Assert.java:54) =09at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testDistr= ibutions(StreamExpressionTest.java:6553) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 14840 lines...] [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionT= est [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x= -MacOSX/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.Stre= amExpressionTest_7709C33AFEFFF840-001/init-core-data-001 [junit4] 2> 14197 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS= _SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 14198 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and client= Auth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3DNaN= , ssl=3DNaN, clientAuth=3DNaN) w/ MAC_OS_X supressed clientAuth [junit4] 2> 14199 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.= solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urandom [junit4] 2> 14208 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 ser= vers in /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-sol= rj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_7709C33AFE= FFF840-001/tempDir-001 [junit4] 2> 14237 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 14243 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer client = port:0.0.0.0/0.0.0.0:0 [junit4] 2> 14243 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer Startin= g server [junit4] 2> 14443 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:52123 [junit4] 2> 14575 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to java.= io.IOException: ZooKeeperServer not running [junit4] 2> 14593 ERROR (Thread-8) [ ] o.a.z.s.ZooKeeperServer ZKSh= utdownHandler is not registered, so ZooKeeper server won't take any action = on ERROR or SHUTDOWN server state changes [junit4] 2> 16141 INFO (zkConnectionManagerCallback-6-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16680 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.Ser= ver jetty-9.3.20.v20170531 [junit4] 2> 16685 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.Ser= ver jetty-9.3.20.v20170531 [junit4] 2> 16685 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.Ser= ver jetty-9.3.20.v20170531 [junit4] 2> 16686 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Ser= ver jetty-9.3.20.v20170531 [junit4] 2> 16790 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.h.C= ontextHandler Started o.e.j.s.ServletContextHandler@76cae4dc{/solr,null,AVA= ILABLE} [junit4] 2> 16791 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.h.C= ontextHandler Started o.e.j.s.ServletContextHandler@6ae8509d{/solr,null,AVA= ILABLE} [junit4] 2> 16791 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.h.C= ontextHandler Started o.e.j.s.ServletContextHandler@7586f850{/solr,null,AVA= ILABLE} [junit4] 2> 16791 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.h.C= ontextHandler Started o.e.j.s.ServletContextHandler@39e71dc4{/solr,null,AVA= ILABLE} [junit4] 2> 16837 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.Abs= tractConnector Started ServerConnector@22a0d70b{HTTP/1.1,[http/1.1]}{127.0.= 0.1:52142} [junit4] 2> 16837 INFO (jetty-launcher-3-thread-2) [ ] o.e.j.s.Ser= ver Started @22579ms [junit4] 2> 16837 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.s.e= .JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D52142} [junit4] 2> 16843 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.Abs= tractConnector Started ServerConnector@3d47808a{HTTP/1.1,[http/1.1]}{127.0.= 0.1:52143} [junit4] 2> 16843 INFO (jetty-launcher-3-thread-3) [ ] o.e.j.s.Ser= ver Started @22585ms [junit4] 2> 16843 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.Abs= tractConnector Started ServerConnector@7005355a{HTTP/1.1,[http/1.1]}{127.0.= 0.1:52141} [junit4] 2> 16844 INFO (jetty-launcher-3-thread-4) [ ] o.e.j.s.Ser= ver Started @22586ms [junit4] 2> 16844 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.s.e= .JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D52141} [junit4] 2> 16842 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Abs= tractConnector Started ServerConnector@7a603d7b{HTTP/1.1,[http/1.1]}{127.0.= 0.1:52140} [junit4] 2> 16844 INFO (jetty-launcher-3-thread-1) [ ] o.e.j.s.Ser= ver Started @22586ms [junit4] 2> 16844 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.s.e= .JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D52140} [junit4] 2> 16843 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.s.e= .JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D52143} [junit4] 2> 16893 ERROR (jetty-launcher-3-thread-3) [ ] o.a.s.u.Sta= rtupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing o= r incomplete. [junit4] 2> 16893 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.Sol= rDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 16894 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.Sol= rDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 16894 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.Sol= rDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 16894 ERROR (jetty-launcher-3-thread-1) [ ] o.a.s.u.Sta= rtupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing o= r incomplete. [junit4] 2> 16893 ERROR (jetty-launcher-3-thread-2) [ ] o.a.s.u.Sta= rtupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing o= r incomplete. [junit4] 2> 16898 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.Sol= rDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 16898 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.Sol= rDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 16898 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.Sol= rDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 16894 ERROR (jetty-launcher-3-thread-4) [ ] o.a.s.u.Sta= rtupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing o= r incomplete. [junit4] 2> 16898 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.Sol= rDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 16898 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.Sol= rDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 16898 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.Sol= rDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 16899 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.Sol= rDispatchFilter |___/\___/_|_| Start time: 2017-12-16T23:12:16.621Z [junit4] 2> 16899 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.Sol= rDispatchFilter |___/\___/_|_| Start time: 2017-12-16T23:12:16.624Z [junit4] 2> 16897 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.Sol= rDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 16899 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.Sol= rDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 16899 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.Sol= rDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 16900 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.Sol= rDispatchFilter |___/\___/_|_| Start time: 2017-12-16T23:12:16.626Z [junit4] 2> 16899 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.Sol= rDispatchFilter |___/\___/_|_| Start time: 2017-12-16T23:12:16.624Z [junit4] 2> 16902 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.Sol= rResourceLoader solr home defaulted to 'solr/' (could not find system prope= rty or JNDI) [junit4] 2> 16909 INFO (zkConnectionManagerCallback-8-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16911 INFO (zkConnectionManagerCallback-10-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16918 INFO (zkConnectionManagerCallback-12-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16918 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.s.Sol= rDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 16920 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.s.Sol= rDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 16930 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.s.Sol= rDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 16931 INFO (zkConnectionManagerCallback-14-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 16932 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.s.Sol= rDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 17030 INFO (jetty-launcher-3-thread-2) [ ] o.a.s.c.ZkC= ontainer Zookeeper client=3D127.0.0.1:52123/solr [junit4] 2> 17031 INFO (jetty-launcher-3-thread-3) [ ] o.a.s.c.ZkC= ontainer Zookeeper client=3D127.0.0.1:52123/solr [junit4] 2> 17033 INFO (jetty-launcher-3-thread-1) [ ] o.a.s.c.ZkC= ontainer Zookeeper client=3D127.0.0.1:52123/solr [junit4] 2> 17035 INFO (jetty-launcher-3-thread-4) [ ] o.a.s.c.ZkC= ontainer Zookeeper client=3D127.0.0.1:52123/solr [junit4] 2> 17082 INFO (zkConnectionManagerCallback-24-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 17084 INFO (zkConnectionManagerCallback-28-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 17084 INFO (zkConnectionManagerCallback-30-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 17085 INFO (zkConnectionManagerCallback-26-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 17100 INFO (zkConnectionManagerCallback-32-thread-1-proce= ssing-n:127.0.0.1:52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.Connec= tionManager zkClient has connected [junit4] 2> 17101 INFO (zkConnectionManagerCallback-36-thread-1-proce= ssing-n:127.0.0.1:52140_solr) [n:127.0.0.1:52140_solr ] o.a.s.c.c.Connec= tionManager zkClient has connected [junit4] 2> 17101 INFO (zkConnectionManagerCallback-34-thread-1-proce= ssing-n:127.0.0.1:52141_solr) [n:127.0.0.1:52141_solr ] o.a.s.c.c.Connec= tionManager zkClient has connected [junit4] 2> 17102 INFO (zkConnectionManagerCallback-38-thread-1-proce= ssing-n:127.0.0.1:52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.Connec= tionManager zkClient has connected [junit4] 2> 17184 WARN (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.c.ZkController The _default configset could not be uploade= d. Please provide 'solr.default.confdir' parameter that points to a configs= et intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 17184 WARN (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.c.ZkController The _default configset could not be uploade= d. Please provide 'solr.default.confdir' parameter that points to a configs= et intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 17184 WARN (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.c.ZkController The _default configset could not be uploade= d. Please provide 'solr.default.confdir' parameter that points to a configs= et intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 17190 WARN (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.c.ZkController The _default configset could not be uploade= d. Please provide 'solr.default.confdir' parameter that points to a configs= et intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 17228 INFO (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 17228 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 17230 INFO (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 17230 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.= 0.0.1:52143_solr [junit4] 2> 17234 INFO (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_no= des/127.0.0.1:52141_solr [junit4] 2> 17234 INFO (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_no= des/127.0.0.1:52140_solr [junit4] 2> 17235 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.c.Overseer Overseer (id=3D99186503462813708-127.0.0.1:5214= 3_solr-n_0000000000) starting [junit4] 2> 17236 INFO (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 17239 INFO (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_no= des/127.0.0.1:52142_solr [junit4] 2> 17262 INFO (zkCallback-33-thread-1-processing-n:127.0.0.1= :52141_solr) [n:127.0.0.1:52141_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (0) -> (3) [junit4] 2> 17262 INFO (zkCallback-35-thread-1-processing-n:127.0.0.1= :52140_solr) [n:127.0.0.1:52140_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (0) -> (3) [junit4] 2> 17262 INFO (zkCallback-37-thread-1-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (0) -> (3) [junit4] 2> 17263 INFO (zkCallback-31-thread-1-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (0) -> (3) [junit4] 2> 17524 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_no= des/127.0.0.1:52143_solr [junit4] 2> 17530 INFO (zkCallback-37-thread-1-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (3) -> (4) [junit4] 2> 17530 INFO (zkCallback-35-thread-1-processing-n:127.0.0.1= :52140_solr) [n:127.0.0.1:52140_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (3) -> (4) [junit4] 2> 17530 INFO (zkCallback-31-thread-1-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (3) -> (4) [junit4] 2> 17530 INFO (zkCallback-33-thread-1-processing-n:127.0.0.1= :52141_solr) [n:127.0.0.1:52141_solr ] o.a.s.c.c.ZkStateReader Updated l= ive nodes from ZooKeeper... (3) -> (4) [junit4] 2> 17778 INFO (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52142.solr.no= de' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMB= eanServer@7c1fb3da [junit4] 2> 17788 INFO (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52142.solr.jv= m' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBea= nServer@7c1fb3da [junit4] 2> 17788 INFO (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52142.solr.je= tty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@7c1fb3da [junit4] 2> 17802 INFO (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52140.solr.no= de' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMB= eanServer@7c1fb3da [junit4] 2> 17803 INFO (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52140.solr.jv= m' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBea= nServer@7c1fb3da [junit4] 2> 17803 INFO (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52140.solr.je= tty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@7c1fb3da [junit4] 2> 17833 INFO (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52141.solr.no= de' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMB= eanServer@7c1fb3da [junit4] 2> 17833 INFO (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52141.solr.jv= m' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBea= nServer@7c1fb3da [junit4] 2> 17834 INFO (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52141.solr.je= tty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@7c1fb3da [junit4] 2> 17843 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52143.solr.no= de' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMB= eanServer@7c1fb3da [junit4] 2> 17843 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52143.solr.jv= m' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBea= nServer@7c1fb3da [junit4] 2> 17845 INFO (jetty-launcher-3-thread-1) [n:127.0.0.1:52140= _solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneat= h /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-solrj/tes= t/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_7709C33AFEFFF840= -001/tempDir-001/node1/. [junit4] 2> 17846 INFO (jetty-launcher-3-thread-2) [n:127.0.0.1:52142= _solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneat= h /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-solrj/tes= t/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_7709C33AFEFFF840= -001/tempDir-001/node2/. [junit4] 2> 17847 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52143.solr.je= tty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.Jmx= MBeanServer@7c1fb3da [junit4] 2> 17848 INFO (jetty-launcher-3-thread-4) [n:127.0.0.1:52141= _solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneat= h /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-solrj/tes= t/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_7709C33AFEFFF840= -001/tempDir-001/node4/. [junit4] 2> 17849 INFO (jetty-launcher-3-thread-3) [n:127.0.0.1:52143= _solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneat= h /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-solrj/tes= t/J1/temp/solr.client.solrj.io.stream.StreamExpressionTest_7709C33AFEFFF840= -001/tempDir-001/node3/. [junit4] 2> 18178 INFO (zkConnectionManagerCallback-48-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 18187 INFO (zkConnectionManagerCallback-52-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 18189 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKee= per... (0) -> (4) [junit4] 2> 18190 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 12= 7.0.0.1:52123/solr ready [junit4] 2> 18813 INFO (qtp1702888122-58) [n:127.0.0.1:52141_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with param= s replicationFactor=3D1&collection.configName=3Dconf&name=3Dcollection1_col= lection&nrtReplicas=3D1&action=3DCREATE&numShards=3D2&wt=3Djavabin&version= =3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 18854 INFO (OverseerThreadFactory-33-thread-1-processing-= n:127.0.0.1:52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.CreateCollecti= onCmd Create collection collection1_collection [junit4] 2> 19010 INFO (OverseerStateUpdate-99186503462813708-127.0.0= .1:52143_solr-n_0000000000) [n:127.0.0.1:52143_solr ] o.a.s.c.o.SliceMut= ator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1_collection_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:52142/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 19017 INFO (OverseerStateUpdate-99186503462813708-127.0.0= .1:52143_solr-n_0000000000) [n:127.0.0.1:52143_solr ] o.a.s.c.o.SliceMut= ator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1_collection", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection1_collection_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:52143/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 19249 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr ] = o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&coreNode= Name=3Dcore_node4&collection.configName=3Dconf&newCollection=3Dtrue&name=3D= collection1_collection_shard2_replica_n2&action=3DCREATE&numShards=3D2&coll= ection=3Dcollection1_collection&shard=3Dshard2&wt=3Djavabin&version=3D2&rep= licaType=3DNRT [junit4] 2> 19249 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr ]= o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&coreNod= eName=3Dcore_node3&collection.configName=3Dconf&newCollection=3Dtrue&name= =3Dcollection1_collection_shard1_replica_n1&action=3DCREATE&numShards=3D2&c= ollection=3Dcollection1_collection&shard=3Dshard1&wt=3Djavabin&version=3D2&= replicaType=3DNRT [junit4] 2> 19250 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr ]= o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 21474= 83647 transient cores [junit4] 2> 19250 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr ] = o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 214748= 3647 transient cores [junit4] 2> 19387 INFO (zkCallback-37-thread-1-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 19387 INFO (zkCallback-37-thread-2-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 19387 INFO (zkCallback-31-thread-1-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 19388 INFO (zkCallback-31-thread-2-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20302 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 20307 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 20338 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] = Schema name=3Dtest [junit4] 2> 20340 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n2] S= chema name=3Dtest [junit4] 2> 20479 WARN (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analy= sis class [solr.GeoHashField]. Please consult documentation how to replace = it accordingly. [junit4] 2> 20493 WARN (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analy= sis class [solr.LatLonType]. Please consult documentation how to replace it= accordingly. [junit4] 2> 20499 WARN (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and = destination for multiple copyFields (2) [junit4] 2> 20499 WARN (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.IndexSchema Field text is not multivalued and destinatio= n for multiple copyFields (3) [junit4] 2> 20500 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field i= d [junit4] 2> 20501 WARN (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and= destination for multiple copyFields (2) [junit4] 2> 20501 WARN (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destinati= on for multiple copyFields (3) [junit4] 2> 20501 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field = id [junit4] 2> 20504 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_= shard2_replica_n2' using configuration from collection collection1_collecti= on, trusted=3Dtrue [junit4] 2> 20504 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection= _shard1_replica_n1' using configuration from collection collection1_collect= ion, trusted=3Dtrue [junit4] 2> 20506 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52142.solr.c= ore.collection1_collection.shard1.replica_n1' (registry 'solr.core.collecti= on1_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserv= er.JmxMBeanServer@7c1fb3da [junit4] 2> 20506 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52143.solr.co= re.collection1_collection.shard2.replica_n2' (registry 'solr.core.collectio= n1_collection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@7c1fb3da [junit4] 2> 20506 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 20506 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 20506 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] = Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/so= lr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressi= onTest_7709C33AFEFFF840-001/tempDir-001/node2/collection1_collection_shard1= _replica_n1], dataDir=3D[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/so= lr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressi= onTest_7709C33AFEFFF840-001/tempDir-001/node2/./collection1_collection_shar= d1_replica_n1/data/] [junit4] 2> 20506 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n2] ] O= pening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/sol= r/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressio= nTest_7709C33AFEFFF840-001/tempDir-001/node3/collection1_collection_shard2_= replica_n2], dataDir=3D[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/sol= r/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.StreamExpressio= nTest_7709C33AFEFFF840-001/tempDir-001/node3/./collection1_collection_shard= 2_replica_n2/data/] [junit4] 2> 20602 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache= .solr.update.UpdateLog [junit4] 2> 20602 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSync= Level=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBucket= s=3D65536 [junit4] 2> 20603 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 20603 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 20605 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apach= e.solr.update.UpdateLog [junit4] 2> 20605 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyn= cLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBucke= ts=3D65536 [junit4] 2> 20606 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@66bfdedf[collection1= _collection_shard2_replica_n2] main] [junit4] 2> 20615 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 20616 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 20621 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c509701[collection= 1_collection_shard1_replica_n1] main] [junit4] 2> 20622 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO wit= h znodeBase: /configs/conf [junit4] 2> 20622 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed= .json using ZooKeeperStorageIO:path=3D/configs/conf [junit4] 2> 20623 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 20623 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO wi= th znodeBase: /configs/conf [junit4] 2> 20623 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_manage= d.json using ZooKeeperStorageIO:path=3D/configs/conf [junit4] 2> 20623 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms= . [junit4] 2> 20634 INFO (searcherExecutor-42-thread-1-processing-n:127= .0.0.1:52143_solr x:collection1_collection_shard2_replica_n2 s:shard2 c:col= lection1_collection r:core_node4) [n:127.0.0.1:52143_solr c:collection1_col= lection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o= .a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new s= earcher Searcher@66bfdedf[collection1_collection_shard2_replica_n2] main{Ex= itableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 20634 INFO (searcherExecutor-43-thread-1-processing-n:127= .0.0.1:52142_solr x:collection1_collection_shard1_replica_n1 s:shard1 c:col= lection1_collection r:core_node3) [n:127.0.0.1:52142_solr c:collection1_col= lection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1] o= .a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new s= earcher Searcher@5c509701[collection1_collection_shard1_replica_n1] main{Ex= itableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 20636 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent = updates, using new clock 1586984061881024512 [junit4] 2> 20637 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent= updates, using new clock 1586984061882073088 [junit4] 2> 20664 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to con= tinue. [junit4] 2> 20664 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to cont= inue. [junit4] 2> 20665 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try= and sync [junit4] 2> 20665 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - tr= y and sync [junit4] 2> 20667 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:52142/so= lr/collection1_collection_shard1_replica_n1/ [junit4] 2> 20667 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:52143/sol= r/collection1_collection_shard2_replica_n2/ [junit4] 2> 20669 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 20669 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 20669 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:52142/solr/collection1_co= llection_shard1_replica_n1/ has no replicas [junit4] 2> 20669 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:52143/solr/collection1_col= lection_shard2_replica_n2/ has no replicas [junit4] 2> 20669 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participa= ting in election, clear LIR [junit4] 2> 20669 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.ShardLeaderElectionContext Found all replicas participat= ing in election, clear LIR [junit4] 2> 20689 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://1= 27.0.0.1:52143/solr/collection1_collection_shard2_replica_n2/ shard2 [junit4] 2> 20693 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://= 127.0.0.1:52142/solr/collection1_collection_shard1_replica_n1/ shard1 [junit4] 2> 20802 INFO (zkCallback-37-thread-2-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20802 INFO (zkCallback-37-thread-3-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20802 INFO (zkCallback-31-thread-2-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20802 INFO (zkCallback-31-thread-1-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20806 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 20821 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores = params=3D{qt=3D/admin/cores&coreNodeName=3Dcore_node3&collection.configName= =3Dconf&newCollection=3Dtrue&name=3Dcollection1_collection_shard1_replica_n= 1&action=3DCREATE&numShards=3D2&collection=3Dcollection1_collection&shard= =3Dshard1&wt=3Djavabin&version=3D2&replicaType=3DNRT} status=3D0 QTime=3D15= 75 [junit4] 2> 20846 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 20849 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores p= arams=3D{qt=3D/admin/cores&coreNodeName=3Dcore_node4&collection.configName= =3Dconf&newCollection=3Dtrue&name=3Dcollection1_collection_shard2_replica_n= 2&action=3DCREATE&numShards=3D2&collection=3Dcollection1_collection&shard= =3Dshard2&wt=3Djavabin&version=3D2&replicaType=3DNRT} status=3D0 QTime=3D16= 03 [junit4] 2> 20922 INFO (qtp1702888122-58) [n:127.0.0.1:52141_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> 20955 INFO (zkCallback-31-thread-1-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20955 INFO (zkCallback-37-thread-1-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20955 INFO (zkCallback-37-thread-3-processing-n:127.0.0.1= :52143_solr) [n:127.0.0.1:52143_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 20955 INFO (zkCallback-31-thread-2-processing-n:127.0.0.1= :52142_solr) [n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader A cluster= state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:= /collections/collection1_collection/state.json] for collection [collection1= _collection] has occurred - updating... (live nodes size: [4]) [junit4] 2> 21925 INFO (qtp1702888122-58) [n:127.0.0.1:52141_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections para= ms=3D{replicationFactor=3D1&collection.configName=3Dconf&name=3Dcollection1= _collection&nrtReplicas=3D1&action=3DCREATE&numShards=3D2&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D3115 [junit4] 2> 21938 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries = to finish - collection: collection1_collection failOnTimeout:true timeout (= sec):90 [junit4] 2> 21939 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEFF= F840]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished = - collection: collection1_collection [junit4] 2> 21943 INFO (qtp1702888122-66) [n:127.0.0.1:52141_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with = params collections=3Dcollection1_collection&name=3Dcollection1&action=3DCRE= ATEALIAS&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 21948 INFO (OverseerCollectionConfigSetProcessor-99186503= 462813708-127.0.0.1:52143_solr-n_0000000000) [n:127.0.0.1:52143_solr ] o= .a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/= qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeep= er [junit4] 2> 22061 INFO (qtp1702888122-66) [n:127.0.0.1:52141_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections para= ms=3D{collections=3Dcollection1_collection&name=3Dcollection1&action=3DCREA= TEALIAS&wt=3Djavabin&version=3D2} status=3D0 QTime=3D117 [junit4] 2> 22097 INFO (TEST-StreamExpressionTest.testListStream-seed= #[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListStream [junit4] 2> 22211 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DT= OLEADER&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_s= hard2_replica_n2/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-158698406= 3525191680)} 0 9 [junit4] 2> 22217 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984063= 538823168,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 22217 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 3538823168,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 22217 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 22217 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 22219 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 22219 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 2 [junit4] 2> 22224 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 22225 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 7 [junit4] 2> 22229 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery=3D= *:* (-1586984063508414464),commit=3D} 0 112 [junit4] 2> 22362 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&wt=3Djavabin&version=3D2}{add=3D[hello (1586984063561891= 840), hello1 (1586984063688769536), hello2 (1586984063689818112)]} 0 127 [junit4] 2> 22366 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 3695060992,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 22368 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 22368 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 22369 INFO (qtp41083760-61) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984063= 698206720,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 22369 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 2 [junit4] 2> 22371 INFO (qtp41083760-61) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache= .solr.update.SolrIndexWriter@79dfaf8a commitCommandVersion:1586984063698206= 720 [junit4] 2> 22724 INFO (qtp41083760-61) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ec4e575[collection1= _collection_shard2_replica_n2] main] [junit4] 2> 22727 INFO (searcherExecutor-42-thread-1-processing-n:127= .0.0.1:52143_solr x:collection1_collection_shard2_replica_n2 s:shard2 c:col= lection1_collection r:core_node4) [n:127.0.0.1:52143_solr c:collection1_col= lection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o= .a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new s= earcher Searcher@6ec4e575[collection1_collection_shard2_replica_n2] main{Ex= itableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c3))= )} [junit4] 2> 22734 INFO (qtp41083760-61) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 22734 INFO (qtp41083760-61) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 367 [junit4] 2> 22736 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{commit=3D} 0 371 [junit4] 2> 22754 INFO (qtp1702888122-68) [n:127.0.0.1:52141_solr = ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147= 483647 transient cores [junit4] 2> 22789 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/stream params=3D{indent=3Doff&expr=3Dlist(search(coll= ection1,+q%3D"id:hello",++fl%3Did,+sort%3D"id+desc"),search(collection1,+q%= 3D"id:hello1",+fl%3Did,+sort%3D"id+desc"),search(collection1,+q%3D"id:hello= 2",+fl%3Did,+sort%3D"id+desc"))&wt=3Djson&version=3D2.2} status=3D0 QTime= =3D29 [junit4] 2> 22806 INFO (zkConnectionManagerCallback-56-thread-1-proce= ssing-n:127.0.0.1:52142_solr x:collection1_collection_shard1_replica_n1 s:s= hard1 c:collection1_collection r:core_node3) [n:127.0.0.1:52142_solr c:coll= ection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_re= plica_n1] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 22810 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0= ) -> (4) [junit4] 2> 22813 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:5= 2123/solr ready [junit4] 2> 22860 INFO (qtp302039255-49) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/select params=3D{q=3Did:hello&distrib=3Dfalse&fl=3Did= &sort=3Did+desc&wt=3Djson&version=3D2.2} hits=3D0 status=3D0 QTime=3D36 [junit4] 2> 22901 INFO (qtp41083760-52) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] we= bapp=3D/solr path=3D/select params=3D{q=3Did:hello&distrib=3Dfalse&fl=3Did&= sort=3Did+desc&wt=3Djson&version=3D2.2} hits=3D1 status=3D0 QTime=3D77 [junit4] 2> 22915 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] we= bapp=3D/solr path=3D/select params=3D{q=3Did:hello1&distrib=3Dfalse&fl=3Did= &sort=3Did+desc&wt=3Djson&version=3D2.2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 22915 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/select params=3D{q=3Did:hello1&distrib=3Dfalse&fl=3Di= d&sort=3Did+desc&wt=3Djson&version=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 22923 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/select params=3D{q=3Did:hello2&distrib=3Dfalse&fl=3Di= d&sort=3Did+desc&wt=3Djson&version=3D2.2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 22923 INFO (qtp41083760-57) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.c.S.Request [collection1_collection_shard2_replica_n2] we= bapp=3D/solr path=3D/select params=3D{q=3Did:hello2&distrib=3Dfalse&fl=3Did= &sort=3Did+desc&wt=3Djson&version=3D2.2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 22936 INFO (TEST-StreamExpressionTest.testListStream-seed= #[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListStream [junit4] 2> 22981 INFO (TEST-StreamExpressionTest.testPriorityStream-= seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPriori= tyStream [junit4] 2> 23080 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@35a6a30c[collection1= _collection_shard2_replica_n2] realtime] [junit4] 2> 23081 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DT= OLEADER&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_s= hard2_replica_n2/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-158698406= 4344129536)} 0 96 [junit4] 2> 23086 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 4450035712,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23086 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984064= 450035712,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23086 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 23087 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache= .solr.update.SolrIndexWriter@79dfaf8a commitCommandVersion:1586984064450035= 712 [junit4] 2> 23088 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23088 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 2 [junit4] 2> 23089 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@55ee075d[collection1= _collection_shard2_replica_n2] main] [junit4] 2> 23091 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23094 INFO (searcherExecutor-42-thread-1-processing-n:127= .0.0.1:52143_solr x:collection1_collection_shard2_replica_n2 s:shard2 c:col= lection1_collection r:core_node4) [n:127.0.0.1:52143_solr c:collection1_col= lection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n2] o= .a.s.c.SolrCore [collection1_collection_shard2_replica_n2] Registered new s= earcher Searcher@55ee075d[collection1_collection_shard2_replica_n2] main{Ex= itableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 23117 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 31 [junit4] 2> 23120 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery=3D= *:* (-1586984064342032384),commit=3D} 0 138 [junit4] 2> 23128 INFO (TEST-StreamExpressionTest.testPriorityStream-= seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPriority= Stream [junit4] IGNOR/A 0.21s J1 | StreamExpressionTest.testPriorityStream [junit4] > Assumption #1: got: , expected: is [junit4] 2> 23180 INFO (TEST-StreamExpressionTest.testClassifyStream-= seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testClassi= fyStream [junit4] 2> 23202 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DTO= LEADER&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_sh= ard1_replica_n1/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-1586984064= 559087616)} 0 14 [junit4] 2> 23207 INFO (qtp41083760-57) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984064= 576913408,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23207 INFO (qtp41083760-57) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 23208 INFO (qtp302039255-49) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 4577961984,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23208 INFO (qtp302039255-49) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 23210 INFO (qtp41083760-57) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23210 INFO (qtp41083760-57) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_= shard1_replica_n1/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 3 [junit4] 2> 23211 INFO (qtp302039255-49) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23211 INFO (qtp302039255-49) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_= shard1_replica_n1/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 3 [junit4] 2> 23212 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcolle= ction1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery= =3D*:* (-1586984064551747584),commit=3D} 0 30 [junit4] 2> 23213 INFO (TEST-StreamExpressionTest.testClassifyStream-= seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testClassify= Stream [junit4] IGNOR/A 0.07s J1 | StreamExpressionTest.testClassifyStream [junit4] > Assumption #1: got: , expected: is [junit4] 2> 23252 INFO (TEST-StreamExpressionTest.testStandardize-see= d#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStandardi= ze [junit4] 2> 23261 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DT= OLEADER&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_s= hard2_replica_n2/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-158698406= 4631439360)} 0 2 [junit4] 2> 23266 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984064= 638779392,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23266 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 23268 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23268 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 2 [junit4] 2> 23270 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 4642973696,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23270 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 23272 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23272 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 2 [junit4] 2> 23274 INFO (qtp41083760-52) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery=3D= *:* (-1586984064628293632),commit=3D} 0 20 [junit4] 2> 23291 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/stream params=3D{indent=3Doff&expr=3Dlet(echo%3Dtrue,= +a%3Dstandardize(matrix(array(1,2,3),+array(4,5,6))),+b%3Dstandardize(array= (4,5,6)))&wt=3Djson&version=3D2.2} status=3D0 QTime=3D9 [junit4] 2> 23573 INFO (TEST-StreamExpressionTest.testStandardize-see= d#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStandardize [junit4] 2> 23681 INFO (TEST-StreamExpressionTest.testLogNormalDistri= bution-seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting tes= tLogNormalDistribution [junit4] 2> 23686 INFO (qtp302039255-79) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DT= OLEADER&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_s= hard2_replica_n2/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-158698406= 5078132736)} 0 1 [junit4] 2> 23689 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 5082327040,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23689 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 23689 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984065= 082327040,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 23689 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 23690 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23690 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 1 [junit4] 2> 23690 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 23690 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 1 [junit4] 2> 23691 INFO (qtp41083760-56) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery=3D= *:* (-1586984065076035584),commit=3D} 0 8 [junit4] 2> 23698 INFO (qtp302039255-49) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/stream params=3D{indent=3Doff&expr=3Dlet(echo%3Dtrue,= +a%3Ddescribe(sample(logNormalDistribution(.1,+0),10000)),+b%3Ddescribe(sam= ple(logNormalDistribution(.3,+0),10000)),+c%3Ddescribe(sample(logNormalDist= ribution(.6,+0),10000)),d%3Dmean(sample(logNormalDistribution(.3,+0),10000)= ),+e%3Dmean(sample(logNormalDistribution(.3,+2),10000)),+)&wt=3Djson&versio= n=3D2.2} status=3D0 QTime=3D1 [junit4] 2> 23952 INFO (OverseerCollectionConfigSetProcessor-99186503= 462813708-127.0.0.1:52143_solr-n_0000000000) [n:127.0.0.1:52143_solr ] o= .a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/= qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeep= er [junit4] 2> 24475 INFO (TEST-StreamExpressionTest.testLogNormalDistri= bution-seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testL= ogNormalDistribution [junit4] 2> 24507 INFO (TEST-StreamExpressionTest.testArray-seed#[770= 9C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testArray [junit4] 2> 24518 INFO (qtp41083760-61) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DTO= LEADER&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_sh= ard1_replica_n1/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-1586984065= 950547968)} 0 1 [junit4] 2> 24521 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984065= 954742272,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 24521 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 5954742272,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 24521 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 24521 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 24524 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 24524 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_= shard1_replica_n1/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 2 [junit4] 2> 24524 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 24524 INFO (qtp41083760-60) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_= shard1_replica_n1/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 3 [junit4] 2> 24526 INFO (qtp302039255-75) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcolle= ction1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery= =3D*:* (-1586984065941110784),commit=3D} 0 17 [junit4] 2> 24531 INFO (qtp302039255-76) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/stream params=3D{indent=3Doff&expr=3Darray(1,+2,+3,+3= 00,+2,+500)&wt=3Djson&version=3D2.2} status=3D0 QTime=3D0 [junit4] 2> 24537 INFO (qtp302039255-77) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] w= ebapp=3D/solr path=3D/stream params=3D{indent=3Doff&expr=3Darray(1.122,+2.2= 22,+3.333,+300.1,+2.13,+500.23)&wt=3Djson&version=3D2.2} status=3D0 QTime= =3D0 [junit4] 2> 24538 INFO (TEST-StreamExpressionTest.testArray-seed#[770= 9C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testArray [junit4] 2> 24559 INFO (TEST-StreamExpressionTest.testTopicStream-see= d#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTopicStre= am [junit4] 2> 24568 INFO (qtp302039255-79) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DT= OLEADER&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_s= hard2_replica_n2/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-158698406= 5997733888)} 0 6 [junit4] 2> 24571 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984066= 007171072,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 24571 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 6007171072,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 24571 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 24571 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 24575 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 24575 INFO (qtp302039255-78) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_sha= rd1_replica_n1] webapp=3D/solr path=3D/update params=3D{update.distrib=3DF= ROMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 4 [junit4] 2> 24576 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 24577 INFO (qtp41083760-59) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DFR= OMLEADER&waitSearcher=3Dtrue&openSearcher=3Dtrue&commit=3Dtrue&softCommit= =3Dfalse&distrib.from=3Dhttp://127.0.0.1:52143/solr/collection1_collection_= shard2_replica_n2/&commit_end_point=3Dtrue&wt=3Djavabin&version=3D2&expunge= Deletes=3Dfalse}{commit=3D} 0 5 [junit4] 2> 24578 INFO (qtp41083760-57) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{_stateVer_=3Dcollec= tion1_collection:4&commit=3Dtrue&wt=3Djavabin&version=3D2}{deleteByQuery=3D= *:* (-1586984065995636736),commit=3D} 0 18 [junit4] 2> 24579 INFO (TEST-StreamExpressionTest.testTopicStream-see= d#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTopicStream [junit4] IGNOR/A 0.04s J1 | StreamExpressionTest.testTopicStream [junit4] > Assumption #1: got: , expected: is [junit4] 2> 24601 INFO (TEST-StreamExpressionTest.testParameterSubsti= tution-seed#[7709C33AFEFFF840]) [ ] o.a.s.SolrTestCaseJ4 ###Starting tes= tParameterSubstitution [junit4] 2> 24646 INFO (qtp41083760-52) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.p.LogUpdateProcessorFactory [collection1_collection_shar= d2_replica_n2] webapp=3D/solr path=3D/update params=3D{update.distrib=3DTO= LEADER&distrib.from=3Dhttp://127.0.0.1:52142/solr/collection1_collection_sh= ard1_replica_n1/&wt=3Djavabin&version=3D2}{deleteByQuery=3D*:* (-1586984066= 047016960)} 0 37 [junit4] 2> 24649 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D1586984066= 088960000,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeD= eletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 24649 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW= .commit. [junit4] 2> 24649 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=3D158698406= 6088960000,optimize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expunge= Deletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 24649 INFO (qtp302039255-80) [n:127.0.0.1:52142_solr c:co= llection1_collection s:shard1 r:core_node3 x:collection1_collection_shard1_= replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping I= W.commit. [junit4] 2> 24650 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_r= eplica_n2] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 24650 INFO (qtp41083760-54) [n:127.0.0.1:52143_solr c:col= lection1_collection s:shard2 r:core_node4 x:collection1_collection_ [...truncated too long message...] AwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 116771 WARN (zkCallback-33-thread-2-processing-n:127.0.0.= 1:52141_solr) [n:127.0.0.1:52141_solr ] o.a.s.c.ZkController Unable to r= egister nodeLost path for 127.0.0.1:52142_solr [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /autoscaling/nodeLost/127.0.0.1= :52142_solr [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java= :783) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.lambda$cre= ate$7(SolrZkClient.java:364) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.create(Sol= rZkClient.java:364) [junit4] 2> =09at org.apache.solr.cloud.ZkController.lambda$registerLi= veNodesListener$2(ZkController.java:879) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.lambda$re= freshLiveNodes$1(ZkStateReader.java:704) [junit4] 2> =09at java.util.concurrent.ConcurrentHashMap$KeySetView.fo= rEach(ConcurrentHashMap.java:4649) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.refreshLi= veNodes(ZkStateReader.java:703) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.access$90= 0(ZkStateReader.java:72) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$LiveNodeW= atcher.refreshAndWatch(ZkStateReader.java:1090) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$LiveNodeW= atcher.process(ZkStateReader.java:1085) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$1.lambda$p= rocess$1(SolrZkClient.java:268) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 116780 WARN (zkCallback-33-thread-2-processing-n:127.0.0.= 1:52141_solr) [n:127.0.0.1:52141_solr ] o.a.s.c.ZkController Unable to r= egister nodeLost path for 127.0.0.1:52143_solr [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /autoscaling/nodeLost/127.0.0.1= :52143_solr [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java= :783) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.lambda$cre= ate$7(SolrZkClient.java:364) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.create(Sol= rZkClient.java:364) [junit4] 2> =09at org.apache.solr.cloud.ZkController.lambda$registerLi= veNodesListener$2(ZkController.java:879) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.lambda$re= freshLiveNodes$1(ZkStateReader.java:704) [junit4] 2> =09at java.util.concurrent.ConcurrentHashMap$KeySetView.fo= rEach(ConcurrentHashMap.java:4649) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.refreshLi= veNodes(ZkStateReader.java:703) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader.access$90= 0(ZkStateReader.java:72) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$LiveNodeW= atcher.refreshAndWatch(ZkStateReader.java:1090) [junit4] 2> =09at org.apache.solr.common.cloud.ZkStateReader$LiveNodeW= atcher.process(ZkStateReader.java:1085) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$1.lambda$p= rocess$1(SolrZkClient.java:268) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1149) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:624) [junit4] 2> =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 116780 INFO (jetty-closer-4-thread-2) [ ] o.e.j.s.h.Co= ntextHandler Stopped o.e.j.s.ServletContextHandler@76cae4dc{/solr,null,UNAV= AILABLE} [junit4] 2> 116784 INFO (jetty-closer-4-thread-1) [ ] o.e.j.s.h.Co= ntextHandler Stopped o.e.j.s.ServletContextHandler@6ae8509d{/solr,null,UNAV= AILABLE} [junit4] 2> 116788 ERROR (SUITE-StreamExpressionTest-seed#[7709C33AFEF= FF840]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not regi= stered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN serv= er state changes [junit4] 2> 116790 INFO (SUITE-StreamExpressionTest-seed#[7709C33AFEF= FF840]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52123 52= 123 [junit4] 2> 117899 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer connec= ting to 127.0.0.1:52123 52123 [junit4] 2> 118063 WARN (Thread-8) [ ] o.a.s.c.ZkTestServer Watch = limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =0980=09/solr/aliases.json [junit4] 2> =0980=09/solr/clusterprops.json [junit4] 2> =0912=09/solr/configs/conf [junit4] 2> =094=09/solr/security.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =0980=09/solr/clusterstate.json [junit4] 2> =0920=09/solr/collections/parallelDestinationCollection/st= ate.json [junit4] 2> =0920=09/solr/collections/destinationCollection/state.json [junit4] 2> =0918=09/solr/collections/parallelDestinationCollection1/s= tate.json [junit4] 2> =0912=09/solr/collections/collection1_collection/state.jso= n [junit4] 2> =0910=09/solr/collections/mainCorpus1/state.json [junit4] 2> =0910=09/solr/collections/destination/state.json [junit4] 2> =0910=09/solr/collections/destination1/state.json [junit4] 2> =0910=09/solr/collections/workQueue1/state.json [junit4] 2> =0910=09/solr/collections/workQueue/state.json [junit4] 2> =0910=09/solr/collections/mainCorpus/state.json [junit4] 2> =092=09/solr/overseer_elect/election/99186503462813708-127= .0.0.1:52143_solr-n_0000000000 [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =0980=09/solr/live_nodes [junit4] 2> =0980=09/solr/collections [junit4] 2>=20 [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/w= orkspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-solrj/test/J1/temp/solr.cli= ent.solrj.io.stream.StreamExpressionTest_7709C33AFEFFF840-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene70): {expr_= s=3DPostingsFormat(name=3DLuceneFixedGap), join2_s=3DLucene50(blocksize=3D1= 28), multiDefault=3DLucene50(blocksize=3D128), subject=3DLucene50(blocksize= =3D128), a1_s=3DLucene50(blocksize=3D128), a2_s=3DPostingsFormat(name=3DLuc= eneFixedGap), test_t=3DPostingsFormat(name=3DMockRandom), level1_s=3DPostin= gsFormat(name=3DLuceneFixedGap), ident_s=3DLucene50(blocksize=3D128), body_= t=3DPostingsFormat(name=3DMockRandom), s_multi=3DPostingsFormat(name=3DLuce= neFixedGap), col_s=3DLucene50(blocksize=3D128), level2_s=3DLucene50(blocksi= ze=3D128), a_ss=3DLucene50(blocksize=3D128), a_s=3DPostingsFormat(name=3DLu= ceneFixedGap), a_t=3DLucene50(blocksize=3D128), side_s=3DPostingsFormat(nam= e=3DMockRandom), id=3DPostingsFormat(name=3DMockRandom), text=3DPostingsFor= mat(name=3DLuceneFixedGap)}, docValues:{expr_s=3DDocValuesFormat(name=3DDir= ect), join2_s=3DDocValuesFormat(name=3DMemory), multiDefault=3DDocValuesFor= mat(name=3DMemory), price_f=3DDocValuesFormat(name=3DMemory), a1_s=3DDocVal= uesFormat(name=3DAsserting), intDefault=3DDocValuesFormat(name=3DAsserting)= , a_f=3DDocValuesFormat(name=3DAsserting), join1_i=3DDocValuesFormat(name= =3DDirect), a2_s=3DDocValuesFormat(name=3DDirect), a_i=3DDocValuesFormat(na= me=3DLucene70), join3_i=3DDocValuesFormat(name=3DLucene70), test_i=3DDocVal= uesFormat(name=3DMemory), field_i=3DDocValuesFormat(name=3DMemory), level1_= s=3DDocValuesFormat(name=3DDirect), s_multi=3DDocValuesFormat(name=3DDirect= ), col_s=3DDocValuesFormat(name=3DAsserting), level2_s=3DDocValuesFormat(na= me=3DAsserting), a_ss=3DDocValuesFormat(name=3DMemory), a_s=3DDocValuesForm= at(name=3DDirect), id=3DDocValuesFormat(name=3DLucene70), timestamp=3DDocVa= luesFormat(name=3DMemory), i_multi=3DDocValuesFormat(name=3DLucene70), b_ls= =3DDocValuesFormat(name=3DAsserting), test_dt=3DDocValuesFormat(name=3DDire= ct), ident_s=3DDocValuesFormat(name=3DAsserting), order_i=3DDocValuesFormat= (name=3DMemory), _version_=3DDocValuesFormat(name=3DAsserting), side_s=3DDo= cValuesFormat(name=3DLucene70), miles_i=3DDocValuesFormat(name=3DAsserting)= }, maxPointsInLeafNode=3D465, maxMBSortInHeap=3D7.466029071790098, sim=3DRa= ndomSimilarity(queryNorm=3Dfalse): {}, locale=3Dmt-MT, timezone=3DAmerica/B= elize [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144= (64-bit)/cpus=3D3,threads=3D1,free=3D39654392,total=3D116154368 [junit4] 2> NOTE: All tests run in this JVM: [LargeVolumeEmbeddedTest,= TestFastInputStream, ClientUtilsTest, TestConfigSetAdminRequest, StreamExp= ressionTest] [junit4] Completed [61/146 (1!)] on J1 in 103.95s, 139 tests, 1 failure,= 9 skipped <<< FAILURES! [...truncated 47442 lines...] ------=_Part_100_1122963442.1513467129713 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_100_1122963442.1513467129713--