From dev-return-354611-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Thu May 2 00:15:33 2019 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [207.244.88.153]) by mx-eu-01.ponee.io (Postfix) with SMTP id C44D0180629 for ; Thu, 2 May 2019 02:15:32 +0200 (CEST) Received: (qmail 55917 invoked by uid 500); 2 May 2019 00:15:30 -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 55901 invoked by uid 99); 2 May 2019 00:15:30 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 02 May 2019 00:15:30 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id CBEF0182614 for ; Thu, 2 May 2019 00:15:29 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.299 X-Spam-Level: X-Spam-Status: No, score=-0.299 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, URIBL_BLOCKED=0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id nSfZVRAF8pwz for ; Thu, 2 May 2019 00:15:23 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTP id B2B695FE0C for ; Thu, 2 May 2019 00:15:22 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id 1098410801BE for ; Thu, 2 May 2019 00:15:21 +0000 (UTC) Date: Thu, 2 May 2019 00:14:16 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1375174388.21.1556756121069.JavaMail.jenkins@serv1.sd-datasolutions.de> In-Reply-To: <1827126778.3.1556720486004.JavaMail.jenkins@serv1.sd-datasolutions.de> References: <1827126778.3.1556720486004.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-9) - Build # 114 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_20_1761756202.1556756120978" X-Jenkins-Job: Lucene-Solr-8.x-MacOSX X-Jenkins-Result: FAILURE ------=_Part_20_1761756202.1556756120978 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/114/ Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseSerialGC 1 tests failed. FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test Error Message: Thread pool didn't terminate within 15 secs Stack Trace: java.lang.AssertionError: Thread pool didn't terminate within 15 secs =09at __randomizedtesting.SeedInfo.seed([1F004AB1981A3781:9754756B36E65A79]= :0) =09at org.junit.Assert.fail(Assert.java:88) =09at org.junit.Assert.assertTrue(Assert.java:41) =09at org.apache.solr.update.TestInPlaceUpdatesDistrib.reorderedDBQsResurre= ctionTest(TestInPlaceUpdatesDistrib.java:852) =09at org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpda= tesDistrib.java:179) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:564) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1750) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:938) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:974) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:988) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:1054) =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:947) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:832) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:883) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:894) =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 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 15684 lines...] [junit4] Suite: org.apache.solr.update.TestInPlaceUpdatesDistrib [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x= -MacOSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDis= trib_1F004AB1981A3781-001/init-core-data-001 [junit4] 2> 3247519 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hos= tContext system property: /_/ [junit4] 2> 3247520 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs = to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOS= X/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/L= ucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes= ] [junit4] 2> 3247540 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8= .2.0 [junit4] 2> 3247554 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=3Dinplac= e-updates [junit4] 2> 3247557 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.s.IndexSchema Loaded schema inplace-updat= es/1.6 with uniqueid field id [junit4] 2> 3248047 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelis= t initialized: WhitelistHostChecker [whitelistHosts=3Dnull, whitelistHostCh= eckingEnabled=3Dfalse] [junit4] 2> 3248048 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentifica= tionAlgorithm configured for SslContextFactory@4a8e51cf[provider=3Dnull,key= Store=3Dnull,trustStore=3Dnull] [junit4] 2> 3248052 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentifica= tionAlgorithm configured for SslContextFactory@7b055af8[provider=3Dnull,key= Store=3Dnull,trustStore=3Dnull] [junit4] 2> 3248103 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocatin= g transient cache for 2147483647 transient cores [junit4] 2> 3248103 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system coll= ection, keeping metrics history in memory. [junit4] 2> 3248124 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 's= olr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver= .JmxMBeanServer@1341b363 [junit4] 2> 3248137 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 's= olr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.J= mxMBeanServer@1341b363 [junit4] 2> 3248137 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[1F004= AB1981A3781]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 's= olr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserv= er.JmxMBeanServer@1341b363 [junit4] 2> 3248143 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, fr= om paths: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/te= st-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-8.x-Mac= OSX/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 3248182 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0 [junit4] 2> 3248201 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.s.IndexSchema [collection1] Schema name=3Dinplace-updates [junit4] 2> 3248204 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with unique= id field id [junit4] 2> 3248204 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using confi= guration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/s= olr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 3248205 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collectio= n1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbean= server.JmxMBeanServer@1341b363 [junit4] 2> 3248205 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users= /jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/col= lection1], dataDir=3D[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/= build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1= 981A3781-001/init-core-data-001/] [junit4] 2> 3248265 WARN (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {typ= e =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler,attributes= =3D {initParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {d= efaults=3D{a=3DA,b=3DB}}} [junit4] 2> 3248335 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.= solr.update.CdcrUpdateLog [junit4] 2> 3248335 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncL= evel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 3248336 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3248336 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3248338 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4816a2e[collection1] = main] [junit4] 2> 3248339 WARN (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /= Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/sol= r/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 3248339 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 3248339 INFO (coreLoadExecutor-18814-thread-1) [ x:col= lection1] o.a.s.u.UpdateLog Could not find max version in index or recent u= pdates, using new clock 1632375906764849152 [junit4] 2> 3248341 INFO (searcherExecutor-18815-thread-1-processing-= x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registere= d new searcher Searcher@4816a2e[collection1] main{ExitableDirectoryReader(U= ninvertingDirectoryReader())} [junit4] 2> 3248349 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3248349 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkT= estServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3248349 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkT= estServer Starting server [junit4] 2> 3248454 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer start zk server on port:55604 [junit4] 2> 3248454 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127= .0.0.1:55604 [junit4] 2> 3248454 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55604 [junit4] 2> 3248487 INFO (zkConnectionManagerCallback-25269-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3248492 INFO (zkConnectionManagerCallback-25271-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3248500 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrc= onfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3248502 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schem= a-inplace-updates.xml to /configs/conf1/schema.xml [junit4] 2> 3248504 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrc= onfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.ra= ndomindexconfig.xml [junit4] 2> 3248506 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopw= ords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3248512 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protw= ords.txt to /configs/conf1/protwords.txt [junit4] 2> 3248514 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/curre= ncy.xml to /configs/conf1/currency.xml [junit4] 2> 3248516 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enums= Config.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3248524 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-= exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3248526 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mappi= ng-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3248528 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_s= ynonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3248529 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/= Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synon= yms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3248531 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT= replicas unless explicitly asked otherwise [junit4] 2> 3249076 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated sock= et close linger time [junit4] 2> 3249076 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original = configured port=3D0) [junit4] 2> 3249076 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty = on port 0 try number 1 ... [junit4] 2> 3249076 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018= -11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 9+= 181 [junit4] 2> 3249078 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.session DefaultSessionIdManager workerName= =3Dnode0 [junit4] 2> 3249078 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.session No SessionScavenger set, using def= aults [junit4] 2> 3249078 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 3249080 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletCo= ntextHandler@4a6745c4{/_,null,AVAILABLE} [junit4] 2> 3249082 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@= 9dcada2{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:55610} [junit4] 2> 3249083 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.s.Server Started @3249160ms [junit4] 2> 3249083 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hos= tContext=3D/_, solr.data.dir=3D/Users/jenkins/workspace/Lucene-Solr-8.x-Mac= OSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib= _1F004AB1981A3781-001/tempDir-001/control/data, hostPort=3D55610, coreRootD= irectory=3D/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-= core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr= -8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdate= sDistrib_1F004AB1981A3781-001/control-001/cores} [junit4] 2> 3249084 ERROR (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option so= lr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3249084 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory or= g.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 3249084 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welco= me to Apache Solr? version 8.2.0 [junit4] 2> 3249084 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Start= ing in cloud mode on port null [junit4] 2> 3249084 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Insta= ll dir: null [junit4] 2> 3249084 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start= time: 2019-05-01T23:56:17.747523Z [junit4] 2> 3249087 INFO (zkConnectionManagerCallback-25273-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3249089 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from S= olrHome (not found in ZooKeeper) [junit4] 2> 3249089 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.SolrXmlConfig Loading container configurat= ion from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-co= re/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/= control-001/solr.xml [junit4] 2> 3249094 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter auto= ReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 3249094 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter auto= ReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 3249097 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.= jmx.mbeanserver.JmxMBeanServer@1341b363, but no JMX reporters were configur= ed - adding default JMX reporter. [junit4] 2> 3249167 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist i= nitialized: WhitelistHostChecker [whitelistHosts=3Dnull, whitelistHostCheck= ingEnabled=3Dfalse] [junit4] 2> 3249168 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificatio= nAlgorithm configured for SslContextFactory@3614d124[provider=3Dnull,keySto= re=3Dnull,trustStore=3Dnull] [junit4] 2> 3249171 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificatio= nAlgorithm configured for SslContextFactory@75256169[provider=3Dnull,keySto= re=3Dnull,trustStore=3Dnull] [junit4] 2> 3249173 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.0.0.1:5= 5604/solr [junit4] 2> 3249176 INFO (zkConnectionManagerCallback-25280-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3249180 INFO (zkConnectionManagerCallback-25282-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3249433 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.OverseerElectionContext I am going to be t= he leader 127.0.0.1:55610__ [junit4] 2> 3249434 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.Overseer Overseer (id=3D72066794904223748-= 127.0.0.1:55610__-n_0000000000) starting [junit4] 2> 3249448 INFO (zkConnectionManagerCallback-25289-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3249452 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster a= t 127.0.0.1:55604/solr ready [junit4] 2> 3249455 INFO (OverseerStateUpdate-72066794904223748-127.0= .0.1:55610__-n_0000000000) [n:127.0.0.1:55610__ ] o.a.s.c.Overseer Start= ing to work on the main queue : 127.0.0.1:55610__ [junit4] 2> 3249456 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ZkController Register node as live in ZooK= eeper:/live_nodes/127.0.0.1:55610__ [junit4] 2> 3249458 INFO (OverseerStateUpdate-72066794904223748-127.0= .0.1:55610__-n_0000000000) [n:127.0.0.1:55610__ ] o.a.s.c.c.ZkStateReade= r Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3249460 INFO (zkCallback-25288-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3249509 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collect= ion, keeping metrics history in memory. [junit4] 2> 3249536 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@1341b363 [junit4] 2> 3249553 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@1341b363 [junit4] 2> 3249553 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@1341b363 [junit4] 2> 3249554 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definit= ions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/= solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene= -Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceU= pdatesDistrib_1F004AB1981A3781-001/control-001/cores [junit4] 2> 3249599 INFO (zkConnectionManagerCallback-25295-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3249600 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Zo= oKeeper... (0) -> (1) [junit4] 2> 3249602 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster a= t 127.0.0.1:55604/solr ready [junit4] 2> 3249607 INFO (qtp1287886252-58759) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams collection.configName=3Dconf1&name=3Dcontrol_collection&nrtReplicas=3D1= &action=3DCREATE&numShards=3D1&createNodeSet=3D127.0.0.1:55610__&wt=3Djavab= in&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 3249611 INFO (OverseerThreadFactory-18829-thread-1) [ = ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection [junit4] 2> 3249724 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ = x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core= create command qt=3D/admin/cores&collection.configName=3Dconf1&newCollecti= on=3Dtrue&name=3Dcontrol_collection_shard1_replica_n1&action=3DCREATE&numSh= ards=3D1&collection=3Dcontrol_collection&shard=3Dshard1&wt=3Djavabin&versio= n=3D2&replicaType=3DNRT [junit4] 2> 3249725 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ = x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDef= ault Allocating transient cache for 4 transient cores [junit4] 2> 3250740 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.SolrConfig Using Lucene MatchVersion: 8.2.0 [junit4] 2> 3250752 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .s.IndexSchema [control_collection_shard1_replica_n1] Schema name=3Dinplace= -updates [junit4] 2> 3250754 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id [junit4] 2> 3250754 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' u= sing configuration from collection control_collection, trusted=3Dtrue [junit4] 2> 3250755 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard= 1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') e= nabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3250755 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore = at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/te= st/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/contr= ol-001/cores/control_collection_shard1_replica_n1], dataDir=3D[/Users/jenki= ns/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../.= ./../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/= solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A37= 81-001/control-001/cores/control_collection_shard1_replica_n1/data/] [junit4] 2> 3250759 WARN (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestHa= ndler,name =3D /dump,class =3D DumpRequestHandler,attributes =3D {initParam= s=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3D= A,b=3DB}}} [junit4] 2> 3250806 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Cdc= rUpdateLog [junit4] 2> 3250806 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH nu= mRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 3251411 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3251411 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3251413 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .s.SolrIndexSearcher Opening [Searcher@54ce6972[control_collection_shard1_r= eplica_n1] main] [junit4] 2> 3251414 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /co= nfigs/conf1 [junit4] 2> 3251415 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooK= eeperStorageIO:path=3D/configs/conf1 [junit4] 2> 3251415 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 3251415 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .u.UpdateLog Could not find max version in index or recent updates, using n= ew clock 1632375909990268928 [junit4] 2> 3251419 INFO (searcherExecutor-18834-thread-1-processing-= n:127.0.0.1:55610__ x:control_collection_shard1_replica_n1 c:control_collec= tion s:shard1) [n:127.0.0.1:55610__ c:control_collection s:shard1 x:contro= l_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1= _replica_n1] Registered new searcher Searcher@54ce6972[control_collection_s= hard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader()= )} [junit4] 2> 3251424 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ZkShardTerms Successful update of terms at /collections/control_collecti= on/terms/shard1 to Terms{values=3D{core_node2=3D0}, version=3D0} [junit4] 2> 3251424 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ShardLeaderElectionContextBase make sure parent is created /collections/= control_collection/leaders/shard1 [junit4] 2> 3251430 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3251430 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 3251430 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.SyncStrategy Sync replicas to http://127.0.0.1:55610/_/control_collectio= n_shard1_replica_n1/ [junit4] 2> 3251431 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3251431 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.SyncStrategy http://127.0.0.1:55610/_/control_collection_shard1_replica_= n1/ has no replicas [junit4] 2> 3251431 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ShardLeaderElectionContextBase Creating leader registration node /collec= tions/control_collection/leaders/shard1/leader after winning as /collection= s/control_collection/leader_elect/shard1/election/72066794904223748-core_no= de2-n_0000000000 [junit4] 2> 3251434 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55610/_= /control_collection_shard1_replica_n1/ shard1 [junit4] 2> 3251536 INFO (zkCallback-25281-thread-1) [ ] o.a.s.c.c= .ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected ty= pe:NodeDataChanged path:/collections/control_collection/state.json] for col= lection [control_collection] has occurred - updating... (live nodes size: [= 1]) [junit4] 2> 3251536 INFO (zkCallback-25281-thread-2) [ ] o.a.s.c.c= .ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected ty= pe:NodeDataChanged path:/collections/control_collection/state.json] for col= lection [control_collection] has occurred - updating... (live nodes size: [= 1]) [junit4] 2> 3251538 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .c.ZkController I am the leader, no recovery necessary [junit4] 2> 3251541 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s= .s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/a= dmin/cores&collection.configName=3Dconf1&newCollection=3Dtrue&name=3Dcontro= l_collection_shard1_replica_n1&action=3DCREATE&numShards=3D1&collection=3Dc= ontrol_collection&shard=3Dshard1&wt=3Djavabin&version=3D2&replicaType=3DNRT= } status=3D0 QTime=3D1816 [junit4] 2> 3251544 INFO (qtp1287886252-58759) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for a= t most 45 seconds. Check all shard replicas [junit4] 2> 3251621 INFO (OverseerCollectionConfigSetProcessor-720667= 94904223748-127.0.0.1:55610__-n_0000000000) [ ] o.a.s.c.OverseerTaskQueu= e Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't = exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 3251641 INFO (zkCallback-25281-thread-2) [ ] o.a.s.c.c= .ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected ty= pe:NodeDataChanged path:/collections/control_collection/state.json] for col= lection [control_collection] has occurred - updating... (live nodes size: [= 1]) [junit4] 2> 3251641 INFO (zkCallback-25281-thread-3) [ ] o.a.s.c.c= .ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected ty= pe:NodeDataChanged path:/collections/control_collection/state.json] for col= lection [control_collection] has occurred - updating... (live nodes size: [= 1]) [junit4] 2> 3251641 INFO (zkCallback-25281-thread-1) [ ] o.a.s.c.c= .ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected ty= pe:NodeDataChanged path:/collections/control_collection/state.json] for col= lection [control_collection] has occurred - updating... (live nodes size: [= 1]) [junit4] 2> 3251643 INFO (qtp1287886252-58759) [n:127.0.0.1:55610__ = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{collection.configName=3Dconf1&name=3Dcontrol_collection&nrtReplicas= =3D1&action=3DCREATE&numShards=3D1&createNodeSet=3D127.0.0.1:55610__&wt=3Dj= avabin&version=3D2} status=3D0 QTime=3D2036 [junit4] 2> 3251748 INFO (zkConnectionManagerCallback-25301-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3251750 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Zo= oKeeper... (0) -> (1) [junit4] 2> 3251758 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster a= t 127.0.0.1:55604/solr ready [junit4] 2> 3251758 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions= :false cause connection loss:false [junit4] 2> 3251760 INFO (qtp1287886252-58760) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams collection.configName=3Dconf1&name=3Dcollection1&nrtReplicas=3D1&action= =3DCREATE&numShards=3D1&createNodeSet=3D&stateFormat=3D1&wt=3Djavabin&versi= on=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 3251768 INFO (OverseerThreadFactory-18829-thread-2) [ = ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 3251983 WARN (OverseerThreadFactory-18829-thread-2) [ = ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (col= lection1) without cores. [junit4] 2> 3251985 INFO (qtp1287886252-58760) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for a= t most 45 seconds. Check all shard replicas [junit4] 2> 3251986 INFO (qtp1287886252-58760) [n:127.0.0.1:55610__ = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{collection.configName=3Dconf1&name=3Dcollection1&nrtReplicas=3D1&ac= tion=3DCREATE&numShards=3D1&createNodeSet=3D&stateFormat=3D1&wt=3Djavabin&v= ersion=3D2} status=3D0 QTime=3D226 [junit4] 2> 3251988 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jet= ty instances pullReplicaCount=3D0 numOtherReplicas=3D3 [junit4] 2> 3252393 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty= 1 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/= solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A37= 81-001/shard-1-001 of type NRT [junit4] 2> 3252393 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 3252394 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Start Jetty (original configured port=3D0) [junit4] 2> 3252394 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 3252394 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4= 550056e785fb5665914545889f21dc136ad9e6; jvm 9+181 [junit4] 2> 3252395 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 3252395 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session No SessionScavenger set, using defaults [junit4] 2> 3252395 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session node0 Scavenging every 660000ms [junit4] 2> 3252395 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@37693a8{/_,null,= AVAILABLE} [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.AbstractConnector Started ServerConnector@7122f545{HTTP/1.1,[http/1.1, = h2c]}{127.0.0.1:55625} [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.Server Started @3252473ms [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/_, solrconfig=3Ds= olrconfig.xml, solr.data.dir=3D/Users/jenkins/workspace/Lucene-Solr-8.x-Mac= OSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib= _1F004AB1981A3781-001/tempDir-001/jetty1, hostPort=3D55625, coreRootDirecto= ry=3D/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/t= est/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-M= acOSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistr= ib_1F004AB1981A3781-001/shard-1-001/cores} [junit4] 2> 3252396 ERROR (closeThreadPool-25302-thread-1) [ ] o.a.= s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be mi= ssing or incomplete. [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jL= oggerFactory [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.= 2.0 [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port nul= l [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3252396 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-01T23:56:21.05= 9861Z [junit4] 2> 3252400 INFO (zkConnectionManagerCallback-25304-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3252401 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeep= er) [junit4] 2> 3252401 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/works= pace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.T= estInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-1-001/solr.xml [junit4] 2> 3252405 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay = is ignored [junit4] 2> 3252405 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpirat= ion is ignored [junit4] 2> 3252406 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServe= r@1341b363, but no JMX reporters were configured - adding default JMX repor= ter. [junit4] 2> 3252527 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChec= ker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Dfalse] [junit4] 2> 3252528 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Ssl= ContextFactory@6143b362[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 3252532 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Ssl= ContextFactory@3da01c51[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 3252533 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkContainer Zookeeper client=3D127.0.0.1:55604/solr [junit4] 2> 3252536 INFO (zkConnectionManagerCallback-25311-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3252541 INFO (zkConnectionManagerCallback-25313-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3252551 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper...= (0) -> (1) [junit4] 2> 3252559 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.ZkController Publish node=3D127.0.0.1:55625__ as DOW= N [junit4] 2> 3252560 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient c= ache for 4 transient cores [junit4] 2> 3252560 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/liv= e_nodes/127.0.0.1:55625__ [junit4] 2> 3252562 INFO (zkCallback-25281-thread-3) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3252563 INFO (zkCallback-25300-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3252563 INFO (zkCallback-25288-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3252563 INFO (zkCallback-25312-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3252590 INFO (zkConnectionManagerCallback-25320-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3252592 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper...= (0) -> (2) [junit4] 2> 3252594 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.= 1:55604/solr ready [junit4] 2> 3252595 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keepi= ng metrics history in memory. [junit4] 2> 3252617 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (re= gistry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServ= er@1341b363 [junit4] 2> 3252631 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (reg= istry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer= @1341b363 [junit4] 2> 3252632 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (r= egistry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanSe= rver@1341b363 [junit4] 2> 3252634 INFO (closeThreadPool-25302-thread-1) [n:127.0.0.= 1:55625__ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions under= neath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/= test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-= MacOSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDist= rib_1F004AB1981A3781-001/shard-1-001/cores [junit4] 2> 3252907 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty= 2 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/= solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A37= 81-001/shard-2-001 of type NRT [junit4] 2> 3252907 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 3252907 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Start Jetty (original configured port=3D0) [junit4] 2> 3252907 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 3252907 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4= 550056e785fb5665914545889f21dc136ad9e6; jvm 9+181 [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session No SessionScavenger set, using defaults [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session node0 Scavenging every 660000ms [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@159d592a{/_,null= ,AVAILABLE} [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.AbstractConnector Started ServerConnector@3e10e1ee{HTTP/1.1,[http/1.1, = h2c]}{127.0.0.1:55630} [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.Server Started @3252987ms [junit4] 2> 3252909 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/_, solrconfig=3Ds= olrconfig.xml, solr.data.dir=3D/Users/jenkins/workspace/Lucene-Solr-8.x-Mac= OSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib= _1F004AB1981A3781-001/tempDir-001/jetty2, hostPort=3D55630, coreRootDirecto= ry=3D/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/t= est/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shar= d-2-001/cores, replicaType=3DNRT} [junit4] 2> 3252910 ERROR (closeThreadPool-25302-thread-1) [ ] o.a.= s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be mi= ssing or incomplete. [junit4] 2> 3252910 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jL= oggerFactory [junit4] 2> 3252910 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.= 2.0 [junit4] 2> 3252910 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port nul= l [junit4] 2> 3252910 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3252910 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-01T23:56:21.57= 3401Z [junit4] 2> 3252913 INFO (zkConnectionManagerCallback-25323-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3252915 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeep= er) [junit4] 2> 3252915 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/works= pace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.T= estInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-2-001/solr.xml [junit4] 2> 3252918 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay = is ignored [junit4] 2> 3252918 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpirat= ion is ignored [junit4] 2> 3252919 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServe= r@1341b363, but no JMX reporters were configured - adding default JMX repor= ter. [junit4] 2> 3253086 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChec= ker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Dfalse] [junit4] 2> 3253088 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Ssl= ContextFactory@57fe01f9[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 3253091 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Ssl= ContextFactory@4f48643d[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 3253092 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkContainer Zookeeper client=3D127.0.0.1:55604/solr [junit4] 2> 3253095 INFO (zkConnectionManagerCallback-25330-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253100 INFO (zkConnectionManagerCallback-25332-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253112 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3253120 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkController Publish node=3D127.0.0.1:55630__ as DOWN [junit4] 2> 3253122 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transien= t cores [junit4] 2> 3253122 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:5= 5630__ [junit4] 2> 3253125 INFO (zkCallback-25281-thread-3) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3253125 INFO (zkCallback-25300-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3253125 INFO (zkCallback-25288-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3253125 INFO (zkCallback-25331-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3253125 INFO (zkCallback-25312-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3253125 INFO (zkCallback-25319-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3253158 INFO (zkConnectionManagerCallback-25339-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253160 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 3253163 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55604/solr ready [junit4] 2> 3253163 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history = in memory. [junit4] 2> 3253190 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')= enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3253209 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') e= nabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3253209 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty= ') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3253211 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkin= s/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.u= pdate.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-2-001/cores [junit4] 2> 3253621 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[1= F004AB1981A3781]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty= 3 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/= solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A37= 81-001/shard-3-001 of type NRT [junit4] 2> 3253621 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 3253621 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Start Jetty (original configured port=3D0) [junit4] 2> 3253621 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 3253621 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4= 550056e785fb5665914545889f21dc136ad9e6; jvm 9+181 [junit4] 2> 3253624 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 3253624 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session No SessionScavenger set, using defaults [junit4] 2> 3253624 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.session node0 Scavenging every 600000ms [junit4] 2> 3253624 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cb82de7{/_,null,= AVAILABLE} [junit4] 2> 3253624 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.AbstractConnector Started ServerConnector@30ca9d41{HTTP/1.1,[http/1.1, = h2c]}{127.0.0.1:55635} [junit4] 2> 3253624 INFO (closeThreadPool-25302-thread-1) [ ] o.e.= j.s.Server Started @3253702ms [junit4] 2> 3253625 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/_, solrconfig=3Ds= olrconfig.xml, solr.data.dir=3D/Users/jenkins/workspace/Lucene-Solr-8.x-Mac= OSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDistrib= _1F004AB1981A3781-001/tempDir-001/jetty3, hostPort=3D55635, coreRootDirecto= ry=3D/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/t= est/J0/temp/solr.update.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shar= d-3-001/cores} [junit4] 2> 3253625 ERROR (closeThreadPool-25302-thread-1) [ ] o.a.= s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be mi= ssing or incomplete. [junit4] 2> 3253625 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jL= oggerFactory [junit4] 2> 3253625 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.= 2.0 [junit4] 2> 3253625 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port nul= l [junit4] 2> 3253625 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3253625 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-01T23:56:22.28= 8475Z [junit4] 2> 3253628 INFO (zkConnectionManagerCallback-25342-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253630 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeep= er) [junit4] 2> 3253630 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/works= pace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.T= estInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-3-001/solr.xml [junit4] 2> 3253641 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay = is ignored [junit4] 2> 3253641 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpirat= ion is ignored [junit4] 2> 3253642 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServe= r@1341b363, but no JMX reporters were configured - adding default JMX repor= ter. [junit4] 2> 3253771 INFO (OverseerCollectionConfigSetProcessor-720667= 94904223748-127.0.0.1:55610__-n_0000000000) [ ] o.a.s.c.OverseerTaskQueu= e Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't = exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 3253775 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChec= ker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Dfalse] [junit4] 2> 3253776 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Ssl= ContextFactory@4d29debe[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 3253779 WARN (closeThreadPool-25302-thread-1) [ ] o.e.= j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Ssl= ContextFactory@53dae758[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 3253780 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkContainer Zookeeper client=3D127.0.0.1:55604/solr [junit4] 2> 3253784 INFO (zkConnectionManagerCallback-25349-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253789 INFO (zkConnectionManagerCallback-25351-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253802 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 3253811 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkController Publish node=3D127.0.0.1:55635__ as DOWN [junit4] 2> 3253813 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transien= t cores [junit4] 2> 3253813 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:5= 5635__ [junit4] 2> 3253816 INFO (zkCallback-25331-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253816 INFO (zkCallback-25312-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253816 INFO (zkCallback-25288-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253816 INFO (zkCallback-25300-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253816 INFO (zkCallback-25281-thread-3) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253816 INFO (zkCallback-25350-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253816 INFO (zkCallback-25319-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253817 INFO (zkCallback-25338-thread-1) [ ] o.a.s.c.c= .ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 3253846 INFO (zkConnectionManagerCallback-25358-thread-1)= [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 3253848 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 3253852 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55604/solr ready [junit4] 2> 3253853 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history = in memory. [junit4] 2> 3253889 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')= enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3253903 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') e= nabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3253904 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty= ') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3253909 INFO (closeThreadPool-25302-thread-1) [ ] o.a.= s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkin= s/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.u= pdate.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-3-001/cores [junit4] 2> 3253985 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with= params node=3D127.0.0.1:55625__&action=3DADDREPLICA&collection=3Dcollectio= n1&shard=3Dshard1&type=3DNRT&wt=3Djavabin&version=3D2 and sendToOCPQueue=3D= true [junit4] 2> 3253986 INFO (qtp1287886252-58758) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with= params node=3D127.0.0.1:55635__&action=3DADDREPLICA&collection=3Dcollectio= n1&shard=3Dshard1&type=3DNRT&wt=3Djavabin&version=3D2 and sendToOCPQueue=3D= true [junit4] 2> 3253987 INFO (qtp1287886252-58763) [n:127.0.0.1:55610__ = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with= params node=3D127.0.0.1:55630__&action=3DADDREPLICA&collection=3Dcollectio= n1&shard=3Dshard1&type=3DNRT&wt=3Djavabin&version=3D2 and sendToOCPQueue=3D= true [junit4] 2> 3253991 INFO (OverseerThreadFactory-18829-thread-3) [ c:c= ollection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:= 55625__ for creating new replica of shard shard1 for collection collection1 [junit4] 2> 3253994 INFO (OverseerThreadFactory-18829-thread-3) [ c:c= ollection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica co= mmand. [junit4] 2> 3253998 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ = x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create= command qt=3D/admin/cores&collection.configName=3Dconf1&name=3Dcollection1= _shard1_replica_n1&action=3DCREATE&collection=3Dcollection1&shard=3Dshard1&= wt=3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 3255016 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig = Using Lucene MatchVersion: 8.2.0 [junit4] 2> 3255056 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema= [collection1_shard1_replica_n1] Schema name=3Dinplace-updates [junit4] 2> 3255060 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema= Loaded schema inplace-updates/1.6 with uniqueid field id [junit4] 2> 3255060 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.CoreContain= er Creating SolrCore 'collection1_shard1_replica_n1' using configuration fr= om collection collection1, trusted=3Dtrue [junit4] 2> 3255060 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxRe= porter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (regist= ry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jm= x.mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3255060 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[= collection1_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/wo= rkspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.updat= e.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-1-001/cores/collecti= on1_shard1_replica_n1], dataDir=3D[/Users/jenkins/workspace/Lucene-Solr-8.x= -MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenki= ns/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.= update.TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-1-001/cores/col= lection1_shard1_replica_n1/data/] [junit4] 2> 3255066 WARN (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.RequestHand= lers INVALID paramSet a in requestHandler {type =3D requestHandler,name =3D= /dump,class =3D DumpRequestHandler,attributes =3D {initParams=3Da, name=3D= /dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3DA,b=3DB}}} [junit4] 2> 3255125 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandl= er Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 3255125 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog I= nitializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH numRecordsToKeep= =3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 3255128 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.CommitTrack= er Hard AutoCommit: disabled [junit4] 2> 3255128 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.CommitTrack= er Soft AutoCommit: disabled [junit4] 2> 3255129 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSe= archer Opening [Searcher@126d63e3[collection1_shard1_replica_n1] main] [junit4] 2> 3255131 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.r.ManagedReso= urceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3255132 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.r.ManagedReso= urceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO= :path=3D/configs/conf1 [junit4] 2> 3255132 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.h.Replication= Handler Commits will be reserved for 10000ms. [junit4] 2> 3255132 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog C= ould not find max version in index or recent updates, using new clock 16323= 75913887825920 [junit4] 2> 3255135 INFO (searcherExecutor-18866-thread-1-processing-= n:127.0.0.1:55625__ x:collection1_shard1_replica_n1 c:collection1 s:shard1)= [n:127.0.0.1:55625__ c:collection1 s:shard1 x:collection1_shard1_replica_= n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searche= r Searcher@126d63e3[collection1_shard1_replica_n1] main{ExitableDirectoryRe= ader(UninvertingDirectoryReader())} [junit4] 2> 3255140 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerm= s Successful update of terms at /collections/collection1/terms/shard1 to Te= rms{values=3D{core_node2=3D0}, version=3D0} [junit4] 2> 3255140 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeader= ElectionContextBase make sure parent is created /collections/collection1/le= aders/shard1 [junit4] 2> 3255145 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeader= ElectionContext Enough replicas found to continue. [junit4] 2> 3255145 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeader= ElectionContext I may be the new leader - try and sync [junit4] 2> 3255146 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrateg= y Sync replicas to http://127.0.0.1:55625/_/collection1_shard1_replica_n1/ [junit4] 2> 3255146 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrateg= y Sync Success - now sync replicas to me [junit4] 2> 3255146 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrateg= y http://127.0.0.1:55625/_/collection1_shard1_replica_n1/ has no replicas [junit4] 2> 3255146 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeader= ElectionContextBase Creating leader registration node /collections/collecti= on1/leaders/shard1/leader after winning as /collections/collection1/leader_= elect/shard1/election/72066794904223754-core_node2-n_0000000000 [junit4] 2> 3255148 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeader= ElectionContext I am the new leader: http://127.0.0.1:55625/_/collection1_s= hard1_replica_n1/ shard1 [junit4] 2> 3255255 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.c.ZkControlle= r I am the leader, no recovery necessary [junit4] 2> 3255264 INFO (qtp1994141972-58821) [n:127.0.0.1:55625__ c= :collection1 s:shard1 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCal= l [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&col= lection.configName=3Dconf1&name=3Dcollection1_shard1_replica_n1&action=3DCR= EATE&collection=3Dcollection1&shard=3Dshard1&wt=3Djavabin&version=3D2&repli= caType=3DNRT} status=3D0 QTime=3D1265 [junit4] 2> 3255267 INFO (qtp1287886252-58761) [n:127.0.0.1:55610__ c= :collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/c= ollections params=3D{node=3D127.0.0.1:55625__&action=3DADDREPLICA&collectio= n=3Dcollection1&shard=3Dshard1&type=3DNRT&wt=3Djavabin&version=3D2} status= =3D0 QTime=3D1281 [junit4] 2> 3256008 INFO (OverseerThreadFactory-18829-thread-4) [ c:c= ollection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:= 55635__ for creating new replica of shard shard1 for collection collection1 [junit4] 2> 3256009 INFO (OverseerCollectionConfigSetProcessor-720667= 94904223748-127.0.0.1:55610__-n_0000000000) [ ] o.a.s.c.OverseerTaskQueu= e Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't = exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 3256011 INFO (OverseerThreadFactory-18829-thread-4) [ c:c= ollection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica co= mmand. [junit4] 2> 3256017 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ = x:collection1_shard1_replica_n3] o.a.s.h.a.CoreAdminOperation core create = command qt=3D/admin/cores&collection.configName=3Dconf1&name=3Dcollection1_= shard1_replica_n3&action=3DCREATE&collection=3Dcollection1&shard=3Dshard1&w= t=3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 3257051 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.SolrConfig U= sing Lucene MatchVersion: 8.2.0 [junit4] 2> 3257068 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.IndexSchema = [collection1_shard1_replica_n3] Schema name=3Dinplace-updates [junit4] 2> 3257115 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.IndexSchema = Loaded schema inplace-updates/1.6 with uniqueid field id [junit4] 2> 3257115 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.CoreContaine= r Creating SolrCore 'collection1_shard1_replica_n3' using configuration fro= m collection collection1, trusted=3Dtrue [junit4] 2> 3257115 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.m.r.SolrJmxRep= orter JMX monitoring for 'solr.core.collection1.shard1.replica_n3' (registr= y 'solr.core.collection1.shard1.replica_n3') enabled at server: com.sun.jmx= .mbeanserver.JmxMBeanServer@1341b363 [junit4] 2> 3257116 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.SolrCore [[c= ollection1_shard1_replica_n3] ] Opening new SolrCore at [/Users/jenkins/wor= kspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update= .TestInPlaceUpdatesDistrib_1F004AB1981A3781-001/shard-3-001/cores/collectio= n1_shard1_replica_n3], dataDir=3D[/Users/jenkins/workspace/Lucene-Solr-8.x-= MacOSX/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdatesDist= rib_1F004AB1981A3781-001/shard-3-001/cores/collection1_shard1_replica_n3/da= ta/] [junit4] 2> 3257121 WARN (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.RequestHandl= ers INVALID paramSet a in requestHandler {type =3D requestHandler,name =3D = /dump,class =3D DumpRequestHandler,attributes =3D {initParams=3Da, name=3D/= dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3DA,b=3DB}}} [junit4] 2> 3257188 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.UpdateHandle= r Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 3257188 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog In= itializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH numRecordsToKeep= =3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 3257190 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.CommitTracke= r Hard AutoCommit: disabled [junit4] 2> 3257190 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.CommitTracke= r Soft AutoCommit: disabled [junit4] 2> 3257192 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.SolrIndexSea= rcher Opening [Searcher@6a261848[collection1_shard1_replica_n3] main] [junit4] 2> 3257193 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.r.ManagedResou= rceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3257194 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.r.ManagedResou= rceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:= path=3D/configs/conf1 [junit4] 2> 3257195 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.h.ReplicationH= andler Commits will be reserved for 10000ms. [junit4] 2> 3257195 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog Co= uld not find max version in index or recent updates, using new clock 163237= 5916051038208 [junit4] 2> 3257198 INFO (searcherExecutor-18871-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1)= [n:127.0.0.1:55635__ c:collection1 s:shard1 x:collection1_shard1_replica_= n3] o.a.s.c.SolrCore [collection1_shard1_replica_n3] Registered new searche= r Searcher@6a261848[collection1_shard1_replica_n3] main{ExitableDirectoryRe= ader(UninvertingDirectoryReader())} [junit4] 2> 3257208 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.ZkShardTerms= Successful update of terms at /collections/collection1/terms/shard1 to Ter= ms{values=3D{core_node2=3D0, core_node4=3D0}, version=3D1} [junit4] 2> 3257208 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.ShardLeaderE= lectionContextBase make sure parent is created /collections/collection1/lea= ders/shard1 [junit4] 2> 3257213 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.ZkController= Core needs to recover:collection1_shard1_replica_n3 [junit4] 2> 3257213 INFO (updateExecutor-25345-thread-1-processing-n:= 127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1) [= n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:collection1_shard= 1_replica_n3] o.a.s.u.DefaultSolrCoreState Running recovery [junit4] 2> 3257213 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Starting recovery pro= cess. recoveringAfterStartup=3Dtrue [junit4] 2> 3257214 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy startupVersions is em= pty [junit4] 2> 3257216 INFO (qtp1994141972-58823) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c= .S.Request [collection1_shard1_replica_n1] webapp=3D/_ path=3D/admin/ping = params=3D{wt=3Djavabin&version=3D2} hits=3D0 status=3D0 QTime=3D0 [junit4] 2> 3257216 INFO (qtp1994141972-58823) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c= .S.Request [collection1_shard1_replica_n1] webapp=3D/_ path=3D/admin/ping = params=3D{wt=3Djavabin&version=3D2} status=3D0 QTime=3D0 [junit4] 2> 3257216 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Begin buffering updat= es. core=3D[collection1_shard1_replica_n3] [junit4] 2> 3257217 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.u.UpdateLog Starting to buffer updates. = FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4] 2> 3257217 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Publishing state of c= ore [collection1_shard1_replica_n3] as recovering, leader is [http://127.0.= 0.1:55625/_/collection1_shard1_replica_n1/] and I am [http://127.0.0.1:5563= 5/_/collection1_shard1_replica_n3/] [junit4] 2> 3257217 INFO (qtp985023011-58886) [n:127.0.0.1:55635__ c:= collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.HttpSolrCall= [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&coll= ection.configName=3Dconf1&name=3Dcollection1_shard1_replica_n3&action=3DCRE= ATE&collection=3Dcollection1&shard=3Dshard1&wt=3Djavabin&version=3D2&replic= aType=3DNRT} status=3D0 QTime=3D1199 [junit4] 2> 3257218 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Sending prep recovery= command to [http://127.0.0.1:55625/_]; [WaitForState: action=3DPREPRECOVER= Y&core=3Dcollection1_shard1_replica_n1&nodeName=3D127.0.0.1:55635__&coreNod= eName=3Dcore_node4&state=3Drecovering&checkLive=3Dtrue&onlyIfLeader=3Dtrue&= onlyIfLeaderActive=3Dtrue] [junit4] 2> 3257219 INFO (qtp1994141972-58824) [n:127.0.0.1:55625__ = x:collection1_shard1_replica_n1] o.a.s.h.a.PrepRecoveryOp Going to wait f= or coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLead= er: true, onlyIfLeaderActive: true [junit4] 2> 3257219 INFO (qtp1994141972-58824) [n:127.0.0.1:55625__ = x:collection1_shard1_replica_n1] o.a.s.h.a.PrepRecoveryOp In WaitForState= (recovering): collection=3Dcollection1, shard=3Dshard1, thisCore=3Dcollecti= on1_shard1_replica_n1, leaderDoesNotNeedRecovery=3Dfalse, isLeader? true, l= ive=3Dtrue, checkLive=3Dtrue, currentState=3Ddown, localState=3Dactive, nod= eName=3D127.0.0.1:55635__, coreNodeName=3Dcore_node4, onlyIfActiveCheckResu= lt=3Dfalse, nodeProps: core_node4:{"core":"collection1_shard1_replica_n3","= base_url":"http://127.0.0.1:55635/_","node_name":"127.0.0.1:55635__","state= ":"down","type":"NRT"} [junit4] 2> 3257220 INFO (qtp1287886252-58758) [n:127.0.0.1:55610__ c= :collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/c= ollections params=3D{node=3D127.0.0.1:55635__&action=3DADDREPLICA&collectio= n=3Dcollection1&shard=3Dshard1&type=3DNRT&wt=3Djavabin&version=3D2} status= =3D0 QTime=3D3234 [junit4] 2> 3257331 INFO (watches-25314-thread-1) [ ] o.a.s.h.a.Pr= epRecoveryOp In WaitForState(recovering): collection=3Dcollection1, shard= =3Dshard1, thisCore=3Dcollection1_shard1_replica_n1, leaderDoesNotNeedRecov= ery=3Dfalse, isLeader? true, live=3Dtrue, checkLive=3Dtrue, currentState=3D= recovering, localState=3Dactive, nodeName=3D127.0.0.1:55635__, coreNodeName= =3Dcore_node4, onlyIfActiveCheckResult=3Dfalse, nodeProps: core_node4:{"cor= e":"collection1_shard1_replica_n3","base_url":"http://127.0.0.1:55635/_","n= ode_name":"127.0.0.1:55635__","state":"recovering","type":"NRT"} [junit4] 2> 3257331 INFO (qtp1994141972-58824) [n:127.0.0.1:55625__ = x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=3Dnu= ll path=3D/admin/cores params=3D{nodeName=3D127.0.0.1:55635__&onlyIfLeaderA= ctive=3Dtrue&core=3Dcollection1_shard1_replica_n1&coreNodeName=3Dcore_node4= &action=3DPREPRECOVERY&checkLive=3Dtrue&state=3Drecovering&onlyIfLeader=3Dt= rue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D112 [junit4] 2> 3257837 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Attempting to PeerSyn= c from [http://127.0.0.1:55625/_/collection1_shard1_replica_n1/] - recoveri= ngAfterStartup=3D[true] [junit4] 2> 3257838 WARN (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.u.PeerSyncWithLeader no frame of referen= ce to tell if we've missed updates [junit4] 2> 3257838 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy PeerSync Recovery was= not successful - trying replication. [junit4] 2> 3257838 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Starting Replication = Recovery. [junit4] 2> 3257838 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.c.RecoveryStrategy Attempting to replica= te from [http://127.0.0.1:55625/_/collection1_shard1_replica_n1/]. [junit4] 2> 3257839 INFO (qtp1994141972-58819) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 start commit{_version_=3D1632375916726321152,optimize= =3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,so= ftCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 3257840 INFO (qtp1994141972-58819) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 3257841 INFO (qtp1994141972-58819) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u= .DirectUpdateHandler2 end_commit_flush [junit4] 2> 3257843 INFO (qtp985023011-58889) [n:127.0.0.1:55635__ c:= collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3] o.a.s.u.= p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFF= ERING replay: false [junit4] 2> 3257843 INFO (qtp985023011-58889) [n:127.0.0.1:55635__ c:= collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3] o.a.s.u.= p.LogUpdateProcessorFactory [collection1_shard1_replica_n3] webapp=3D/_ pa= th=3D/update params=3D{update.distrib=3DFROMLEADER&waitSearcher=3Dtrue&open= Searcher=3Dfalse&commit=3Dtrue&softCommit=3Dfalse&distrib.from=3Dhttp://127= .0.0.1:55625/_/collection1_shard1_replica_n1/&commit_end_point=3Dreplicas&w= t=3Djavabin&version=3D2&expungeDeletes=3Dfalse}{commit=3D} 0 0 [junit4] 2> 3257843 INFO (qtp1994141972-58819) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u= .p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=3D/_ p= ath=3D/update params=3D{waitSearcher=3Dtrue&openSearcher=3Dfalse&commit=3Dt= rue&softCommit=3Dfalse&wt=3Djavabin&version=3D2}{commit=3D} 0 4 [junit4] 2> 3257845 INFO (qtp1994141972-58820) [n:127.0.0.1:55625__ c= :collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c= .S.Request [collection1_shard1_replica_n1] webapp=3D/_ path=3D/replication= params=3D{qt=3D/replication&wt=3Djavabin&version=3D2&command=3Dindexversio= n} status=3D0 QTime=3D0 [junit4] 2> 3257845 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 3257845 INFO (recoveryExecutor-25347-thread-1-processing-= n:127.0.0.1:55635__ x:collection1_shard1_replica_n3 c:collection1 s:shard1 = r:core_node4) [n:127.0.0.1:55635__ c:collection1 s:shard1 r:core_node4 x:co= llection1_shard1_replica_n3] o.a.s.h [...truncated too long message...] ockCacheTest, DeleteStatusTest, QueryEqualityTest, TestSolrCoreProperties, = ReplicationFactorTest, AssignBackwardCompatibilityTest, DistributedFacetPiv= otSmallTest, TestManagedSchemaAPI, TestRandomCollapseQParserPlugin, Analysi= sErrorHandlingTest, TestCustomSort, TestLegacyNumericUtils, LargeFieldTest,= VMParamsZkACLAndCredentialsProvidersTest, DateRangeFieldTest, TestFieldTyp= eCollectionResource, TestSimDistributedQueue, TestSafeXMLParsing, TestInPla= ceUpdatesRequiredField, DocumentAnalysisRequestHandlerTest, TestCoreContain= er, TestAuthorizationFramework, TestReplicationHandlerBackup, TestRuleBased= AuthorizationPlugin, DefaultValueUpdateProcessorTest, TestConfigSetsAPIZkFa= ilure, ZkControllerTest, TestUtilizeNode, TestReqParamsAPI, SimplePostToolT= est, TestPostingsSolrHighlighter, MoveReplicaTest, SuggestComponentContextF= ilterQueryTest, NodeLostTriggerIntegrationTest, RankQueryTest, ResponseHead= erTest, BinaryUpdateRequestHandlerTest, TestJsonFacets, TestSubQueryTransfo= rmerDistrib, TestExpandComponent, TestRemoteStreaming, TestLRUCache, TestOm= itPositions, TestCloudConsistency, TestReload, OrderedExecutorTest, TestTol= erantUpdateProcessorCloud, TestMissingGroups, TestCustomDocTransformer, Bad= ComponentTest, SolrSlf4jReporterTest, TestUnInvertedFieldException, AuditLo= ggerPluginTest, TestReRankQParserPlugin, PingRequestHandlerTest, TestIndexi= ngPerformance, WrapperMergePolicyFactoryTest, ExitableDirectoryReaderTest, = BlockJoinFacetDistribTest, DistributedFacetExistsSmallTest, HdfsChaosMonkey= NothingIsSafeTest, TestFastOutputStream, HdfsNNFailoverTest, CloudExitableD= irectoryReaderTest, TestMergePolicyConfig, TestLegacyField, TestQuerySender= Listener, SaslZkACLProviderTest, ReplaceNodeNoTargetTest, LeaderFailureAfte= rFreshStartTest, TestInitQParser, OverseerTest, ProtectedTermFilterFactoryT= est, TestTrieFacet, TestManagedResourceStorage, RecoveryAfterSoftCommitTest= , PeerSyncWithLeaderTest, SpellCheckCollatorTest, TestSimpleQParserPlugin, = TestEmbeddedSolrServerAdminHandler, NestedAtomicUpdateTest, TestRandomFacet= ing, TestCoreAdminApis, TestFreeTextSuggestions, ShowFileRequestHandlerTest= , HdfsLockFactoryTest, TestInfoStreamLogging, TestSystemCollAutoCreate, Nod= eMutatorTest, TestObjectReleaseTracker, ScheduledMaintenanceTriggerTest, Co= reAdminRequestStatusTest, TestScoreJoinQPNoScore, AlternateDirectoryTest, T= estBlobHandler, LoggingHandlerTest, V2StandaloneTest, RAMDirectoryFactoryTe= st, HdfsTlogReplayBufferedWhileIndexingTest, TestSchemaNameResource, Daemon= StreamApiTest, OutputWriterTest, FullHLLTest, SubstringBytesRefFilterTest, = NodeAddedTriggerIntegrationTest, TestDistributedMap, MetricsHistoryIntegrat= ionTest, ChaosMonkeyNothingIsSafeTest, HttpTriggerListenerTest, TestDistrib= utedSearch, SolrCoreCheckLockOnStartupTest, TestCollectionAPI, DisMaxReques= tHandlerTest, TestMultiValuedNumericRangeQuery, CollectionsAPIDistributedZk= Test, NodeMarkersRegistrationTest, TestUtils, OverseerStatusTest, FileBased= SpellCheckerTest, ZkSolrClientTest, TestExclusionRuleCollectionAccess, Test= ZkChroot, EchoParamsTest, TestFieldCacheReopen, FileUtilsTest, SolrMetricsI= ntegrationTest, HdfsWriteToMultipleCollectionsTest, TestDeprecatedFilters, = UpdateLogTest, SliceStateTest, TestSystemIdResolver, ForceLeaderWithTlogRep= licasTest, TestPerFieldSimilarity, CacheHeaderTest, SystemCollectionCompatT= est, CdcrRequestHandlerTest, DeleteReplicaTest, TestManagedStopFilterFactor= y, TestShortCircuitedRequests, BasicDistributedZkTest, SolrJmxReporterCloud= Test, TestExceedMaxTermLength, TestConfigSetsAPI, TestReloadAndDeleteDocs, = TestEmbeddedSolrServerSchemaAPI, AtomicUpdatesTest, DistanceFunctionTest, O= verseerModifyCollectionTest, SpellPossibilityIteratorTest, AnalysisAfterCor= eReloadTest, TestFieldCacheWithThreads, ShardSplitTest, HealthCheckHandlerT= est, TolerantUpdateProcessorTest, BufferStoreTest, TestSortableTextField, H= dfsBasicDistributedZk2Test, SignatureUpdateProcessorFactoryTest, TestBinary= Field, TestCollectionAPIs, TestConfigReload, TestFunctionQuery, MBeansHandl= erTest, TestNoOpRegenerator, TestTrie, TestSolrDeletionPolicy1, CurrencyFie= ldTypeTest, SoftAutoCommitTest, TestHashQParserPlugin, TestFieldCollectionR= esource, CollectionTooManyReplicasTest, ResponseBuilderTest, TestCloudJSONF= acetSKG, TestFaceting, TestSimLargeCluster, TestSolrCloudWithHadoopAuthPlug= in, LeaderElectionTest, LeaderFailoverAfterPartitionTest, LeaderVoteWaitTim= eoutTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MultiThreadedOC= PTest, NestedShardedAtomicUpdateTest, OutOfBoxZkACLAndCredentialsProvidersT= est, OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, Res= tartWhileUpdatingTest, RollingRestartTest, SSLMigrationTest, ShardRoutingCu= stomTest, ShardRoutingTest, SharedFSAutoReplicaFailoverTest, SolrCloudExamp= leTest, TestAuthenticationFramework, TestCloudPivotFacet, TestCloudRecovery= 2, TestClusterProperties, TestDeleteCollectionOnDownNodes, TestDownShardTol= erantSearch, TestHashPartitioner, TestLeaderElectionWithEmptyReplica, TestR= andomFlRTGCloud, TestRebalanceLeaders, TestSizeLimitedDistributedMap, TestS= kipOverseerOperations, TestStressInPlaceUpdates, ZkFailoverTest, ZkNodeProp= sTest, ZkShardTermsTest, AsyncCallRequestStatusResponseTest, CollectionsAPI= AsyncDistributedZkTest, TestHdfsCloudBackupRestore, TestReplicaProperties, = TestRequestStatusCollectionAPI, MetricTriggerIntegrationTest, NodeAddedTrig= gerTest, NodeLostTriggerTest, ScheduledTriggerIntegrationTest, SystemLogLis= tenerTest, TriggerEventQueueTest, TriggerIntegrationTest, CdcrOpsAndBoundar= iesTest, CdcrWithNodesRestartsTest, HDFSCollectionsAPITest, HdfsBasicDistri= butedZkTest, HdfsRecoveryZkTest, HdfsSyncSliceTest, HdfsThreadLeakTest, Str= essHdfsTest, TestClusterStateMutator, ZkStateWriterTest, RuleEngineTest, Bl= obRepositoryCloudTest, ConfigureRecoveryStrategyTest, DirectoryFactoryTest,= HdfsDirectoryFactoryTest, PluginInfoTest, QueryResultKeyTest, ResourceLoad= erTest, TestBackupRepositoryFactory, TestBadConfig, TestConfigOverlay, Test= ConfigSetProperties, TestCoreDiscovery, TestDirectoryFactory, TestDynamicLo= ading, TestSolrXml, TestSolrCloudSnapshots, TestSolrConfigHandlerConcurrent= , XmlUpdateRequestHandlerTest, XsltUpdateRequestHandlerTest, AutoscalingHis= toryHandlerTest, DebugComponentTest, DistributedDebugComponentTest, Distrib= utedFacetPivotWhiteBoxTest, DistributedQueryComponentCustomSortTest, Distri= butedSpellCheckComponentTest, FacetPivotSmallTest, PhrasesIdentificationCom= ponentTest, QueryElevationComponentTest, SearchHandlerTest, TermVectorCompo= nentDistributedTest, TaggerTest, HighlighterMaxOffsetTest, TestSlowComposit= eReaderWrapper, TestNumericRangeQuery64, SolrMetricManagerTest, SolrShardRe= porterTest, TestPHPSerializedResponseWriter, TestRawTransformer, TestSubQue= ryTransformer, TestSubQueryTransformerCrossCore, TestManagedResource, TestD= ynamicFieldCollectionResource, TestFieldResource, TestSchemaResource, TestS= chemaVersionResource, TestManagedSynonymFilterFactory, BooleanFieldTest, Ch= angedSchemaMergeTest, CopyFieldTest, DocValuesMissingTest, IndexSchemaRunti= meFieldTest, ManagedSchemaRoundRobinCloudTest, PreAnalyzedFieldTest, Primit= iveFieldTypeTest, SchemaVersionSpecificBehaviorTest, TestCloudManagedSchema= , TestCollationField, TestCollationFieldDocValues, TestHalfAndHalfDocValues= , TestPointFields, SignificantTermsQParserPluginTest, TestAddFieldRealTimeG= et, TestCollapseQParserPlugin, TestDocSet, TestExtendedDismaxParser, TestFo= ldingMultitermQuery, TestIndexSearcher, TestMaxScoreQueryParser, TestOverri= ddenPrefixQueryForCustomFieldType, TestPseudoReturnFields, TestQueryTypes, = TestQueryUtils, TestRTGBase, TestRealTimeGet, TestRecovery, TestJsonFacetRe= finement, TestJsonFacetsWithNestedObjects, SortByFunctionTest, TestOrdValue= s, BJQParserTest, BlockJoinFacetRandomTest, BlockJoinFacetSimpleTest, Graph= QueryTest, TestNestedDocsSort, TestJsonRequest, SimpleMLTQParserTest, TestB= M25SimilarityFactory, TestClassicSimilarityFactory, TestDFISimilarityFactor= y, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestLMJeline= kMercerSimilarityFactory, TestLegacyBM25SimilarityFactory, TestPerFieldSimi= larityWithDefaultOverride, TestSweetSpotSimilarityFactory, TestDistribIDF, = TestExactStatsCache, JWTAuthPluginIntegrationTest, DirectSolrConnectionTest= , SolrRequestParserTest, SpellCheckCollatorWithCollapseTest, TestSuggestSpe= llingConverter, SuggesterFSTTest, SuggesterTest, TestAnalyzedSuggestions, T= estFileDictionaryLookup, TestHighFrequencyDictionaryFactory, HdfsDirectoryT= est, TestDocTermOrds, TestDocTermOrdsUninvertLimit, TestFieldCache, TestFie= ldCacheSort, TestFieldCacheSortRandom, TestNumericTerms32, DataDrivenBlockJ= oinTest, SolrCmdDistributorTest, SolrIndexSplitterTest, TestInPlaceUpdatesD= istrib] [junit4] Completed [822/855 (1!)] on J0 in 58.90s, 1 test, 1 failure <<<= FAILURES! [...truncated 9050 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/build.xml:634: The followin= g error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/build.xml:128: Found 1 viol= ations in source files (File uses Mockito but has no assumeWorkingMockito()= call). Total time: 102 minutes 0 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/Users/jenkins/tools/hudson.tasks.Ant_AntInstallat= ion/ANT_1.8.2 ------=_Part_20_1761756202.1556756120978 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_20_1761756202.1556756120978--