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 853AD200B74 for ; Thu, 1 Sep 2016 15:42:47 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 83AD6160AB5; Thu, 1 Sep 2016 13:42:47 +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 2D6B0160AAE for ; Thu, 1 Sep 2016 15:42:45 +0200 (CEST) Received: (qmail 7838 invoked by uid 500); 1 Sep 2016 13:42:44 -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 7828 invoked by uid 99); 1 Sep 2016 13:42:43 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 01 Sep 2016 13:42:43 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id 0128BC2E6D for ; Thu, 1 Sep 2016 13:42:43 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-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 mx2-lw-us.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id 1GAlSLKB8Uf7 for ; Thu, 1 Sep 2016 13:42:35 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [188.138.57.78]) by mx2-lw-us.apache.org (ASF Mail Server at mx2-lw-us.apache.org) with ESMTPS id 0FB495F1EB for ; Thu, 1 Sep 2016 13:42:34 +0000 (UTC) Received: from serv1 (localhost.localdomain [127.0.0.1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id B2EA04C00E2 for ; Thu, 1 Sep 2016 13:42:28 +0000 (UTC) Date: Thu, 1 Sep 2016 13:40:46 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1347526612.107.1472737348741.JavaMail.jenkins@serv1> In-Reply-To: <1493140069.93.1472693887577.JavaMail.jenkins@serv1> References: <1493140069.93.1472693887577.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build # 6092 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_106_1080895637.1472737348674" X-Jenkins-Job: Lucene-Solr-master-Windows X-Jenkins-Result: UNSTABLE archived-at: Thu, 01 Sep 2016 13:42:47 -0000 ------=_Part_106_1080895637.1472737348674 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6092/ Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseG1GC 5 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchema= API Error Message: ObjectTracker found 10 object(s) that were not released!!! [MockDirectoryWr= apper, MockDirectoryWrapper, MockDirectoryWrapper, MDCAwareThreadPoolExecut= or, MockDirectoryWrapper, TransactionLog, TransactionLog, MockDirectoryWrap= per, MDCAwareThreadPoolExecutor, MockDirectoryWrapper] Stack Trace: java.lang.AssertionError: ObjectTracker found 10 object(s) that were not re= leased!!! [MockDirectoryWrapper, MockDirectoryWrapper, MockDirectoryWrapper= , MDCAwareThreadPoolExecutor, MockDirectoryWrapper, TransactionLog, Transac= tionLog, MockDirectoryWrapper, MDCAwareThreadPoolExecutor, MockDirectoryWra= pper] =09at __randomizedtesting.SeedInfo.seed([8D1D4826B214854E]:0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.junit.Assert.assertNull(Assert.java:551) =09at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:= 257) =09at sun.reflect.GeneratedMethodAccessor29.invoke(Unknown Source) =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$7.evaluate(Random= izedRunner.java:834) =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) FAILED: junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchema= API Error Message: Could not remove the following files (in the order of attempts): C:\User= s\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1= \temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\nod= e2\testschemaapi_shard1_replica1\data\tlog\tlog.0000000000000000001: java.n= io.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-= Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_= 8D1D4826B214854E-001\tempDir-001\node2\testschemaapi_shard1_replica1\data\t= log\tlog.0000000000000000001: The process cannot access the file because it= is being used by another process. C:\Users\jenkins\workspace\Lucene-So= lr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManaged= SchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\testschemaapi_shard1_repli= ca1\data\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\w= orkspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.= schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\testsche= maapi_shard1_replica1\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSche= maAPI_8D1D4826B214854E-001\tempDir-001\node2\testschemaapi_shard1_replica1\= data: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\= Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.Te= stManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\testschemaapi_sha= rd1_replica1\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\= solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826= B214854E-001\tempDir-001\node2\testschemaapi_shard1_replica1: java.nio.file= .DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-= Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_= 8D1D4826B214854E-001\tempDir-001\node2\testschemaapi_shard1_replica1 C:\= Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\tes= t\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001= \node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspac= e\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.= TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2 C:\Users\jen= kins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp= \solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\te= stschemaapi_shard1_replica2\data\tlog\tlog.0000000000000000000: java.nio.fi= le.FileSystemException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windo= ws\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4= 826B214854E-001\tempDir-001\node1\testschemaapi_shard1_replica2\data\tlog\t= log.0000000000000000000: The process cannot access the file because it is b= eing used by another process. C:\Users\jenkins\workspace\Lucene-Solr-ma= ster-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchem= aAPI_8D1D4826B214854E-001\tempDir-001\node1\testschemaapi_shard1_replica2\d= ata\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\worksp= ace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schem= a.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\testschemaapi= _shard1_replica2\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master= -Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI= _8D1D4826B214854E-001\tempDir-001\node1\testschemaapi_shard1_replica2\data:= java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucen= e-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestMan= agedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\testschemaapi_shard1_r= eplica2\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\= build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B2148= 54E-001\tempDir-001\node1\testschemaapi_shard1_replica2: java.nio.file.Dire= ctoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windo= ws\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4= 826B214854E-001\tempDir-001\node1\testschemaapi_shard1_replica2 C:\Users= \jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\= temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node= 1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Luc= ene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestM= anagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1 C:\Users\jenkins\= workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr= .schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001: java.nio.fil= e.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master= -Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI= _8D1D4826B214854E-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr= -master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSc= hemaAPI_8D1D4826B214854E-001: java.nio.file.DirectoryNotEmptyException: C:\= Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\tes= t\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001=20 Stack Trace: java.io.IOException: Could not remove the following files (in the order of = attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node2\testschemaapi_shard1_replica1\data\tlog\tlog.00000000000000000= 01: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-So= lr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManaged= SchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\testschemaapi_shard1_repli= ca1\data\tlog\tlog.0000000000000000001: The process cannot access the file = because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node2\testschemaapi_shard1_replica1\data\tlog: java.nio.file.Directo= ryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\= solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826= B214854E-001\tempDir-001\node2\testschemaapi_shard1_replica1\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node2\testschemaapi_shard1_replica1\data: java.nio.file.DirectoryNot= EmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\= build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B2148= 54E-001\tempDir-001\node2\testschemaapi_shard1_replica1\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node2\testschemaapi_shard1_replica1: java.nio.file.DirectoryNotEmpty= Exception: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build= \solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-0= 01\tempDir-001\node2\testschemaapi_shard1_replica1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\wo= rkspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.s= chema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node1\testschemaapi_shard1_replica2\data\tlog\tlog.00000000000000000= 00: java.nio.file.FileSystemException: C:\Users\jenkins\workspace\Lucene-So= lr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManaged= SchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\testschemaapi_shard1_repli= ca2\data\tlog\tlog.0000000000000000000: The process cannot access the file = because it is being used by another process. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node1\testschemaapi_shard1_replica2\data\tlog: java.nio.file.Directo= ryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\= solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826= B214854E-001\tempDir-001\node1\testschemaapi_shard1_replica2\data\tlog C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node1\testschemaapi_shard1_replica2\data: java.nio.file.DirectoryNot= EmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\= build\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B2148= 54E-001\tempDir-001\node1\testschemaapi_shard1_replica2\data C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node1\testschemaapi_shard1_replica2: java.nio.file.DirectoryNotEmpty= Exception: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build= \solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-0= 01\tempDir-001\node1\testschemaapi_shard1_replica2 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001\node1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\wo= rkspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.s= chema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempD= ir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspac= e\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.= TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-co= re\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001: java= .nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Sol= r-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedS= chemaAPI_8D1D4826B214854E-001 =09at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323) =09at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(Test= RuleTemporaryFilesCleanup.java:216) =09at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlway= s(TestRuleAdapter.java:31) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:43) =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) FAILED: org.apache.solr.common.cloud.TestCollectionStateWatchers.testDelet= ionsTriggerWatches Error Message: Error from server at http://127.0.0.1:50630/solr: Could not find collection= : tobedeleted Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:50630/solr: Could not find collection : to= bedeleted =09at __randomizedtesting.SeedInfo.seed([BED70CE32A0F39F3:1C16C46CA86CEF9E]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:608) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:261) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:250) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSo= lrClient.java:415) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolr= Client.java:367) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSo= lrClient.java:1280) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnS= taleState(CloudSolrClient.java:1050) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrCl= ient.java:992) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149= ) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166= ) =09at org.apache.solr.common.cloud.TestCollectionStateWatchers.testDeletion= sTriggerWatches(TestCollectionStateWatchers.java:275) =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) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithS= ecureImpersonation Error Message: java.lang.NullPointerException Stack Trace: com.google.common.util.concurrent.UncheckedExecutionException: java.lang.Nu= llPointerException =09at __randomizedtesting.SeedInfo.seed([8D1D4826B214854E]:0) =09at com.google.common.cache.LocalCache$Segment.get(LocalCache.java:2263) =09at com.google.common.cache.LocalCache.get(LocalCache.java:4000) =09at com.google.common.cache.LocalCache.getOrLoad(LocalCache.java:4004) =09at com.google.common.cache.LocalCache$LocalLoadingCache.get(LocalCache.j= ava:4874) =09at org.apache.hadoop.security.Groups.getGroups(Groups.java:182) =09at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.getUsersFi= rstGroup(TestSolrCloudWithSecureImpersonation.java:64) =09at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.getImperso= natorSettings(TestSolrCloudWithSecureImpersonation.java:86) =09at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.startup(Te= stSolrCloudWithSecureImpersonation.java:99) =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$6.evaluate(Random= izedRunner.java:811) =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) Caused by: java.lang.NullPointerException =09at java.lang.ProcessBuilder.start(ProcessBuilder.java:1012) =09at org.apache.hadoop.util.Shell.runCommand(Shell.java:483) =09at org.apache.hadoop.util.Shell.run(Shell.java:456) =09at org.apache.hadoop.util.Shell$ShellCommandExecutor.execute(Shell.java:= 722) =09at org.apache.hadoop.util.Shell.execCommand(Shell.java:815) =09at org.apache.hadoop.util.Shell.execCommand(Shell.java:798) =09at org.apache.hadoop.security.ShellBasedUnixGroupsMapping.getUnixGroups(= ShellBasedUnixGroupsMapping.java:84) =09at org.apache.hadoop.security.ShellBasedUnixGroupsMapping.getGroups(Shel= lBasedUnixGroupsMapping.java:52) =09at org.apache.hadoop.security.JniBasedUnixGroupsMappingWithFallback.getG= roups(JniBasedUnixGroupsMappingWithFallback.java:51) =09at org.apache.hadoop.security.Groups$GroupCacheLoader.fetchGroupList(Gro= ups.java:239) =09at org.apache.hadoop.security.Groups$GroupCacheLoader.load(Groups.java:2= 20) =09at org.apache.hadoop.security.Groups$GroupCacheLoader.load(Groups.java:2= 08) =09at com.google.common.cache.LocalCache$LoadingValueReference.loadFuture(L= ocalCache.java:3599) =09at com.google.common.cache.LocalCache$Segment.loadSync(LocalCache.java:2= 379) =09at com.google.common.cache.LocalCache$Segment.lockedGetOrLoad(LocalCache= .java:2342) =09at com.google.common.cache.LocalCache$Segment.get(LocalCache.java:2257) =09... 31 more FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithS= ecureImpersonation Error Message: Stack Trace: java.lang.NullPointerException =09at __randomizedtesting.SeedInfo.seed([8D1D4826B214854E]:0) =09at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation.shutdown(T= estSolrCloudWithSecureImpersonation.java:154) =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$7.evaluate(Random= izedRunner.java:834) =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 10667 lines...] [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithSecureImpersonati= on [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWit= hSecureImpersonation_8D1D4826B214854E-001\init-core-data-001 [junit4] 2> 288001 INFO (SUITE-TestSolrCloudWithSecureImpersonation-s= eed#[8D1D4826B214854E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (= false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reaso= n=3D, value=3DNaN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 288300 WARN (SUITE-TestSolrCloudWithSecureImpersonation-s= eed#[8D1D4826B214854E]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to lo= ad native-hadoop library for your platform... using builtin-java classes wh= ere applicable [junit4] 2> 288329 ERROR (SUITE-TestSolrCloudWithSecureImpersonation-s= eed#[8D1D4826B214854E]-worker) [ ] o.a.h.u.Shell Failed to locate the wi= nutils binary in the hadoop binary path [junit4] 2> java.io.IOException: Could not locate executable null\bin\= winutils.exe in the Hadoop binaries. [junit4] 2> =09at org.apache.hadoop.util.Shell.getQualifiedBinPath(She= ll.java:356) [junit4] 2> =09at org.apache.hadoop.util.Shell.getWinUtilsPath(Shell.j= ava:371) [junit4] 2> =09at org.apache.hadoop.util.Shell.(Shell.java:364= ) [junit4] 2> =09at org.apache.hadoop.util.StringUtils.(StringUt= ils.java:80) [junit4] 2> =09at org.apache.hadoop.security.Groups.parseStaticMapping= (Groups.java:130) [junit4] 2> =09at org.apache.hadoop.security.Groups.(Groups.java= :94) [junit4] 2> =09at org.apache.hadoop.security.Groups.(Groups.java= :74) [junit4] 2> =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.getUsersFirstGroup(TestSolrCloudWithSecureImpersonation.java:60) [junit4] 2> =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.getImpersonatorSettings(TestSolrCloudWithSecureImpersonation.java:86= ) [junit4] 2> =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.startup(TestSolrCloudWithSecureImpersonation.java:99) [junit4] 2> =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native = Method) [junit4] 2> =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMe= thodAccessorImpl.java:62) [junit4] 2> =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(Dele= gatingMethodAccessorImpl.java:43) [junit4] 2> =09at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner.= invoke(RandomizedRunner.java:1764) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 6.evaluate(RandomizedRunner.java:811) [junit4] 2> =09at com.carrotsearch.randomizedtesting.RandomizedRunner$= 7.evaluate(RandomizedRunner.java:827) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.SystemPrope= rtiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.eva= luate(AbstractBeforeAfterRule.java:45) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at org.apache.lucene.util.TestRuleStoreClassName$1.eval= uate(TestRuleStoreClassName.java:41) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.NoShadowing= OrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.jav= a:40) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.NoShadowing= OrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.jav= a:40) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.= evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluat= e(TestRuleMarkFailure.java:47) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailure= s$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.ev= aluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> =09at com.carrotsearch.randomizedtesting.rules.StatementAd= apter.evaluate(StatementAdapter.java:36) [junit4] 2> =09at com.carrotsearch.randomizedtesting.ThreadLeakControl= $StatementRunner.run(ThreadLeakControl.java:367) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 288389 INFO (SUITE-TestSolrCloudWithSecureImpersonation-s= eed#[8D1D4826B214854E]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene62): {}, do= cValues:{}, maxPointsInLeafNode=3D145, maxMBSortInHeap=3D6.866268939079598,= sim=3DRandomSimilarity(queryNorm=3Dtrue): {}, locale=3Dcs, timezone=3DPaci= fic/Midway [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (= 64-bit)/cpus=3D3,threads=3D1,free=3D161170984,total=3D536870912 [junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIDistribute= dZkTest, ResponseLogComponentTest, DefaultValueUpdateProcessorTest, TestSol= rIndexConfig, ZkSolrClientTest, TestTrie, TestMaxScoreQueryParser, NumericF= ieldsTest, StressHdfsTest, OpenCloseCoreStressTest, SuggestComponentTest, T= estSolrCloudWithSecureImpersonation] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestSolrCloud= WithSecureImpersonation -Dtests.seed=3D8D1D4826B214854E -Dtests.slow=3Dtrue= -Dtests.locale=3Dcs -Dtests.timezone=3DPacific/Midway -Dtests.asserts=3Dtr= ue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite)= <<< [junit4] > Throwable #1: com.google.common.util.concurrent.UncheckedE= xecutionException: java.lang.NullPointerException [junit4] > =09at __randomizedtesting.SeedInfo.seed([8D1D4826B214854E]= :0) [junit4] > =09at com.google.common.cache.LocalCache$Segment.get(Local= Cache.java:2263) [junit4] > =09at com.google.common.cache.LocalCache.get(LocalCache.ja= va:4000) [junit4] > =09at com.google.common.cache.LocalCache.getOrLoad(LocalCa= che.java:4004) [junit4] > =09at com.google.common.cache.LocalCache$LocalLoadingCache= .get(LocalCache.java:4874) [junit4] > =09at org.apache.hadoop.security.Groups.getGroups(Groups.j= ava:182) [junit4] > =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.getUsersFirstGroup(TestSolrCloudWithSecureImpersonation.java:64) [junit4] > =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.getImpersonatorSettings(TestSolrCloudWithSecureImpersonation.java:86= ) [junit4] > =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.startup(TestSolrCloudWithSecureImpersonation.java:99) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: java.lang.NullPointerException [junit4] > =09at java.lang.ProcessBuilder.start(ProcessBuilder.java:1= 012) [junit4] > =09at org.apache.hadoop.util.Shell.runCommand(Shell.java:4= 83) [junit4] > =09at org.apache.hadoop.util.Shell.run(Shell.java:456) [junit4] > =09at org.apache.hadoop.util.Shell$ShellCommandExecutor.ex= ecute(Shell.java:722) [junit4] > =09at org.apache.hadoop.util.Shell.execCommand(Shell.java:= 815) [junit4] > =09at org.apache.hadoop.util.Shell.execCommand(Shell.java:= 798) [junit4] > =09at org.apache.hadoop.security.ShellBasedUnixGroupsMappi= ng.getUnixGroups(ShellBasedUnixGroupsMapping.java:84) [junit4] > =09at org.apache.hadoop.security.ShellBasedUnixGroupsMappi= ng.getGroups(ShellBasedUnixGroupsMapping.java:52) [junit4] > =09at org.apache.hadoop.security.JniBasedUnixGroupsMapping= WithFallback.getGroups(JniBasedUnixGroupsMappingWithFallback.java:51) [junit4] > =09at org.apache.hadoop.security.Groups$GroupCacheLoader.f= etchGroupList(Groups.java:239) [junit4] > =09at org.apache.hadoop.security.Groups$GroupCacheLoader.l= oad(Groups.java:220) [junit4] > =09at org.apache.hadoop.security.Groups$GroupCacheLoader.l= oad(Groups.java:208) [junit4] > =09at com.google.common.cache.LocalCache$LoadingValueRefer= ence.loadFuture(LocalCache.java:3599) [junit4] > =09at com.google.common.cache.LocalCache$Segment.loadSync(= LocalCache.java:2379) [junit4] > =09at com.google.common.cache.LocalCache$Segment.lockedGet= OrLoad(LocalCache.java:2342) [junit4] > =09at com.google.common.cache.LocalCache$Segment.get(Local= Cache.java:2257) [junit4] > =09... 31 moreThrowable #2: java.lang.NullPointerException [junit4] > =09at __randomizedtesting.SeedInfo.seed([8D1D4826B214854E]= :0) [junit4] > =09at org.apache.solr.cloud.TestSolrCloudWithSecureImperso= nation.shutdown(TestSolrCloudWithSecureImpersonation.java:154) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] Completed [49/632 (1!)] on J1 in 0.44s, 0 tests, 2 errors <<< F= AILURES! [...truncated 1675 lines...] [junit4] Suite: org.apache.solr.schema.TestManagedSchemaAPI [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-m= aster-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManagedSche= maAPI_8D1D4826B214854E-001\init-core-data-001 [junit4] 2> 2838571 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clie= ntAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3DN= aN, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 2838573 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2838573 INFO (Thread-4808) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2838573 INFO (Thread-4808) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 2838671 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:64602 [junit4] 2> 2838671 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsPr= ovider [junit4] 2> 2838672 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to co= nnect to ZooKeeper [junit4] 2> 2838678 INFO (zkCallback-10043-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6= c7e93b3 name:ZooKeeperConnection Watcher:127.0.0.1:64602 got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838678 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to Z= ooKeeper [junit4] 2> 2838679 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838679 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml [junit4] 2> 2838683 INFO (jetty-launcher-10042-thread-1) [ ] o.e.j= .s.Server jetty-9.3.8.v20160314 [junit4] 2> 2838683 INFO (jetty-launcher-10042-thread-2) [ ] o.e.j= .s.Server jetty-9.3.8.v20160314 [junit4] 2> 2838683 INFO (jetty-launcher-10042-thread-1) [ ] o.e.j= .s.h.ContextHandler Started o.e.j.s.ServletContextHandler@556c1fe8{/solr,nu= ll,AVAILABLE} [junit4] 2> 2838683 INFO (jetty-launcher-10042-thread-2) [ ] o.e.j= .s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4eff352a{/solr,nu= ll,AVAILABLE} [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-2) [ ] o.e.j= .s.ServerConnector Started ServerConnector@1caaac76{HTTP/1.1,[http/1.1]}{12= 7.0.0.1:64607} [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-1) [ ] o.e.j= .s.ServerConnector Started ServerConnector@683ede5b{HTTP/1.1,[http/1.1]}{12= 7.0.0.1:64606} [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-2) [ ] o.e.j= .s.Server Started @2844876ms [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-1) [ ] o.e.j= .s.Server Started @2844876ms [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D6= 4607} [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D6= 4606} [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClass= Loader@73d16e93 [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClass= Loader@73d16e93 [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2' [junit4] 2> 2838688 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1' [junit4] 2> 2838689 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2838689 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2838689 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system= property or JNDI) [junit4] 2> 2838689 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system= property or JNDI) [junit4] 2> 2838690 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2838690 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2838690 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2838690 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2838692 INFO (zkCallback-10044-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b= 6478cb name:ZooKeeperConnection Watcher:127.0.0.1:64602/solr got event Watc= hedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838692 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2838692 INFO (zkCallback-10045-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3= 9ec55b7 name:ZooKeeperConnection Watcher:127.0.0.1:64602/solr got event Wat= chedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838692 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838692 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2838692 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838693 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2838693 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2838702 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 0x156e5e335320001, 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> 2838703 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\. [junit4] 2> 2838702 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenki= ns\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\s= olr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\. [junit4] 2> 2838703 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.CoreContainer New CoreContainer 1039447290 [junit4] 2> 2838703 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.CoreContainer New CoreContainer 1284490468 [junit4] 2> 2838703 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.CoreContainer Loading cores into CoreContainer [instanceDir=3DC:\Users\j= enkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\te= mp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1] [junit4] 2> 2838703 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.CoreContainer Loading cores into CoreContainer [instanceDir=3DC:\Users\j= enkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\te= mp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2] [junit4] 2> 2838703 WARN (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-= Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManag= edSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\lib to classpath: C:\Use= rs\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J= 1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\no= de1\lib [junit4] 2> 2838703 WARN (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-= Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestManag= edSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\lib to classpath: C:\Use= rs\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J= 1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\no= de2\lib [junit4] 2> 2838703 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme := ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,cor= ePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueu= e : -1,fairnessPolicy : false, [junit4] 2> 2838703 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme := ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,cor= ePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueu= e : -1,fairnessPolicy : false, [junit4] 2> 2838709 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: s= ocketTimeout=3D340000&connTimeout=3D45000&maxConnections=3D100000&maxConnec= tionsPerHost=3D100000 [junit4] 2> 2838709 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2838709 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: s= ocketTimeout=3D340000&connTimeout=3D45000&maxConnections=3D100000&maxConnec= tionsPerHost=3D100000 [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFa= ctory)] [junit4] 2> 2838709 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFa= ctory)] [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.ZkContainer Zookeeper client=3D127.0.0.1:64602/solr [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.ZkController zkHost includes chroot [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.ZkContainer Zookeeper client=3D127.0.0.1:64602/solr [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.ZkController zkHost includes chroot [junit4] 2> 2838710 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2838711 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2838711 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2838712 INFO (zkCallback-10050-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4= e9641f0 name:ZooKeeperConnection Watcher:127.0.0.1:64602 got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838712 INFO (zkCallback-10051-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6= 4b90b08 name:ZooKeeperConnection Watcher:127.0.0.1:64602 got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838712 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2838712 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2838712 INFO (jetty-launcher-10042-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838712 INFO (jetty-launcher-10042-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838713 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 0x156e5e335320003, 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> 2838713 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.ap= ache.solr.cloud.ZkController$$Lambda$9/95131194@7908b153 [junit4] 2> 2838713 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.ap= ache.solr.cloud.ZkController$$Lambda$9/95131194@1ad987da [junit4] 2> 2838714 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 2838714 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 2838715 INFO (zkCallback-10053-thread-1-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ConnectionManager = Watcher org.apache.solr.common.cloud.ConnectionManager@52c2b6e6 name:ZooKee= perConnection Watcher:127.0.0.1:64602/solr got event WatchedEvent state:Syn= cConnected type:None path:null path:null type:None [junit4] 2> 2838715 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 2838715 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ConnectionManager = Watcher org.apache.solr.common.cloud.ConnectionManager@70894e6c name:ZooKee= perConnection Watcher:127.0.0.1:64602/solr got event WatchedEvent state:Syn= cConnected type:None path:null path:null type:None [junit4] 2> 2838715 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 2838716 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2838716 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2838718 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queu= e-work [junit4] 2> 2838718 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queu= e-work [junit4] 2> 2838721 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= running [junit4] 2> 2838721 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= running [junit4] 2> 2838722 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= completed [junit4] 2> 2838722 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= completed [junit4] 2> 2838724 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= failure [junit4] 2> 2838724 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= failure [junit4] 2> 2838725 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2838725 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2838726 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 2838726 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 2838727 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2838728 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2838729 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2838729 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2838729 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2838729 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2838731 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 2838731 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 2838731 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 2838731 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 2838732 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper.= .. (0) -> (0) [junit4] 2> 2838732 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper.= .. (0) -> (0) [junit4] 2> 2838735 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2838735 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2838736 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2838736 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2838737 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 2838738 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 2838738 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.LeaderElector Joined leadership election with path= : /overseer_elect/election/96517206047850501-127.0.0.1:64606_solr-n_0000000= 000 [junit4] 2> 2838738 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.LeaderElector Joined leadership election with path= : /overseer_elect/election/96517206047850502-127.0.0.1:64607_solr-n_0000000= 001 [junit4] 2> 2838738 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.OverseerElectionContext I am going to be the leade= r 127.0.0.1:64606_solr [junit4] 2> 2838738 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2838739 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/electi= on/96517206047850501-127.0.0.1:64606_solr-n_0000000000 to know if I could b= e the leader [junit4] 2> 2838740 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/l= ive_nodes/127.0.0.1:64607_solr [junit4] 2> 2838740 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:646= 07_solr [junit4] 2> 2838741 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.Overseer Overseer (id=3D96517206047850501-127.0.0.= 1:64606_solr-n_0000000000) starting [junit4] 2> 2838741 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader A li= ve node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged = path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 2838745 INFO (zkCallback-10053-thread-1-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader A li= ve node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged = path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 2838747 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader Upda= ted live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2838747 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2838748 INFO (zkCallback-10053-thread-1-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader Upda= ted live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2838748 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skippin= g setup for authorization module. [junit4] 2> 2838748 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2838748 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions= underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\buil= d\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-= 001\tempDir-001\node2\. [junit4] 2> 2838749 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 2838750 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.s.SolrDispatchFilter user.dir=3DC:\Users\jenkins\wor= kspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1 [junit4] 2> 2838750 INFO (jetty-launcher-10042-thread-2) [n:127.0.0.1= :64607_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2838753 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting Oversee= rAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D10000 autoRep= licaFailoverWaitAfterExpiration=3D30000 autoReplicaFailoverBadNodeExpiratio= n=3D60000 [junit4] 2> 2838754 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.ZkController Publish node=3D127.0.0.1:64606_solr a= s DOWN [junit4] 2> 2838755 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/l= ive_nodes/127.0.0.1:64606_solr [junit4] 2> 2838755 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:646= 06_solr [junit4] 2> 2838756 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.Overseer= Starting to work on the main queue [junit4] 2> 2838756 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader A li= ve node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged = path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 2838756 INFO (zkCallback-10053-thread-1-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader A li= ve node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged = path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 2838756 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader Upda= ted live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2838756 INFO (zkCallback-10053-thread-1-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader Upda= ted live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2838756 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStat= eReader Updated live nodes from ZooKeeper... (2) -> (2) [junit4] 2> 2838758 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:64606_solr"} current state versio= n: 0 [junit4] 2> 2838758 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.o.NodeMu= tator DownNode state invoked for node: 127.0.0.1:64606_solr [junit4] 2> 2838761 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skippin= g setup for authorization module. [junit4] 2> 2838761 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2838761 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions= underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\buil= d\solr-core\test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-= 001\tempDir-001\node1\. [junit4] 2> 2838762 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 2838763 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.s.SolrDispatchFilter user.dir=3DC:\Users\jenkins\wor= kspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1 [junit4] 2> 2838763 INFO (jetty-launcher-10042-thread-1) [n:127.0.0.1= :64606_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2838763 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsPr= ovider [junit4] 2> 2838764 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to co= nnect to ZooKeeper [junit4] 2> 2838767 INFO (OverseerCollectionConfigSetProcessor-965172= 06047850501-127.0.0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ]= o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2838772 INFO (zkCallback-10056-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3= bdf05ab name:ZooKeeperConnection Watcher:127.0.0.1:64602 got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838772 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to Z= ooKeeper [junit4] 2> 2838772 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838775 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsPr= ovider [junit4] 2> 2838776 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to co= nnect to ZooKeeper [junit4] 2> 2838780 INFO (zkCallback-10059-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3= aa81e8b name:ZooKeeperConnection Watcher:127.0.0.1:64602/solr got event Wat= chedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2838780 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to Z= ooKeeper [junit4] 2> 2838780 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2838780 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from = ZooKeeper...=20 [junit4] 2> 2838781 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster propert= ies [junit4] 2> 2838781 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooK= eeper... (0) -> (2) [junit4] 2> 2838784 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mana= ged-schema [junit4] 2> 2838787 INFO (SUITE-TestManagedSchemaAPI-seed#[8D1D4826B2= 14854E]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solr= config.xml [junit4] 2> 2838809 INFO (TEST-TestManagedSchemaAPI.test-seed#[8D1D48= 26B214854E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 2838812 INFO (qtp1902620818-46221) [n:127.0.0.1:64606_sol= r ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with = params replicationFactor=3D2&collection.configName=3Dconf1&name=3Dtestschem= aapi&action=3DCREATE&numShards=3D1&wt=3Djavabin&version=3D2 and sendToOCPQu= eue=3Dtrue [junit4] 2> 2838817 INFO (OverseerCollectionConfigSetProcessor-965172= 06047850501-127.0.0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ]= o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the= message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "name":"testschemaapi", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"1", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} [junit4] 2> 2838818 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.OverseerC= ollectionMessageHandler OverseerCollectionMessageHandler.processMessage : c= reate , { [junit4] 2> "name":"testschemaapi", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"1", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} [junit4] 2> 2838818 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.CreateCol= lectionCmd Create collection testschemaapi [junit4] 2> 2838818 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.OverseerC= ollectionMessageHandler creating collections conf node /collections/testsch= emaapi=20 [junit4] 2> 2838819 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.SolrZkC= lient makePath: /collections/testschemaapi [junit4] 2> 2838821 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader A co= llections change: [WatchedEvent state:SyncConnected type:NodeChildrenChange= d path:/collections], has occurred - updating... [junit4] 2> 2838821 INFO (zkCallback-10053-thread-1-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader A co= llections change: [WatchedEvent state:SyncConnected type:NodeChildrenChange= d path:/collections], has occurred - updating... [junit4] 2> 2838822 INFO (zkCallback-10059-thread-1) [ ] o.a.s.c.c= .ZkStateReader A collections change: [WatchedEvent state:SyncConnected type= :NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 2838824 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "name":"testschemaapi", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"1", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 2838824 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.o.Cluste= rStateMutator building a new cName: testschemaapi [junit4] 2> 2838825 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.o.ZkStat= eWriter going to create_collection /collections/testschemaapi/state.json [junit4] 2> 2838907 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.CreateCol= lectionCmd Creating SolrCores for new collection testschemaapi, shardNames = [shard1] , replicationFactor : 2 [junit4] 2> 2838907 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.CreateCol= lectionCmd Creating core testschemaapi_shard1_replica1 as part of shard sha= rd1 of collection testschemaapi on 127.0.0.1:64607_solr [junit4] 2> 2838907 INFO (OverseerThreadFactory-10943-thread-1-proces= sing-n:127.0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.CreateCol= lectionCmd Creating core testschemaapi_shard1_replica2 as part of shard sha= rd1 of collection testschemaapi on 127.0.0.1:64606_solr [junit4] 2> 2838911 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= ollection.configName=3Dconf1&name=3Dtestschemaapi_shard1_replica2&action=3D= CREATE&numShards=3D1&collection=3Dtestschemaapi&shard=3Dshard1&wt=3Djavabin= &version=3D2 [junit4] 2> 2838911 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r ] o.a.s.h.a.CoreAdminOperation core create command qt=3D/admin/cores&c= ollection.configName=3Dconf1&name=3Dtestschemaapi_shard1_replica1&action=3D= CREATE&numShards=3D1&collection=3Dtestschemaapi&shard=3Dshard1&wt=3Djavabin= &version=3D2 [junit4] 2> 2838912 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=3Dtestschemaapi= _shard1_replica2, collection.configName=3Dconf1, config=3Dsolrconfig.xml, t= ransient=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetPrope= rties=3Dconfigsetprops.json, collection=3Dtestschemaapi, numShards=3D1, dat= aDir=3Ddata\, shard=3Dshard1} [junit4] 2> 2838913 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=3Dtestschemaapi= _shard1_replica1, collection.configName=3Dconf1, config=3Dsolrconfig.xml, t= ransient=3Dfalse, schema=3Dschema.xml, loadOnStartup=3Dtrue, configSetPrope= rties=3Dconfigsetprops.json, collection=3Dtestschemaapi, numShards=3D1, dat= aDir=3Ddata\, shard=3Dshard1} [junit4] 2> 2838913 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkCont= roller publishing state=3Ddown [junit4] 2> 2838913 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkCont= roller publishing state=3Ddown [junit4] 2> 2838913 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkCont= roller Registering watch for collection testschemaapi [junit4] 2> 2838913 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkCont= roller Registering watch for collection testschemaapi [junit4] 2> 2838913 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkSt= ateReader Add data for [testschemaapi] ver [0] [junit4] 2> 2838913 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.Overseer= processMessage: queueSize: 2, message =3D { [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:64606/solr", [junit4] 2> "node_name":"127.0.0.1:64606_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 2838913 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkCont= roller look for our core node name [junit4] 2> 2838913 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkSt= ateReader Add data for [testschemaapi] ver [0] [junit4] 2> 2838913 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D1 message=3D{ [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:64606/solr", [junit4] 2> "node_name":"127.0.0.1:64606_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "operation":"state"} [junit4] 2> 2838914 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkCont= roller look for our core node name [junit4] 2> 2838916 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "core":"testschemaapi_shard1_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:64607/solr", [junit4] 2> "node_name":"127.0.0.1:64607_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 2838916 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D1 message=3D{ [junit4] 2> "core":"testschemaapi_shard1_replica1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:64607/solr", [junit4] 2> "node_name":"127.0.0.1:64607_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "operation":"state"} [junit4] 2> 2839001 INFO (OverseerStateUpdate-96517206047850501-127.0= .0.1:64606_solr-n_0000000000) [n:127.0.0.1:64606_solr ] o.a.s.c.o.ZkStat= eWriter going to update_collection /collections/testschemaapi/state.json ve= rsion: 0 [junit4] 2> 2839002 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader A cl= uster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged = path:/collections/testschemaapi/state.json] for collection [testschemaapi] = has occurred - updating... (live nodes size: [2]) [junit4] 2> 2839002 INFO (zkCallback-10053-thread-2-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader A cl= uster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged = path:/collections/testschemaapi/state.json] for collection [testschemaapi] = has occurred - updating... (live nodes size: [2]) [junit4] 2> 2839002 INFO (zkCallback-10052-thread-1-processing-n:127.= 0.0.1:64607_solr) [n:127.0.0.1:64607_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [0] to [1] [junit4] 2> 2839002 INFO (zkCallback-10053-thread-2-processing-n:127.= 0.0.1:64606_solr) [n:127.0.0.1:64606_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [0] to [1] [junit4] 2> 2839744 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkCont= roller waiting to find shard id in clusterstate for testschemaapi_shard1_re= plica1 [junit4] 2> 2839744 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkCont= roller waiting to find shard id in clusterstate for testschemaapi_shard1_re= plica2 [junit4] 2> 2839744 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkCont= roller Check for collection zkNode:testschemaapi [junit4] 2> 2839744 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkCont= roller Check for collection zkNode:testschemaapi [junit4] 2> 2839744 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkCont= roller Collection zkNode exists [junit4] 2> 2839744 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkCont= roller Collection zkNode exists [junit4] 2> 2839744 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkSt= ateReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 2839744 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkSt= ateReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 2839745 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkSt= ateReader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specif= ied config exists in ZooKeeper [junit4] 2> 2839745 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkSt= ateReader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specif= ied config exists in ZooKeeper [junit4] 2> 2839745 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrRe= sourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\worksp= ace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schem= a.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\testschemaapi= _shard1_replica2' [junit4] 2> 2839745 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrRe= sourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\worksp= ace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.schem= a.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\testschemaapi= _shard1_replica1' [junit4] 2> 2839745 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrRe= sourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2839745 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrRe= sourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2839745 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrRe= sourceLoader solr home defaulted to 'solr/' (could not find system property= or JNDI) [junit4] 2> 2839745 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrRe= sourceLoader solr home defaulted to 'solr/' (could not find system property= or JNDI) [junit4] 2> 2839749 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config= loaded config solrconfig.xml with version 0=20 [junit4] 2> 2839749 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config= loaded config solrconfig.xml with version 0=20 [junit4] 2> 2839754 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCo= nfig current version of requestparams : -1 [junit4] 2> 2839754 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCo= nfig current version of requestparams : -1 [junit4] 2> 2839776 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCo= nfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2839776 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCo= nfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2839780 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCo= nfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2839780 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCo= nfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2839783 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexS= chema [testschemaapi_shard1_replica2] Schema name=3Dminimal [junit4] 2> 2839783 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexS= chema [testschemaapi_shard1_replica1] Schema name=3Dminimal [junit4] 2> 2839790 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexS= chema unique key field: id [junit4] 2> 2839790 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexS= chema unique key field: id [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config= SetProperties Did not find ConfigSet properties, assuming default propertie= s: Can't find resource 'configsetprops.json' in classpath or '/configs/conf= 1', cwd=3DC:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\= solr-core\test\J1 [junit4] 2> 2839796 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config= SetProperties Did not find ConfigSet properties, assuming default propertie= s: Can't find resource 'configsetprops.json' in classpath or '/configs/conf= 1', cwd=3DC:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\= solr-core\test\J1 [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreCo= ntainer Creating SolrCore 'testschemaapi_shard1_replica2' using configurati= on from collection testschemaapi [junit4] 2> 2839796 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreCo= ntainer Creating SolrCore 'testschemaapi_shard1_replica1' using configurati= on from collection testschemaapi [junit4] 2> 2839796 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.= a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.= a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2839796 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.= a.s.c.SolrCore [[testschemaapi_shard1_replica1] ] Opening new SolrCore at [= C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\= test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-= 001\node2\testschemaapi_shard1_replica1], dataDir=3D[C:\Users\jenkins\works= pace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.sche= ma.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node2\.\testschema= api_shard1_replica1\data\] [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.= a.s.c.SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [= C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\= test\J1\temp\solr.schema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-= 001\node1\testschemaapi_shard1_replica2], dataDir=3D[C:\Users\jenkins\works= pace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.sche= ma.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\.\testschema= api_shard1_replica2\data\] [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.= a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_r= eplica2 [junit4] 2> 2839796 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.= a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_r= eplica1 [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.= a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\wor= kspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.sc= hema.TestManagedSchemaAPI_8D1D4826B214854E-001\tempDir-001\node1\.\testsche= maapi_shard1_replica2\data\snapshot_metadata [junit4] 2> 2839796 INFO (qtp1902620818-46225) [n:127.0.0.1:64606_sol= r c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.= a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file... [junit4] 2> 2839796 INFO (qtp1801023945-46232) [n:127.0.0.1:64607_sol= r c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.= a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\wor= kspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\ [...truncated too long message...] rIsRemovedAfterTimeout-seed#[BED70CE32A0F39F3]-EventThread) [n:127.0.0.1:51= 379_solr ] o.a.z.ClientCnxn Error while calling watcher=20 [junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.= apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$8/4= 20568788@28c6114b rejected from org.apache.solr.common.util.ExecutorUtil$MD= CAwareThreadPoolExecutor@2cf9908b[Shutting down, pool size =3D 1, active th= reads =3D 1, queued tasks =3D 0, completed tasks =3D 14] [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.= rejectedExecution(ThreadPoolExecutor.java:2047) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.reject(Threa= dPoolExecutor.java:823) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.execute(Thre= adPoolExecutor.java:1369) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.execute(ExecutorUtil.java:214) [junit4] 2> =09at java.util.concurrent.AbstractExecutorService.submit(= AbstractExecutorService.java:112) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$3.process(= SolrZkClient.java:266) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:522) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:498) [junit4] 2> 213943 ERROR (TEST-TestCollectionStateWatchers.testWaitFor= StateWatcherIsRetainedOnPredicateFailure-seed#[BED70CE32A0F39F3]-EventThrea= d) [n:127.0.0.1:50630_solr ] o.a.z.ClientCnxn Error while calling watche= r=20 [junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.= apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$8/4= 20568788@26d6619d rejected from org.apache.solr.common.util.ExecutorUtil$MD= CAwareThreadPoolExecutor@63d4eb23[Shutting down, pool size =3D 1, active th= reads =3D 1, queued tasks =3D 0, completed tasks =3D 30] [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.= rejectedExecution(ThreadPoolExecutor.java:2047) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.reject(Threa= dPoolExecutor.java:823) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.execute(Thre= adPoolExecutor.java:1369) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor.execute(ExecutorUtil.java:214) [junit4] 2> =09at java.util.concurrent.AbstractExecutorService.submit(= AbstractExecutorService.java:112) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$3.process(= SolrZkClient.java:266) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:522) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:498) [junit4] 2> 213941 WARN (zkCallback-502-thread-1-processing-n:127.0.0= .1:50640_solr) [n:127.0.0.1:50640_solr ] o.a.s.c.c.ZkStateReader ZooKeep= er watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessio= n expired for /live_nodes] [junit4] 2> 213943 INFO (zkCallback-483-thread-3-processing-n:127.0.0= .1:50633_solr) [n:127.0.0.1:50633_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (4) -> (1) [junit4] 2> 213944 INFO (jetty-launcher-465-thread-2) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7162320a{/solr,null,= UNAVAILABLE} [junit4] 2> 213945 INFO (zkCallback-483-thread-3-processing-n:127.0.0= .1:50633_solr) [n:127.0.0.1:50633_solr ] o.a.s.c.c.ZkStateReader A live = node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged pat= h:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 213946 WARN (zkCallback-483-thread-3-processing-n:127.0.0= .1:50633_solr) [n:127.0.0.1:50633_solr ] o.a.s.c.c.ZkStateReader ZooKeep= er watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessio= n expired for /live_nodes] [junit4] 2> 213947 INFO (jetty-launcher-465-thread-4) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@48777a30{/solr,null,= UNAVAILABLE} [junit4] 2> 215441 WARN (zkCallback-508-thread-1-processing-n:127.0.0= .1:50630_solr) [n:127.0.0.1:50630_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> 215442 INFO (jetty-launcher-465-thread-3) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40b0ed29{/solr,null,= UNAVAILABLE} [junit4] 2> 215443 WARN (zkCallback-516-thread-1-processing-n:127.0.0= .1:51379_solr) [n:127.0.0.1:51379_solr ] o.a.s.c.c.ZkStateReader ZooKeep= er watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessio= n expired for /live_nodes] [junit4] 2> 215444 INFO (jetty-launcher-465-thread-1) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f752ffc{/solr,null,= UNAVAILABLE} [junit4] 2> 215445 INFO (SUITE-TestCollectionStateWatchers-seed#[BED7= 0CE32A0F39F3]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:5= 0626 50626 [junit4] 2> 215447 INFO (Thread-558) [ ] o.a.s.c.ZkTestServer conn= ecting to 127.0.0.1:50626 50626 [junit4] 2> 215449 WARN (Thread-558) [ ] o.a.s.c.ZkTestServer Watc= h limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =098=09/solr/aliases.json [junit4] 2> =098=09/solr/clusterprops.json [junit4] 2> =097=09/solr/security.json [junit4] 2> =097=09/solr/configs/config [junit4] 2> =093=09/solr/collections/stateformat1/state.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =0916=09/solr/collections/falsepredicate/state.json [junit4] 2> =0912=09/solr/collections/waitforstate/state.json [junit4] 2> =098=09/solr/clusterstate.json [junit4] 2> =097=09/solr/collections/testcollection/state.json [junit4] 2> =093=09/solr/collections/stateformat1/state.json [junit4] 2> =092=09/solr/collections/currentstate/state.json [junit4] 2> =092=09/solr/overseer_elect/election/96517225526788106-127= .0.0.1:50630_solr-n_0000000001 [junit4] 2> =092=09/solr/collections/delayed/state.json [junit4] 2> =092=09/solr/overseer_elect/election/96517225526788107-127= .0.0.1:50647_solr-n_0000000000 [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =0976=09/solr/overseer/queue [junit4] 2> =0936=09/solr/overseer/collection-queue-work [junit4] 2> =0914=09/solr/overseer/queue-work [junit4] 2> =098=09/solr/collections [junit4] 2> =095=09/solr/live_nodes [junit4] 2>=20 [junit4] 2> 215449 INFO (SUITE-TestCollectionStateWatchers-seed#[BED7= 0CE32A0F39F3]-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-solrj\test\J0\temp\so= lr.common.cloud.TestCollectionStateWatchers_BED70CE32A0F39F3-001 [junit4] 2> Sep 01, 2016 1:20:41 PM 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=3DAsserting(Lucene62), sim=3D= ClassicSimilarity, locale=3Dund, timezone=3DAsia/Pontianak [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (= 64-bit)/cpus=3D3,threads=3D1,free=3D128819072,total=3D373293056 [junit4] 2> NOTE: All tests run in this JVM: [TestCollectionAdminReque= st, StreamingTest, SolrExampleEmbeddedTest, SolrExampleStreamingTest, Cloud= SolrClientTest, TestJavaBinCodec, TermsResponseTest, TestXMLEscaping, TestT= oleratedUpdateError, JdbcDriverTest, NoOpResponseParserTest, LBHttpSolrClie= ntBuilderTest, SchemaTest, FieldAnalysisResponseTest, TestRetryUtil, TestCo= reAdmin, ModifiableSolrParamsTest, ConcurrentUpdateSolrClientTest, TestSpel= lCheckResponse, SolrExampleXMLTest, CommonAdminParamsTest, JDBCStreamTest, = StreamExpressionToExpessionTest, StreamExpressionParserTest, ConcatOperatio= nTest, OperationsTest, SolrPingTest, TestConfigSetAdminRequest, TestDelegat= ionTokenRequest, TestDelegationTokenResponse, TestCollectionStateWatchers] [junit4] Completed [85/85 (1!)] on J0 in 57.00s, 9 tests, 1 error <<< FA= ILURES! [...truncated 59471 lines...] ------=_Part_106_1080895637.1472737348674 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_106_1080895637.1472737348674--