Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id AD733200B38 for ; Fri, 8 Jul 2016 10:57:19 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id ABEB0160A5A; Fri, 8 Jul 2016 08:57:19 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 35A41160A58 for ; Fri, 8 Jul 2016 10:57:17 +0200 (CEST) Received: (qmail 45809 invoked by uid 500); 8 Jul 2016 08:57:16 -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 45797 invoked by uid 99); 8 Jul 2016 08:57:15 -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; Fri, 08 Jul 2016 08:57:15 +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 19B9918052A for ; Fri, 8 Jul 2016 08:57:15 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2 X-Spam-Level: ** X-Spam-Status: No, score=2 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id cfBETBux-wXp for ; Fri, 8 Jul 2016 08:57:04 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTPS id B487B5F484 for ; Fri, 8 Jul 2016 08:57:03 +0000 (UTC) Received: from serv1 (localhost.localdomain [127.0.0.1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id 69D484C043F for ; Fri, 8 Jul 2016 08:57:03 +0000 (UTC) Date: Fri, 8 Jul 2016 08:56:40 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <92757471.3.1467968223438.JavaMail.jenkins@serv1> In-Reply-To: <164643329.147.1467948454151.JavaMail.jenkins@serv1> References: <164643329.147.1467948454151.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_92) - Build # 5968 - Still Failing! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_2_1326101370.1467968223347" X-Jenkins-Job: Lucene-Solr-master-Windows X-Jenkins-Result: FAILURE archived-at: Fri, 08 Jul 2016 08:57:19 -0000 ------=_Part_2_1326101370.1467968223347 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/5968/ Java: 64bit/jdk1.8.0_92 -XX:-UseCompressedOops -XX:+UseParallelGC 1 tests failed. FAILED: org.apache.solr.cloud.TestLocalFSCloudBackupRestore.test Error Message: expected: but was: Stack Trace: java.lang.AssertionError: expected: but was: =09at __randomizedtesting.SeedInfo.seed([E23341F9FC062E83:6A677E2352FA437B]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:128) =09at org.junit.Assert.assertEquals(Assert.java:147) =09at org.apache.solr.cloud.AbstractCloudBackupRestoreTestCase.testBackupAn= dRestore(AbstractCloudBackupRestoreTestCase.java:209) =09at org.apache.solr.cloud.AbstractCloudBackupRestoreTestCase.test(Abstrac= tCloudBackupRestoreTestCase.java:127) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1764) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:871) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:921) =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:367) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:809) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:460) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:880) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:816) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:827) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11211 lines...] [junit4] Suite: org.apache.solr.cloud.TestLocalFSCloudBackupRestore [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestLocalFSCloud= BackupRestore_E23341F9FC062E83-001\init-core-data-001 [junit4] 2> 1014494 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false)= and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, = value=3DNaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 1014495 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVE= R [junit4] 2> 1014495 INFO (Thread-2966) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1014495 INFO (Thread-2966) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1014596 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.ZkTestServer start zk server on por= t:49356 [junit4] 2> 1014596 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1014597 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1014601 INFO (zkCallback-1600-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63= 55fa44 name:ZooKeeperConnection Watcher:127.0.0.1:49356 got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014601 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1014603 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1014603 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr= .xml [junit4] 2> 1014608 INFO (jetty-launcher-1599-thread-1) [ ] o.e.j.= s.Server jetty-9.3.8.v20160314 [junit4] 2> 1014609 INFO (jetty-launcher-1599-thread-2) [ ] o.e.j.= s.Server jetty-9.3.8.v20160314 [junit4] 2> 1014609 INFO (jetty-launcher-1599-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f470f59{/solr,nul= l,AVAILABLE} [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31ff9cf4{/solr,nul= l,AVAILABLE} [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-1) [ ] o.e.j.= s.ServerConnector Started ServerConnector@29c163c2{HTTP/1.1,[http/1.1]}{127= .0.0.1:49360} [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-2) [ ] o.e.j.= s.ServerConnector Started ServerConnector@620140a{HTTP/1.1,[http/1.1]}{127.= 0.0.1:49361} [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-1) [ ] o.e.j.= s.Server Started @1020291ms [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-2) [ ] o.e.j.= s.Server Started @1020291ms [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D49= 361} [junit4] 2> 1014612 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D49= 360} [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassL= oader@73d16e93 [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassL= oader@73d16e93 [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkin= s\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\so= lr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\nod= e2' [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkin= s\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\so= lr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\nod= e1' [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system = property or JNDI) [junit4] 2> 1014613 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system = property or JNDI) [junit4] 2> 1014617 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1014617 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1014619 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1014619 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1014622 INFO (zkCallback-1602-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25= 05ca1f name:ZooKeeperConnection Watcher:127.0.0.1:49356/solr got event Watc= hedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014622 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1014623 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1014623 INFO (zkCallback-1601-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@26= 4fd226 name:ZooKeeperConnection Watcher:127.0.0.1:49356/solr got event Watc= hedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014623 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1014623 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1014623 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1014624 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1014641 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkin= s\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\so= lr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\nod= e2\. [junit4] 2> 1014641 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.CoreContainer New CoreContainer 584583307 [junit4] 2> 1014641 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.CoreContainer Loading cores into CoreContainer [instanceDir=3DC:\Users\je= nkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\tem= p\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001= \node2] [junit4] 2> 1014641 WARN (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-S= olr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestLocalFS= CloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node2\lib to classpath:= C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core= \test\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001= \tempDir-001\node2\lib [junit4] 2> 1014641 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : = ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,core= PoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue= : -1,fairnessPolicy : false, [junit4] 2> 1014641 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkin= s\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\so= lr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\nod= e1\. [junit4] 2> 1014642 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.CoreContainer New CoreContainer 440399877 [junit4] 2> 1014642 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.CoreContainer Loading cores into CoreContainer [instanceDir=3DC:\Users\je= nkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\tem= p\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001= \node1] [junit4] 2> 1014642 WARN (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-S= olr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestLocalFS= CloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node1\lib to classpath:= C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core= \test\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001= \tempDir-001\node1\lib [junit4] 2> 1014642 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : = ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,core= PoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue= : -1,fairnessPolicy : false, [junit4] 2> 1014645 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x155c99619230001, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:228) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:208) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1014648 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: so= cketTimeout=3D340000&connTimeout=3D45000&maxConnections=3D100000&maxConnect= ionsPerHost=3D100000 [junit4] 2> 1014648 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1014648 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFac= tory)] [junit4] 2> 1014648 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:49356/solr [junit4] 2> 1014648 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.ZkController zkHost includes chroot [junit4] 2> 1014648 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1014649 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1014653 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: so= cketTimeout=3D340000&connTimeout=3D45000&maxConnections=3D100000&maxConnect= ionsPerHost=3D100000 [junit4] 2> 1014653 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1014653 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFac= tory)] [junit4] 2> 1014653 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:49356/solr [junit4] 2> 1014653 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.ZkController zkHost includes chroot [junit4] 2> 1014653 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1014654 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1014656 INFO (zkCallback-1605-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@60= 1436f2 name:ZooKeeperConnection Watcher:127.0.0.1:49356 got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014656 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1014656 INFO (jetty-launcher-1599-thread-1) [ ] o.a.s.= c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1014657 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x155c99619230003, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:228) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:208) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1014658 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apa= che.solr.cloud.ZkController$$Lambda$33/463492475@6a1b6a9c [junit4] 2> 1014659 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect t= o ZooKeeper [junit4] 2> 1014660 INFO (zkCallback-1608-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30= 28de44 name:ZooKeeperConnection Watcher:127.0.0.1:49356 got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014660 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1014660 INFO (jetty-launcher-1599-thread-2) [ ] o.a.s.= c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1014661 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apa= che.solr.cloud.ZkController$$Lambda$33/463492475@7660d872 [junit4] 2> 1014662 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect t= o ZooKeeper [junit4] 2> 1014666 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ConnectionManager W= atcher org.apache.solr.common.cloud.ConnectionManager@568d6e49 name:ZooKeep= erConnection Watcher:127.0.0.1:49356/solr got event WatchedEvent state:Sync= Connected type:None path:null path:null type:None [junit4] 2> 1014666 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeepe= r [junit4] 2> 1014667 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1014669 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue= -work [junit4] 2> 1014671 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-r= unning [junit4] 2> 1014672 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ConnectionManager W= atcher org.apache.solr.common.cloud.ConnectionManager@62e00af0 name:ZooKeep= erConnection Watcher:127.0.0.1:49356/solr got event WatchedEvent state:Sync= Connected type:None path:null path:null type:None [junit4] 2> 1014672 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeepe= r [junit4] 2> 1014674 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-c= ompleted [junit4] 2> 1014678 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-f= ailure [junit4] 2> 1014680 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1014682 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1014684 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1014684 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1014686 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1014686 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1014687 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1014688 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1014689 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeep= er...=20 [junit4] 2> 1014689 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeep= er...=20 [junit4] 2> 1014690 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1014691 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1014691 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (0) [junit4] 2> 1014692 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (0) [junit4] 2> 1014695 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1014697 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1014699 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1014699 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1014699 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.LeaderElector Joined leadership election with path:= /overseer_elect/election/96204613562335237-127.0.0.1:49360_solr-n_00000000= 00 [junit4] 2> 1014700 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.LeaderElector Joined leadership election with path:= /overseer_elect/election/96204613562335238-127.0.0.1:49361_solr-n_00000000= 01 [junit4] 2> 1014700 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:49360_solr [junit4] 2> 1014700 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1014702 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/electio= n/96204613562335237-127.0.0.1:49360_solr-n_0000000000 to know if I could be= the leader [junit4] 2> 1014702 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:49361_solr [junit4] 2> 1014702 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:4936= 1_solr [junit4] 2> 1014702 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.Overseer Overseer (id=3D96204613562335237-127.0.0.1= :49360_solr-n_0000000000) starting [junit4] 2> 1014704 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A liv= e node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1014704 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1014704 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader A liv= e node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1014707 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1014708 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1014715 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping= setup for authorization module. [junit4] 2> 1014715 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1014717 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions = underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build= \solr-core\test\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC= 062E83-001\tempDir-001\node2\. [junit4] 2> 1014719 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 1014719 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting Overseer= AutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D10000 autoRepl= icaFailoverWaitAfterExpiration=3D30000 autoReplicaFailoverBadNodeExpiration= =3D60000 [junit4] 2> 1014720 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.ZkController Publish node=3D127.0.0.1:49360_solr as= DOWN [junit4] 2> 1014720 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.s.SolrDispatchFilter user.dir=3DC:\Users\jenkins\work= space\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1 [junit4] 2> 1014720 INFO (jetty-launcher-1599-thread-2) [n:127.0.0.1:= 49361_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1014721 INFO (OverseerCollectionConfigSetProcessor-962046= 13562335237-127.0.0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ]= o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1014722 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= Starting to work on the main queue [junit4] 2> 1014722 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:49360_solr [junit4] 2> 1014722 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:4936= 0_solr [junit4] 2> 1014724 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStat= eReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 1014725 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A liv= e node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1014725 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader A liv= e node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1014725 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1014726 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader Updat= ed live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1014727 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:49360_solr"} current state versio= n: 0 [junit4] 2> 1014727 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.NodeMu= tator DownNode state invoked for node: 127.0.0.1:49360_solr [junit4] 2> 1014735 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping= setup for authorization module. [junit4] 2> 1014735 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1014736 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions = underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build= \solr-core\test\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC= 062E83-001\tempDir-001\node1\. [junit4] 2> 1014736 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 1014737 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.s.SolrDispatchFilter user.dir=3DC:\Users\jenkins\work= space\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1 [junit4] 2> 1014737 INFO (jetty-launcher-1599-thread-1) [n:127.0.0.1:= 49360_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1014737 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1014738 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1014739 INFO (zkCallback-1613-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a= 317aed name:ZooKeeperConnection Watcher:127.0.0.1:49356 got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014739 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1014739 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1014743 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCred= entialsProvider [junit4] 2> 1014744 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for cli= ent to connect to ZooKeeper [junit4] 2> 1014745 INFO (zkCallback-1616-thread-1) [ ] o.a.s.c.c.= ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75= af5cf1 name:ZooKeeperConnection Watcher:127.0.0.1:49356/solr got event Watc= hedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1014745 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ConnectionManager Client is conne= cted to ZooKeeper [junit4] 2> 1014745 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLP= rovider [junit4] 2> 1014745 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster st= ate from ZooKeeper...=20 [junit4] 2> 1014746 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluste= r properties [junit4] 2> 1014747 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes = from ZooKeeper... (0) -> (2) [junit4] 2> 1014749 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/schema.xml [junit4] 2> 1014752 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/c= onf1/solrconfig.xml [junit4] 2> 1014770 INFO (TEST-TestLocalFSCloudBackupRestore.test-see= d#[E23341F9FC062E83]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 1014774 INFO (qtp1890841476-9342) [n:127.0.0.1:49361_solr= ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with p= arams replicationFactor=3D1&property.customKey=3DcustomValue&collection.con= figName=3Dconf1&name=3Dbackuprestore&action=3DCREATE&numShards=3D2&wt=3Djav= abin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1014776 INFO (OverseerCollectionConfigSetProcessor-962046= 13562335237-127.0.0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ]= o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the= message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "name":"backuprestore", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"1", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "property.customKey":"customValue", [junit4] 2> "operation":"create"} [junit4] 2> 1014776 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.OverseerCo= llectionMessageHandler OverseerCollectionMessageHandler.processMessage : cr= eate , { [junit4] 2> "name":"backuprestore", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"1", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "property.customKey":"customValue", [junit4] 2> "operation":"create"} [junit4] 2> 1014776 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.OverseerCo= llectionMessageHandler Create collection backuprestore [junit4] 2> 1014776 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.OverseerCo= llectionMessageHandler creating collections conf node /collections/backupre= store=20 [junit4] 2> 1014777 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.SolrZkCl= ient makePath: /collections/backuprestore [junit4] 2> 1014778 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A col= lections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged= path:/collections], has occurred - updating... [junit4] 2> 1014778 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader A col= lections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged= path:/collections], has occurred - updating... [junit4] 2> 1014778 INFO (zkCallback-1616-thread-1) [ ] o.a.s.c.c.= ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:= NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1014778 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader Addin= g lazy collectionRef for collection backuprestore [junit4] 2> 1014779 INFO (zkCallback-1616-thread-1) [ ] o.a.s.c.c.= ZkStateReader Adding lazy collectionRef for collection backuprestore [junit4] 2> 1014779 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader Addin= g lazy collectionRef for collection backuprestore [junit4] 2> 1014781 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "name":"backuprestore", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"1", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "property.customKey":"customValue", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 1014781 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.Cluste= rStateMutator building a new cName: backuprestore [junit4] 2> 1014782 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.ZkStat= eWriter going to create_collection /collections/backuprestore/state.json [junit4] 2> 1014880 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.OverseerCo= llectionMessageHandler Creating SolrCores for new collection backuprestore,= shardNames [shard1, shard2] , replicationFactor : 1 [junit4] 2> 1014880 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.OverseerCo= llectionMessageHandler Creating core backuprestore_shard2_replica1 as part = of shard shard2 of collection backuprestore on 127.0.0.1:49361_solr [junit4] 2> 1014880 INFO (OverseerThreadFactory-4810-thread-1-process= ing-n:127.0.0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.OverseerCo= llectionMessageHandler Creating core backuprestore_shard1_replica1 as part = of shard shard1 of collection backuprestore on 127.0.0.1:49360_solr [junit4] 2> 1014885 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= ] o.a.s.h.a.CoreAdminOperation core create command property.customKey= =3DcustomValue&qt=3D/admin/cores&collection.configName=3Dconf1&name=3Dbacku= prestore_shard2_replica1&action=3DCREATE&numShards=3D2&collection=3Dbackupr= estore&shard=3Dshard2&wt=3Djavabin&version=3D2 [junit4] 2> 1014885 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr = ] o.a.s.h.a.CoreAdminOperation core create command property.customKey=3Dcu= stomValue&qt=3D/admin/cores&collection.configName=3Dconf1&name=3Dbackuprest= ore_shard1_replica1&action=3DCREATE&numShards=3D2&collection=3Dbackuprestor= e&shard=3Dshard1&wt=3Djavabin&version=3D2 [junit4] 2> 1014888 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=3Dbackuprestore_sha= rd1_replica1, collection.configName=3Dconf1, config=3Dsolrconfig.xml, trans= ient=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetPropertie= s=3Dconfigsetprops.json, collection=3Dbackuprestore, customKey=3DcustomValu= e, numShards=3D2, dataDir=3Ddata\, shard=3Dshard1} [junit4] 2> 1014888 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=3Dbackuprestore_= shard2_replica1, collection.configName=3Dconf1, config=3Dsolrconfig.xml, tr= ansient=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetProper= ties=3Dconfigsetprops.json, collection=3Dbackuprestore, customKey=3DcustomV= alue, numShards=3D2, dataDir=3Ddata\, shard=3Dshard2} [junit4] 2> 1014888 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ZkControll= er publishing state=3Ddown [junit4] 2> 1014888 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ZkContr= oller publishing state=3Ddown [junit4] 2> 1014891 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ZkControll= er Registering watch for collection backuprestore [junit4] 2> 1014891 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ZkContr= oller Registering watch for collection backuprestore [junit4] 2> 1014891 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.c.ZkStateR= eader Add data for [backuprestore] ver [0] [junit4] 2> 1014892 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.c.ZkSta= teReader Add data for [backuprestore] ver [0] [junit4] 2> 1014892 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ZkContr= oller look for our core node name [junit4] 2> 1014892 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ZkControll= er look for our core node name [junit4] 2> 1014892 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 2, message =3D { [junit4] 2> "core":"backuprestore_shard1_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49360/solr", [junit4] 2> "node_name":"127.0.0.1:49360_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"backuprestore", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1014892 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D2 message=3D{ [junit4] 2> "core":"backuprestore_shard1_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49360/solr", [junit4] 2> "node_name":"127.0.0.1:49360_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"backuprestore", [junit4] 2> "operation":"state"} [junit4] 2> 1014895 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 2, message =3D { [junit4] 2> "core":"backuprestore_shard2_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49361/solr", [junit4] 2> "node_name":"127.0.0.1:49361_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"backuprestore", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1014895 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D2 message=3D{ [junit4] 2> "core":"backuprestore_shard2_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49361/solr", [junit4] 2> "node_name":"127.0.0.1:49361_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"backuprestore", [junit4] 2> "operation":"state"} [junit4] 2> 1015000 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.ZkStat= eWriter going to update_collection /collections/backuprestore/state.json ve= rsion: 0 [junit4] 2> 1015001 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/backuprestore/state.json] for collection [backuprestore] h= as occurred - updating... (live nodes size: [2]) [junit4] 2> 1015001 INFO (zkCallback-1609-thread-2-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/backuprestore/state.json] for collection [backuprestore] h= as occurred - updating... (live nodes size: [2]) [junit4] 2> 1015001 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader Updat= ing data for [backuprestore] from [0] to [1] [junit4] 2> 1015002 INFO (zkCallback-1609-thread-2-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader Updat= ing data for [backuprestore] from [0] to [1] [junit4] 2> 1015893 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ZkControll= er waiting to find shard id in clusterstate for backuprestore_shard1_replic= a1 [junit4] 2> 1015893 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ZkContr= oller waiting to find shard id in clusterstate for backuprestore_shard2_rep= lica1 [junit4] 2> 1015893 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ZkControll= er Check for collection zkNode:backuprestore [junit4] 2> 1015893 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ZkContr= oller Check for collection zkNode:backuprestore [junit4] 2> 1015893 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ZkContr= oller Collection zkNode exists [junit4] 2> 1015893 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.c.ZkSta= teReader Load collection config from: [/collections/backuprestore] [junit4] 2> 1015893 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ZkControll= er Collection zkNode exists [junit4] 2> 1015893 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.c.ZkStateR= eader Load collection config from: [/collections/backuprestore] [junit4] 2> 1015895 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.c.ZkSta= teReader path=3D[/collections/backuprestore] [configName]=3D[conf1] specifi= ed config exists in ZooKeeper [junit4] 2> 1015895 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.c.ZkStateR= eader path=3D[/collections/backuprestore] [configName]=3D[conf1] specified = config exists in ZooKeeper [junit4] 2> 1015895 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.SolrRes= ourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspa= ce\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.= TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node2\backup= restore_shard2_replica1' [junit4] 2> 1015895 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.SolrResour= ceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\= Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.Tes= tLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node1\backupres= tore_shard1_replica1' [junit4] 2> 1015895 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.SolrRes= ourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1015895 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.SolrResour= ceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1015895 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.SolrRes= ourceLoader solr home defaulted to 'solr/' (could not find system property = or JNDI) [junit4] 2> 1015895 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.SolrResour= ceLoader solr home defaulted to 'solr/' (could not find system property or = JNDI) [junit4] 2> 1015897 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.Config loa= ded config solrconfig.xml with version 0=20 [junit4] 2> 1015897 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.Config = loaded config solrconfig.xml with version 0=20 [junit4] 2> 1015903 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.SolrCon= fig current version of requestparams : -1 [junit4] 2> 1015904 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.SolrConfig= current version of requestparams : -1 [junit4] 2> 1015905 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.SolrConfig= Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1015905 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.SolrCon= fig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1015910 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.SolrConfig= Loaded SolrConfig: solrconfig.xml [junit4] 2> 1015910 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.SolrCon= fig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1015914 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.s.IndexSchem= a [backuprestore_shard1_replica1] Schema name=3Dminimal [junit4] 2> 1015914 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.s.IndexSc= hema [backuprestore_shard2_replica1] Schema name=3Dminimal [junit4] 2> 1015916 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.s.IndexSchem= a unique key field: id [junit4] 2> 1015916 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.s.IndexSc= hema unique key field: id [junit4] 2> 1015923 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.ConfigSetP= roperties Did not find ConfigSet properties, assuming default properties: C= an't find resource 'configsetprops.json' in classpath or '/configs/conf1', = cwd=3DC:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr= -core\test\J1 [junit4] 2> 1015923 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 x:backuprestore_shard1_replica1] o.a.s.c.CoreContai= ner Creating SolrCore 'backuprestore_shard1_replica1' using configuration f= rom collection backuprestore [junit4] 2> 1015923 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.ConfigS= etProperties Did not find ConfigSet properties, assuming default properties= : Can't find resource 'configsetprops.json' in classpath or '/configs/conf1= ', cwd=3DC:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\s= olr-core\test\J1 [junit4] 2> 1015923 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 x:backuprestore_shard2_replica1] o.a.s.c.CoreCon= tainer Creating SolrCore 'backuprestore_shard2_replica1' using configuratio= n from collection backuprestore [junit4] 2> 1015923 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1015923 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1015923 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore [[backuprestore_shard1_replica1] ] Opening new SolrCore at [C:\U= sers\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test= \J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\temp= Dir-001\node1\backuprestore_shard1_replica1], dataDir=3D[C:\Users\jenkins\w= orkspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.= cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node1\= .\backuprestore_shard1_replica1\data\] [junit4] 2> 1015923 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore [[backuprestore_shard2_replica1] ] Opening new SolrCore at [C= :\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\t= est\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\t= empDir-001\node2\backuprestore_shard2_replica1], dataDir=3D[C:\Users\jenkin= s\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\so= lr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\nod= e2\.\backuprestore_shard2_replica1\data\] [junit4] 2> 1015923 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore JMX monitoring not detected for core: backuprestore_shard1_repli= ca1 [junit4] 2> 1015923 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore JMX monitoring not detected for core: backuprestore_shard2_re= plica1 [junit4] 2> 1015924 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspa= ce\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.= TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node1\.\back= uprestore_shard1_replica1\data\ [junit4] 2> 1015924 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore New index directory detected: old=3Dnull new=3DC:\Users\jenkins\= workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr= .cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node1= \.\backuprestore_shard1_replica1\data\index/ [junit4] 2> 1015924 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\work= space\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.clo= ud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node2\.\b= ackuprestore_shard2_replica1\data\ [junit4] 2> 1015924 WARN (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore [backuprestore_shard1_replica1] Solr index directory 'C:\Users\j= enkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\te= mp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-00= 1\node1\.\backuprestore_shard1_replica1\data\index' doesn't exist. Creating= new index... [junit4] 2> 1015924 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore New index directory detected: old=3Dnull new=3DC:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\no= de2\.\backuprestore_shard2_replica1\data\index/ [junit4] 2> 1015925 WARN (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore [backuprestore_shard2_replica1] Solr index directory 'C:\User= s\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1= \temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir= -001\node2\.\backuprestore_shard2_replica1\data\index' doesn't exist. Creat= ing new index... [junit4] 2> 1015925 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspa= ce\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.= TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node1\.\back= uprestore_shard1_replica1\data\index [junit4] 2> 1015925 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\work= space\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.clo= ud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\tempDir-001\node2\.\b= ackuprestore_shard2_replica1\data\index [junit4] 2> 1015925 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@6c6e9450= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@fe75874),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 1015925 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1015925 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@7ae681f5= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@589f8e49),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1015925 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1015926 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore no updateRequestProcessorChain defined as default, creating impl= icit default [junit4] 2> 1015926 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore no updateRequestProcessorChain defined as default, creating i= mplicit default [junit4] 2> 1015927 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015927 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015929 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015929 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015930 INFO (OldIndexDirectoryCleanupThreadForCore-backu= prestore_shard2_replica1) [n:127.0.0.1:49361_solr c:backuprestore s:shard2 = r:core_node2 x:backuprestore_shard2_replica1] o.a.s.c.SolrCore Looking for = old index directories to cleanup for core backuprestore_shard2_replica1 in = C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\= test\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\= tempDir-001\node2\.\backuprestore_shard2_replica1\data\ [junit4] 2> 1015930 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015930 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015931 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015931 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1015932 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.RequestHandlers Registered paths: /admin/mbeans,/update/json/docs,/admin/= luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv= ,/sql,/graph,/select,/admin/segments,/admin/system,/replication,/config,/st= ream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,= /terms [junit4] 2> 1015932 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.Loc= alStatsCache [junit4] 2> 1015932 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.RequestHandlers Registered paths: /admin/mbeans,/update/json/docs,/adm= in/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/= csv,/sql,/graph,/select,/admin/segments,/admin/system,/replication,/config,= /stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/fi= le,/terms [junit4] 2> 1015932 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.= LocalStatsCache [junit4] 2> 1015933 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upda= teLog [junit4] 2> 1015933 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH= numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1015933 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.U= pdateLog [junit4] 2> 1015933 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFL= USH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1015936 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1015936 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1015936 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1015936 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1015938 INFO (OldIndexDirectoryCleanupThreadForCore-backu= prestore_shard1_replica1) [n:127.0.0.1:49360_solr c:backuprestore s:shard1 = r:core_node1 x:backuprestore_shard1_replica1] o.a.s.c.SolrCore Looking for = old index directories to cleanup for core backuprestore_shard1_replica1 in = C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\= test\J1\temp\solr.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001\= tempDir-001\node1\.\backuprestore_shard1_replica1\data\ [junit4] 2> 1015940 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@6c6e9450= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@fe75874),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 1015940 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1015940 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@7ae681f5= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@589f8e49),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1015940 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.s.SolrIndexSearcher Opening [Searcher@41d1ef4c[backuprestore_shard2_repl= ica1] main] [junit4] 2> 1015940 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1015940 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= s.SolrIndexSearcher Opening [Searcher@5b1dd65[backuprestore_shard1_replica1= ] main] [junit4] 2> 1015940 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.c.ZkStateReader Load collection config from: [/collections/backupresto= re] [junit4] 2> 1015940 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.c.ZkStateReader Load collection config from: [/collections/backuprestore] [junit4] 2> 1015942 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.c.ZkStateReader path=3D[/collections/backuprestore] [configName]=3D[co= nf1] specified config exists in ZooKeeper [junit4] 2> 1015942 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the Rest= Manager with znodeBase: /configs/conf1 [junit4] 2> 1015942 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.c.ZkStateReader path=3D[/collections/backuprestore] [configName]=3D[conf1= ] specified config exists in ZooKeeper [junit4] 2> 1015942 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestMan= ager with znodeBase: /configs/conf1 [junit4] 2> 1015942 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /= configs/conf1 [junit4] 2> 1015942 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /con= figs/conf1 [junit4] 2> 1015942 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1015943 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1015943 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageI= O:path=3D/configs/conf1 [junit4] 2> 1015943 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStora= geIO:path=3D/configs/conf1 [junit4] 2> 1015943 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_ma= naged.json [junit4] 2> 1015943 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using Zo= oKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1015943 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1015943 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1015943 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_manag= ed.json [junit4] 2> 1015943 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKe= eperStorageIO:path=3D/configs/conf1 [junit4] 2> 1015943 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1015943 INFO (searcherExecutor-4814-thread-1-processing-n= :127.0.0.1:49361_solr x:backuprestore_shard2_replica1 s:shard2 c:backuprest= ore r:core_node2) [n:127.0.0.1:49361_solr c:backuprestore s:shard2 r:core_n= ode2 x:backuprestore_shard2_replica1] o.a.s.c.SolrCore [backuprestore_shard= 2_replica1] Registered new searcher Searcher@41d1ef4c[backuprestore_shard2_= replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1015943 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1015943 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.UpdateLog Looking up max value of version field to seed version bucket= s [junit4] 2> 1015944 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.VersionInfo Refreshing highest value of _version_ for 65536 version bu= ckets from index [junit4] 2> 1015944 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1015944 INFO (searcherExecutor-4813-thread-1-processing-n= :127.0.0.1:49360_solr x:backuprestore_shard1_replica1 s:shard1 c:backuprest= ore r:core_node1) [n:127.0.0.1:49360_solr c:backuprestore s:shard1 r:core_n= ode1 x:backuprestore_shard1_replica1] o.a.s.c.SolrCore [backuprestore_shard= 1_replica1] Registered new searcher Searcher@5b1dd65[backuprestore_shard1_r= eplica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1015944 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.VersionInfo Refreshing highest value of _version_ for 65536 version bucke= ts from index [junit4] 2> 1015944 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.VersionInfo No terms found for _version_, cannot seed version bucket h= ighest value from index [junit4] 2> 1015944 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.VersionInfo No terms found for _version_, cannot seed version bucket high= est value from index [junit4] 2> 1015944 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.UpdateLog Could not find max version in index or recent updates, using= new clock 1539273818512556032 [junit4] 2> 1015944 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.UpdateLog Could not find max version in index or recent updates, using ne= w clock 1539273818512556032 [junit4] 2> 1015946 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1539= 273818512556032 [junit4] 2> 1015946 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= u.UpdateLog Took 1.0ms to seed version buckets with highest version 1539273= 818512556032 [junit4] 2> 1015946 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1015946 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1015947 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.CoreContainer registering core: backuprestore_shard1_replica1 [junit4] 2> 1015947 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.CoreContainer registering core: backuprestore_shard2_replica1 [junit4] 2> 1015947 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ZkController Register replica - core:backuprestore_shard1_replica1 addres= s:http://127.0.0.1:49360/solr collection:backuprestore shard:shard1 [junit4] 2> 1015947 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ZkController Register replica - core:backuprestore_shard2_replica1 add= ress:http://127.0.0.1:49361/solr collection:backuprestore shard:shard2 [junit4] 2> 1015947 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.c.SolrZkClient makePath: /collections/backuprestore/leader_elect/shard1/e= lection [junit4] 2> 1015948 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.c.SolrZkClient makePath: /collections/backuprestore/leader_elect/shard= 2/election [junit4] 2> 1015951 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.LeaderElector Joined leadership election with path: /collections/backupre= store/leader_elect/shard1/election/96204613562335237-core_node1-n_000000000= 0 [junit4] 2> 1015951 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.LeaderElector Joined leadership election with path: /collections/backu= prestore/leader_elect/shard2/election/96204613562335238-core_node2-n_000000= 0000 [junit4] 2> 1015952 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ShardLeaderElectionContext Running the leader process for shard=3Dshar= d2 and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 1015952 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ShardLeaderElectionContext Running the leader process for shard=3Dshard1 = and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 1015953 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1015953 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1015953 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SyncStrategy Sync replicas to http://127.0.0.1:49361/solr/backuprestor= e_shard2_replica1/ [junit4] 2> 1015953 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1015953 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1015953 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.SyncStrategy http://127.0.0.1:49361/solr/backuprestore_shard2_replica1= / has no replicas [junit4] 2> 1015953 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1015954 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SyncStrategy Sync replicas to http://127.0.0.1:49360/solr/backuprestore_s= hard1_replica1/ [junit4] 2> 1015954 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 2, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"backuprestore"} current state version: 0 [junit4] 2> 1015954 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1015954 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.SyncStrategy http://127.0.0.1:49360/solr/backuprestore_shard1_replica1/ h= as no replicas [junit4] 2> 1015955 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.c.SolrZkClient makePath: /collections/backuprestore/leaders/shard2 [junit4] 2> 1015955 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.c.SolrZkClient makePath: /collections/backuprestore/leaders/shard1 [junit4] 2> 1015956 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"backuprestore"} current state version: 0 [junit4] 2> 1015957 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ShardLeaderElectionContextBase Creating leader registration node /coll= ections/backuprestore/leaders/shard2/leader after winning as /collections/b= ackuprestore/leader_elect/shard2/election/96204613562335238-core_node2-n_00= 00000000 [junit4] 2> 1015957 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ShardLeaderElectionContextBase Creating leader registration node /collect= ions/backuprestore/leaders/shard1/leader after winning as /collections/back= uprestore/leader_elect/shard1/election/96204613562335237-core_node1-n_00000= 00000 [junit4] 2> 1015959 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49361= /solr/backuprestore_shard2_replica1/ shard2 [junit4] 2> 1015959 INFO (qtp7664861-9333) [n:127.0.0.1:49360_solr c:= backuprestore s:shard1 r:core_node1 x:backuprestore_shard1_replica1] o.a.s.= c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49360/so= lr/backuprestore_shard1_replica1/ shard1 [junit4] 2> 1015959 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"backuprestore", [junit4] 2> "base_url":"http://127.0.0.1:49361/solr", [junit4] 2> "core":"backuprestore_shard2_replica1", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 1015963 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"backuprestore", [junit4] 2> "base_url":"http://127.0.0.1:49360/solr", [junit4] 2> "core":"backuprestore_shard1_replica1", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 1016066 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.ZkStat= eWriter going to update_collection /collections/backuprestore/state.json ve= rsion: 1 [junit4] 2> 1016067 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/backuprestore/state.json] for collection [backuprestore] h= as occurred - updating... (live nodes size: [2]) [junit4] 2> 1016067 INFO (zkCallback-1609-thread-3-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/backuprestore/state.json] for collection [backuprestore] h= as occurred - updating... (live nodes size: [2]) [junit4] 2> 1016067 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader Updat= ing data for [backuprestore] from [1] to [2] [junit4] 2> 1016067 INFO (zkCallback-1609-thread-3-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader Updat= ing data for [backuprestore] from [1] to [2] [junit4] 2> 1016111 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ZkController We are http://127.0.0.1:49361/solr/backuprestore_shard2_r= eplica1/ and leader is http://127.0.0.1:49361/solr/backuprestore_shard2_rep= lica1/ [junit4] 2> 1016111 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ZkController No LogReplay needed for core=3Dbackuprestore_shard2_repli= ca1 baseURL=3Dhttp://127.0.0.1:49361/solr [junit4] 2> 1016111 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1016111 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.ZkController publishing state=3Dactive [junit4] 2> 1016112 INFO (qtp1890841476-9339) [n:127.0.0.1:49361_solr= c:backuprestore s:shard2 r:core_node2 x:backuprestore_shard2_replica1] o.a= .s.c.c.ZkStateReader Forcing refresh of watched collection state for backup= restore [junit4] 2> 1016112 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "core":"backuprestore_shard2_replica1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49361/solr", [junit4] 2> "node_name":"127.0.0.1:49361_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"backuprestore", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1016112 INFO (OverseerStateUpdate-96204613562335237-127.0= .0.1:49360_solr-n_0000000000) [n:127.0.0.1:49360_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D2 message=3D{ [junit4] 2> "core":"backuprestore_shard2_replica1", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49361/solr", [junit4] 2> "node_name":"127.0.0.1:49361_solr", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"backuprestore", [junit4] 2> "operat [...truncated too long message...] O (OverseerStateUpdate-96204613562335237-127.0.0.1:49360_solr-n_0000000000= ) [n:127.0.0.1:49360_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127= .0.0.1:49360_solr [junit4] 2> 1019190 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A clu= ster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p= ath:/collections/backuprestore/state.json] for collection [backuprestore] h= as occurred - updating... (live nodes size: [2]) [junit4] 2> 1019192 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader Updat= ing data for [backuprestore] from [3] to [4] [junit4] 2> 1019192 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader Updat= ing data for [backuprestore] from [3] to [4] [junit4] 2> 1019199 INFO (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader A liv= e node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/live_nodes], has occurred - updating... (live nodes size: [2]) [junit4] 2> 1019199 INFO (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader A liv= e node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/live_nodes], has occurred - updating... (live nodes size: [2]) [junit4] 2> 1019199 WARN (zkCallback-1609-thread-1-processing-n:127.0= .0.1:49360_solr) [n:127.0.0.1:49360_solr ] o.a.s.c.c.ZkStateReader ZooKe= eper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sess= ion expired for /live_nodes] [junit4] 2> 1019206 WARN (zkCallback-1610-thread-2-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.LeaderElector=20 [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /overseer_elect/election [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper= .java:1472) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:331) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:328) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getChildre= n(SolrZkClient.java:328) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLeader= (LeaderElector.java:105) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$200(Leade= rElector.java:56) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector$ElectionWatcher.= process(LeaderElector.java:348) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$3.lambda$p= rocess$0(SolrZkClient.java:266) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:511) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 266) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1142) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:617) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1019206 INFO (jetty-launcher-1599-thread-2) [ ] o.e.j.= s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4f470f59{/solr,nul= l,UNAVAILABLE} [junit4] 2> 1020700 WARN (zkCallback-1610-thread-1-processing-n:127.0= .0.1:49361_solr) [n:127.0.0.1:49361_solr ] o.a.s.c.c.ZkStateReader ZooKe= eper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sess= ion expired for /live_nodes] [junit4] 2> 1020700 INFO (jetty-launcher-1599-thread-1) [ ] o.e.j.= s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@31ff9cf4{/solr,nul= l,UNAVAILABLE} [junit4] 2> 1020710 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.= 1:49356 49356 [junit4] 2> 1020713 INFO (Thread-2966) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:49356 49356 [junit4] 2> 1020713 WARN (Thread-2966) [ ] o.a.s.c.ZkTestServer Wa= tch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/aliases.json [junit4] 2> =093=09/solr/clusterprops.json [junit4] 2> =092=09/solr/security.json [junit4] 2> =092=09/solr/configs/conf1 [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/clusterstate.json [junit4] 2> =092=09/solr/collections/backuprestore/state.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =0910=09/solr/overseer/queue [junit4] 2> =095=09/solr/overseer/collection-queue-work [junit4] 2> =093=09/solr/live_nodes [junit4] 2> =093=09/solr/collections [junit4] 2> =092=09/solr/overseer/queue-work [junit4] 2>=20 [junit4] 2> 1020713 INFO (SUITE-TestLocalFSCloudBackupRestore-seed#[E= 23341F9FC062E83]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins= \workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\sol= r.cloud.TestLocalFSCloudBackupRestore_E23341F9FC062E83-001 [junit4] 2> Jul 08, 2016 8:18:31 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DLucene62, sim=3DRandomSimil= arity(queryNorm=3Dfalse): {}, locale=3Dzh-HK, timezone=3DAmerica/Dawson [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_92 (6= 4-bit)/cpus=3D3,threads=3D1,free=3D95873448,total=3D488112128 [junit4] 2> NOTE: All tests run in this JVM: [BadIndexSchemaTest, Anal= yticsQueryTest, CollectionsAPIDistributedZkTest, TestDynamicLoading, TestFi= leDictionaryLookup, TestSystemIdResolver, HdfsNNFailoverTest, TestReversedW= ildcardFilterFactory, SuggestComponentContextFilterQueryTest, TestConfigSet= Properties, OpenCloseCoreStressTest, TestConfigOverlay, TestManagedSchema, = LukeRequestHandlerTest, NotRequiredUniqueKeyTest, TestReload, DeleteReplica= Test, WordBreakSolrSpellCheckerTest, DistributedFacetPivotSmallAdvancedTest= , TestFiltering, TestJettySolrRunner, HdfsChaosMonkeySafeLeaderTest, TestGr= aphTermsQParserPlugin, TestJsonFacets, HdfsSyncSliceTest, FacetPivotSmallTe= st, TestCoreDiscovery, TestExactStatsCache, DistributedQueueTest, TestDynam= icFieldCollectionResource, RollingRestartTest, TestScoreJoinQPScore, FileUt= ilsTest, TestInitQParser, HdfsBasicDistributedZkTest, CoreAdminCreateDiscov= erTest, TestHdfsUpdateLog, CollectionsAPISolrJTest, SpatialRPTFieldTypeTest= , TestBinaryField, TestRawTransformer, TestDistributedMissingSort, TestReal= TimeGet, FieldAnalysisRequestHandlerTest, SortSpecParsingTest, BJQParserTes= t, LeaderElectionIntegrationTest, TestConfigSetsAPI, MergeStrategyTest, Sho= wFileRequestHandlerTest, TestClassicSimilarityFactory, IndexSchemaRuntimeFi= eldTest, DirectSolrConnectionTest, SuggesterTSTTest, GraphQueryTest, SortBy= FunctionTest, TestLeaderElectionZkExpiry, FileBasedSpellCheckerTest, TestAt= omicUpdateErrorCases, TestInfoStreamLogging, TestCloudPseudoReturnFields, T= estDistribIDF, TestBulkSchemaAPI, TestReloadDeadlock, ReplicationFactorTest= , TestComplexPhraseQParserPlugin, TestUtils, DocValuesMissingTest, TestSolr= ConfigHandlerCloud, HdfsRecoveryZkTest, TestDistributedStatsComponentCardin= ality, TestRandomFaceting, MultiThreadedOCPTest, RequestLoggingTest, HdfsDi= rectoryTest, RAMDirectoryFactoryTest, DateMathParserTest, CopyFieldTest, Te= stGraphMLResponseWriter, BasicAuthIntegrationTest, SuggesterTest, MultiTerm= Test, DistributedTermsComponentTest, TestAuthorizationFramework, TestAuthen= ticationFramework, ResponseLogComponentTest, TestRestoreCore, TestJoin, Spe= llCheckCollatorTest, EchoParamsTest, HdfsWriteToMultipleCollectionsTest, Te= stIndexingPerformance, MinimalSchemaTest, SolrCoreCheckLockOnStartupTest, B= lockDirectoryTest, FieldMutatingUpdateProcessorTest, TestLocalFSCloudBackup= Restore] [junit4] Completed [205/620 (1!)] on J1 in 6.99s, 1 test, 1 failure <<< = FAILURES! [...truncated 1381 lines...] BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:740: The fo= llowing error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:684: The fo= llowing error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:59: The fol= lowing error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build.xml:233: T= he following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\common-build.xml= :530: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.x= ml:1443: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.x= ml:984: There were test failures: 620 suites (10 ignored), 2635 tests, 1 fa= ilure, 144 ignored (98 assumptions) [seed: E23341F9FC062E83] Total time: 81 minutes 47 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_2_1326101370.1467968223347 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_2_1326101370.1467968223347--