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 9A7C2200B77 for ; Sat, 3 Sep 2016 21:00:34 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 8DDB0160ABB; Sat, 3 Sep 2016 19:00:34 +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 3495D160AAC for ; Sat, 3 Sep 2016 21:00:32 +0200 (CEST) Received: (qmail 33264 invoked by uid 500); 3 Sep 2016 19:00:30 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 33250 invoked by uid 99); 3 Sep 2016 19:00:30 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 03 Sep 2016 19:00:30 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 81C84C0118 for ; Sat, 3 Sep 2016 19:00:29 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2 X-Spam-Level: ** X-Spam-Status: No, score=2 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id reO1IIhKRwGS for ; Sat, 3 Sep 2016 19:00:13 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTPS id 4C1095FB37 for ; Sat, 3 Sep 2016 19:00:13 +0000 (UTC) Received: from serv1 (localhost.localdomain [127.0.0.1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id C22D24C0557 for ; Sat, 3 Sep 2016 19:00:12 +0000 (UTC) Date: Sat, 3 Sep 2016 18:58:55 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1153947126.183.1472929212796.JavaMail.jenkins@serv1> In-Reply-To: <1584375765.179.1472914880065.JavaMail.jenkins@serv1> References: <1584375765.179.1472914880065.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-5.5-Linux (32bit/jdk1.7.0_80) - Build # 375 - Still Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_182_1062132147.1472929212750" X-Jenkins-Job: Lucene-Solr-5.5-Linux X-Jenkins-Result: UNSTABLE archived-at: Sat, 03 Sep 2016 19:00:34 -0000 ------=_Part_182_1062132147.1472929212750 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/375/ Java: 32bit/jdk1.7.0_80 -server -XX:+UseParallelGC 1 tests failed. FAILED: org.apache.solr.schema.TestManagedSchemaAPI.test Error Message: Error from server at http://127.0.0.1:42499/solr/testschemaapi_shard1_repli= ca2: ERROR: [doc=3D2] unknown field 'myNewField1' Stack Trace: org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error fro= m server at http://127.0.0.1:42499/solr/testschemaapi_shard1_replica2: ERRO= R: [doc=3D2] unknown field 'myNewField1' =09at __randomizedtesting.SeedInfo.seed([49B94BCC331D285B:C1ED74169DE145A3]= :0) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudS= olrClient.java:653) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSo= lrClient.java:1002) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnS= taleState(CloudSolrClient.java:891) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrCl= ient.java:827) =09at org.apache.solr.schema.TestManagedSchemaAPI.testAddFieldAndDocument(T= estManagedSchemaAPI.java:101) =09at org.apache.solr.schema.TestManagedSchemaAPI.test(TestManagedSchemaAPI= .java:69) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:606) =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) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrExcep= tion: Error from server at http://127.0.0.1:42499/solr/testschemaapi_shard1= _replica2: ERROR: [doc=3D2] unknown field 'myNewField1' =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:577) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:241) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:230) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSo= lrClient.java:372) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolr= Client.java:325) =09at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrCli= ent.java:628) =09at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrCli= ent.java:625) =09at java.util.concurrent.FutureTask.run(FutureTask.java:262) =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1= .run(ExecutorUtil.java:231) =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.= java:1145) =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor= .java:615) =09... 1 more Build Log: [...truncated 12209 lines...] [junit4] Suite: org.apache.solr.schema.TestManagedSchemaAPI [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-= Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchemaAPI_49= B94BCC331D285B-001/init-core-data-001 [junit4] 2> 1963270 INFO (SUITE-TestManagedSchemaAPI-seed#[49B94BCC33= 1D285B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clie= ntAuth (false) [junit4] 2> 1963272 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 1963273 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1963274 INFO (Thread-6236) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1963274 INFO (Thread-6236) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1963374 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.ZkTestServer start zk server on port:42989 [junit4] 2> 1963374 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvi= der [junit4] 2> 1963374 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conne= ct to ZooKeeper [junit4] 2> 1963375 INFO (zkCallback-24238-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1= 60e48 name:ZooKeeperConnection Watcher:127.0.0.1:42989 got event WatchedEve= nt state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963376 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooK= eeper [junit4] 2> 1963376 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963376 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml [junit4] 2> 1963378 INFO (jetty-launcher-24237-thread-2) [ ] o.e.j= .s.Server jetty-9.2.13.v20150730 [junit4] 2> 1963378 INFO (jetty-launcher-24237-thread-1) [ ] o.e.j= .s.Server jetty-9.2.13.v20150730 [junit4] 2> 1963379 INFO (jetty-launcher-24237-thread-2) [ ] o.e.j= .s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c00fef{/solr,null= ,AVAILABLE} [junit4] 2> 1963379 INFO (jetty-launcher-24237-thread-1) [ ] o.e.j= .s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1878567{/solr,nul= l,AVAILABLE} [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-1) [ ] o.e.j= .s.ServerConnector Started ServerConnector@629edf{HTTP/1.1}{127.0.0.1:38484= } [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-1) [ ] o.e.j= .s.Server Started @1964882ms [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-2) [ ] o.e.j= .s.ServerConnector Started ServerConnector@d745fe{HTTP/1.1}{127.0.0.1:42499= } [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D3= 8484} [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-2) [ ] o.e.j= .s.Server Started @1964882ms [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D4= 2499} [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClass= Loader@149a2c8 [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/= workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.sche= ma.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1' [junit4] 2> 1963382 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClass= Loader@149a2c8 [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/= workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.sche= ma.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2' [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system= property or JNDI) [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system= property or JNDI) [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1963383 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1963389 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1963390 INFO (zkCallback-24239-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9= 0155a name:ZooKeeperConnection Watcher:127.0.0.1:42989/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963390 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1963390 INFO (zkCallback-24240-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1= 4ea843 name:ZooKeeperConnection Watcher:127.0.0.1:42989/solr got event Watc= hedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963390 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1963390 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963390 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963390 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1963390 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1963401 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/= workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.sche= ma.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/. [junit4] 2> 1963401 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.CoreContainer New CoreContainer 18411709 [junit4] 2> 1963401 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.CoreContainer Loading cores into CoreContainer [instanceDir=3D/home/jenk= ins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.= schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1] [junit4] 2> 1963410 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/= workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.sche= ma.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/. [junit4] 2> 1963410 WARN (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Sol= r-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchema= API_49B94BCC331D285B-001/tempDir-001/node1/lib to classpath: /home/jenkins/= workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.sche= ma.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/lib [junit4] 2> 1963410 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.CoreContainer New CoreContainer 6621928 [junit4] 2> 1963410 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.CoreContainer Loading cores into CoreContainer [instanceDir=3D/home/jenk= ins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.= schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2] [junit4] 2> 1963410 INFO (jetty-launcher-24237-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,useRetries : false,connectionsEvictorSleepDel= ay : 5000,maxConnectionIdleTime : 40000, [junit4] 2> 1963411 WARN (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Sol= r-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSchema= API_49B94BCC331D285B-001/tempDir-001/node2/lib to classpath: /home/jenkins/= workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.sche= ma.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/lib [junit4] 2> 1963411 INFO (jetty-launcher-24237-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,useRetries : false,connectionsEvictorSleepDel= ay : 5000,maxConnectionIdleTime : 40000, [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: = socketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: = socketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFa= ctory)] [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.ZkContainer Zookeeper client=3D127.0.0.1:42989/solr [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.ZkController zkHost includes chroot [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFa= ctory)] [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.ZkContainer Zookeeper client=3D127.0.0.1:42989/solr [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.ZkController zkHost includes chroot [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1963412 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1963413 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1963413 INFO (zkCallback-24245-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1= ad4472 name:ZooKeeperConnection Watcher:127.0.0.1:42989 got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963413 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1963413 INFO (zkCallback-24246-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1= 445eab name:ZooKeeperConnection Watcher:127.0.0.1:42989 got event WatchedEv= ent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963413 INFO (jetty-launcher-24237-thread-2) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963413 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1963414 INFO (jetty-launcher-24237-thread-1) [ ] o.a.s= .c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963414 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.ap= ache.solr.cloud.ZkController$6@13a6fd7 [junit4] 2> 1963414 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1963414 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.ap= ache.solr.cloud.ZkController$6@1ec8267 [junit4] 2> 1963414 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect = to ZooKeeper [junit4] 2> 1963414 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_solr ] o.a.s.c.c.ConnectionManager = Watcher org.apache.solr.common.cloud.ConnectionManager@163ae72 name:ZooKeep= erConnection Watcher:127.0.0.1:42989/solr got event WatchedEvent state:Sync= Connected type:None path:null path:null type:None [junit4] 2> 1963415 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1963415 INFO (zkCallback-24248-thread-1-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.c.ConnectionManager = Watcher org.apache.solr.common.cloud.ConnectionManager@116a06b name:ZooKeep= erConnection Watcher:127.0.0.1:42989/solr got event WatchedEvent state:Sync= Connected type:None path:null path:null type:None [junit4] 2> 1963415 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeep= er [junit4] 2> 1963415 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1963416 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queu= e-work [junit4] 2> 1963416 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queu= e-work [junit4] 2> 1963417 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= running [junit4] 2> 1963418 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= running [junit4] 2> 1963419 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= completed [junit4] 2> 1963419 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= completed [junit4] 2> 1963419 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= failure [junit4] 2> 1963419 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-= failure [junit4] 2> 1963420 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1963420 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1963420 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1963420 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1963421 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1963421 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1963427 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1963427 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1963428 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1963428 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1963428 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/l= ive_nodes/127.0.0.1:38484_solr [junit4] 2> 1963428 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/l= ive_nodes/127.0.0.1:42499_solr [junit4] 2> 1963428 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:384= 84_solr [junit4] 2> 1963428 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:424= 99_solr [junit4] 2> 1963430 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1963430 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1963430 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1963430 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1963431 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1963431 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1963431 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.LeaderElector Joined leadership election with path= : /overseer_elect/election/96529840341319686-127.0.0.1:38484_solr-n_0000000= 000 [junit4] 2> 1963431 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.LeaderElector Joined leadership election with path= : /overseer_elect/election/96529840341319685-127.0.0.1:42499_solr-n_0000000= 001 [junit4] 2> 1963431 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.OverseerElectionContext I am going to be the leade= r 127.0.0.1:38484_solr [junit4] 2> 1963431 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1963432 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/electi= on/96529840341319686-127.0.0.1:38484_solr-n_0000000000 to know if I could b= e the leader [junit4] 2> 1963432 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 1963432 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.Overseer Overseer (id=3D96529840341319686-127.0.0.= 1:38484_solr-n_0000000000) starting [junit4] 2> 1963432 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1963434 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting Oversee= rAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D10000 autoRep= licaFailoverWaitAfterExpiration=3D30000 autoReplicaFailoverBadNodeExpiratio= n=3D60000 [junit4] 2> 1963435 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKee= per...=20 [junit4] 2> 1963435 INFO (OverseerCollectionConfigSetProcessor-965298= 40341319686-127.0.0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ]= o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1963442 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.Overseer= Starting to work on the main queue [junit4] 2> 1963446 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skippin= g setup for authorization module. [junit4] 2> 1963446 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1963446 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions= underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-c= ore/test/J1/temp/solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/temp= Dir-001/node2/. [junit4] 2> 1963447 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 1963447 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/jenkins/worksp= ace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1963448 INFO (jetty-launcher-24237-thread-2) [n:127.0.0.1= :42499_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1963471 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skippin= g setup for authorization module. [junit4] 2> 1963471 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1963471 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions= underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-c= ore/test/J1/temp/solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/temp= Dir-001/node1/. [junit4] 2> 1963471 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 1963472 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/jenkins/worksp= ace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1963472 INFO (jetty-launcher-24237-thread-1) [n:127.0.0.1= :38484_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1963472 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvi= der [junit4] 2> 1963472 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conne= ct to ZooKeeper [junit4] 2> 1963473 INFO (zkCallback-24249-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4= 0863 name:ZooKeeperConnection Watcher:127.0.0.1:42989 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963473 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooK= eeper [junit4] 2> 1963473 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963474 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvi= der [junit4] 2> 1963474 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conne= ct to ZooKeeper [junit4] 2> 1963474 INFO (zkCallback-24251-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1= a529d8 name:ZooKeeperConnection Watcher:127.0.0.1:42989/solr got event Watc= hedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963474 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooK= eeper [junit4] 2> 1963475 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963475 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrcon= fig.xml [junit4] 2> 1963476 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/managed= -schema [junit4] 2> 1963476 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvi= der [junit4] 2> 1963476 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to conne= ct to ZooKeeper [junit4] 2> 1963478 INFO (zkCallback-24252-thread-1) [ ] o.a.s.c.c= .ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1= 015eb name:ZooKeeperConnection Watcher:127.0.0.1:42989/solr got event Watch= edEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1963478 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooK= eeper [junit4] 2> 1963478 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1963478 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from Zoo= Keeper...=20 [junit4] 2> 1963481 INFO (qtp21632847-96711) [n:127.0.0.1:38484_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with pa= rams version=3D2&name=3Dtestschemaapi&action=3DCREATE&numShards=3D1&replica= tionFactor=3D2&wt=3Djavabin&collection.configName=3Dconf1 and sendToOCPQueu= e=3Dtrue [junit4] 2> 1963482 INFO (OverseerCollectionConfigSetProcessor-965298= 40341319686-127.0.0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_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> 1963482 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_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> 1963482 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.OverseerC= ollectionMessageHandler Create collection testschemaapi [junit4] 2> 1963482 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.OverseerC= ollectionMessageHandler creating collections conf node /collections/testsch= emaapi=20 [junit4] 2> 1963482 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.c.SolrZkC= lient makePath: /collections/testschemaapi [junit4] 2> 1963483 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_solr ] o.a.s.c.c.ZkStateReader A co= llections change: [WatchedEvent state:SyncConnected type:NodeChildrenChange= d path:/collections], has occurred - updating... [junit4] 2> 1963483 INFO (zkCallback-24252-thread-1) [ ] o.a.s.c.c= .ZkStateReader A collections change: [WatchedEvent state:SyncConnected type= :NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1963483 INFO (zkCallback-24248-thread-1-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.c.ZkStateReader A co= llections change: [WatchedEvent state:SyncConnected type:NodeChildrenChange= d path:/collections], has occurred - updating... [junit4] 2> 1963484 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_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> 1963484 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.Cluste= rStateMutator building a new cName: testschemaapi [junit4] 2> 1963484 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.ZkStat= eWriter going to create_collection /collections/testschemaapi/state.json [junit4] 2> 1963584 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.OverseerC= ollectionMessageHandler Creating SolrCores for new collection testschemaapi= , shardNames [shard1] , replicationFactor : 2 [junit4] 2> 1963584 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.OverseerC= ollectionMessageHandler Creating core testschemaapi_shard1_replica1 as part= of shard shard1 of collection testschemaapi on 127.0.0.1:38484_solr [junit4] 2> 1963586 INFO (OverseerThreadFactory-11689-thread-1-proces= sing-n:127.0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.OverseerC= ollectionMessageHandler Creating core testschemaapi_shard1_replica2 as part= of shard shard1 of collection testschemaapi on 127.0.0.1:42499_solr [junit4] 2> 1963588 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = ] o.a.s.h.a.CoreAdminOperation core create command name=3Dtestschemaapi_= shard1_replica1&qt=3D/admin/cores&version=3D2&action=3DCREATE&numShards=3D1= &shard=3Dshard1&collection=3Dtestschemaapi&wt=3Djavabin&collection.configNa= me=3Dconf1 [junit4] 2> 1963588 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = ] o.a.s.h.a.CoreAdminOperation core create command name=3Dtestschemaapi_= shard1_replica2&qt=3D/admin/cores&version=3D2&action=3DCREATE&numShards=3D1= &shard=3Dshard1&collection=3Dtestschemaapi&wt=3Djavabin&collection.configNa= me=3Dconf1 [junit4] 2> 1963588 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=3D= configsetprops.json, collection=3Dtestschemaapi, numShards=3D1, shard=3Dsha= rd1, config=3Dsolrconfig.xml, transient=3Dfalse, collection.configName=3Dco= nf1, name=3Dtestschemaapi_shard1_replica1, loadOnStartup=3Dtrue, dataDir=3D= data/, schema=3Dschema.xml} [junit4] 2> 1963588 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=3Dsolrconfig.xm= l, collection=3Dtestschemaapi, numShards=3D1, schema=3Dschema.xml, dataDir= =3Ddata/, transient=3Dfalse, collection.configName=3Dconf1, loadOnStartup= =3Dtrue, name=3Dtestschemaapi_shard1_replica2, shard=3Dshard1, configSetPro= perties=3Dconfigsetprops.json} [junit4] 2> 1963589 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkContro= ller publishing state=3Ddown [junit4] 2> 1963589 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller publishing state=3Ddown [junit4] 2> 1963589 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkContro= ller Registering watch for collection testschemaapi [junit4] 2> 1963590 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStat= eReader addZkWatch [testschemaapi] [junit4] 2> 1963590 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller Registering watch for collection testschemaapi [junit4] 2> 1963590 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader addZkWatch [testschemaapi] [junit4] 2> 1963590 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.Overseer= processMessage: queueSize: 2, message =3D { [junit4] 2> "node_name":"127.0.0.1:42499_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:42499/solr", [junit4] 2> "operation":"state", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 1963590 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStat= eReader Add data for [testschemaapi] ver [0] [junit4] 2> 1963590 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader Add data for [testschemaapi] ver [0] [junit4] 2> 1963590 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkContro= ller look for our core node name [junit4] 2> 1963590 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller look for our core node name [junit4] 2> 1963590 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D1 message=3D{ [junit4] 2> "node_name":"127.0.0.1:42499_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:42499/solr", [junit4] 2> "operation":"state", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "roles":null} [junit4] 2> 1963591 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "node_name":"127.0.0.1:38484_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:38484/solr", [junit4] 2> "operation":"state", [junit4] 2> "core":"testschemaapi_shard1_replica1", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 1963591 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D1 message=3D{ [junit4] 2> "node_name":"127.0.0.1:38484_solr", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:38484/solr", [junit4] 2> "operation":"state", [junit4] 2> "core":"testschemaapi_shard1_replica1", [junit4] 2> "roles":null} [junit4] 2> 1963692 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.ZkStat= eWriter going to update_collection /collections/testschemaapi/state.json ve= rsion: 0 [junit4] 2> 1963693 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_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> 1963693 INFO (zkCallback-24248-thread-1-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_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> 1963693 INFO (zkCallback-24248-thread-1-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [0] to [1] [junit4] 2> 1963693 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [0] to [1] [junit4] 2> 1964590 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkContro= ller waiting to find shard id in clusterstate for testschemaapi_shard1_repl= ica2 [junit4] 2> 1964590 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller waiting to find shard id in clusterstate for testschemaapi_shard1_repl= ica1 [junit4] 2> 1964590 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkContro= ller Check for collection zkNode:testschemaapi [junit4] 2> 1964590 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller Check for collection zkNode:testschemaapi [junit4] 2> 1964591 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkContro= ller Collection zkNode exists [junit4] 2> 1964591 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller Collection zkNode exists [junit4] 2> 1964591 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStat= eReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 1964591 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 1964591 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStat= eReader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specifie= d config exists in ZooKeeper [junit4] 2> 1964591 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrReso= urceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/L= ucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestMana= gedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/testschemaapi_shard1_re= plica2' [junit4] 2> 1964591 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specifie= d config exists in ZooKeeper [junit4] 2> 1964591 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrReso= urceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/L= ucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestMana= gedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/testschemaapi_shard1_re= plica1' [junit4] 2> 1964591 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrReso= urceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1964592 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrReso= urceLoader solr home defaulted to 'solr/' (could not find system property o= r JNDI) [junit4] 2> 1964592 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrReso= urceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1964592 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrReso= urceLoader solr home defaulted to 'solr/' (could not find system property o= r JNDI) [junit4] 2> 1964593 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config l= oaded config solrconfig.xml with version 0=20 [junit4] 2> 1964593 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config l= oaded config solrconfig.xml with version 0=20 [junit4] 2> 1964596 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConf= ig current version of requestparams : -1 [junit4] 2> 1964596 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConf= ig current version of requestparams : -1 [junit4] 2> 1964603 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConf= ig Using Lucene MatchVersion: 5.5.3 [junit4] 2> 1964607 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConf= ig Using Lucene MatchVersion: 5.5.3 [junit4] 2> 1964618 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConf= ig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1964622 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSch= ema [testschemaapi_shard1_replica2] Schema name=3Dminimal [junit4] 2> 1964624 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConf= ig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1964627 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSch= ema [testschemaapi_shard1_replica1] Schema name=3Dminimal [junit4] 2> 1964630 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSch= ema unique key field: id [junit4] 2> 1964631 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ConfigSe= tProperties Did not find ConfigSet properties, assuming default properties:= Can't find resource 'configsetprops.json' in classpath or '/configs/conf1'= , cwd=3D/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/= test/J1 [junit4] 2> 1964631 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreCont= ainer Creating SolrCore 'testschemaapi_shard1_replica2' using configuration= from collection testschemaapi [junit4] 2> 1964632 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1964632 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [/h= ome/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/te= mp/solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/= testschemaapi_shard1_replica2], dataDir=3D[/home/jenkins/workspace/Lucene-S= olr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSche= maAPI_49B94BCC331D285B-001/tempDir-001/node2/./testschemaapi_shard1_replica= 2/data/] [junit4] 2> 1964632 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_rep= lica2 [junit4] 2> 1964632 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestM= anagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/./testschemaapi_shar= d1_replica2/data [junit4] 2> 1964633 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/w= orkspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schem= a.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/./testschemaa= pi_shard1_replica2/data/index/ [junit4] 2> 1964633 WARN (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore [testschemaapi_shard1_replica2] Solr index directory '/home/je= nkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/sol= r.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/./test= schemaapi_shard1_replica2/data/index' doesn't exist. Creating new index... [junit4] 2> 1964633 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestM= anagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/./testschemaapi_shar= d1_replica2/data/index [junit4] 2> 1964633 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@1706959 = lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@11476b3),se= gFN=3Dsegments_1,generation=3D1} [junit4] 2> 1964634 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1964634 INFO (OldIndexDirectoryCleanupThreadForCore-tests= chemaapi_shard1_replica2) [n:127.0.0.1:42499_solr c:testschemaapi s:shard1 = r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Looking for = old index directories to cleanup for core testschemaapi_shard1_replica2 in = /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/= temp/solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node= 2/./testschemaapi_shard1_replica2/data/ [junit4] 2> 1964634 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSch= ema unique key field: id [junit4] 2> 1964634 WARN (OldIndexDirectoryCleanupThreadForCore-tests= chemaapi_shard1_replica2) [n:127.0.0.1:42499_solr c:testschemaapi s:shard1 = r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.DirectoryFactory /hom= e/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp= /solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node2/./= testschemaapi_shard1_replica2/data/ does not point to a valid data director= y; skipping clean-up of old index directories. [junit4] 2> 1964635 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ConfigSe= tProperties Did not find ConfigSet properties, assuming default properties:= Can't find resource 'configsetprops.json' in classpath or '/configs/conf1'= , cwd=3D/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/= test/J1 [junit4] 2> 1964635 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreCont= ainer Creating SolrCore 'testschemaapi_shard1_replica1' using configuration= from collection testschemaapi [junit4] 2> 1964635 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore no updateRequestProcessorChain defined as default, creating im= plicit default [junit4] 2> 1964635 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1964635 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore [[testschemaapi_shard1_replica1] ] Opening new SolrCore at [/h= ome/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/te= mp/solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/= testschemaapi_shard1_replica1], dataDir=3D[/home/jenkins/workspace/Lucene-S= olr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestManagedSche= maAPI_49B94BCC331D285B-001/tempDir-001/node1/./testschemaapi_shard1_replica= 1/data/] [junit4] 2> 1964635 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_rep= lica1 [junit4] 2> 1964636 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestM= anagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/./testschemaapi_shar= d1_replica1/data [junit4] 2> 1964636 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/w= orkspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schem= a.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/./testschemaa= pi_shard1_replica1/data/index/ [junit4] 2> 1964636 WARN (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore [testschemaapi_shard1_replica1] Solr index directory '/home/je= nkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/sol= r.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/./test= schemaapi_shard1_replica1/data/index' doesn't exist. Creating new index... [junit4] 2> 1964636 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspac= e/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema.TestM= anagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/./testschemaapi_shar= d1_replica1/data/index [junit4] 2> 1964636 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@15f6188 = lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@fc6806),seg= FN=3Dsegments_1,generation=3D1} [junit4] 2> 1964636 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1964637 INFO (OldIndexDirectoryCleanupThreadForCore-tests= chemaapi_shard1_replica1) [n:127.0.0.1:38484_solr c:testschemaapi s:shard1 = r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Looking for = old index directories to cleanup for core testschemaapi_shard1_replica1 in = /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/= temp/solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node= 1/./testschemaapi_shard1_replica1/data/ [junit4] 2> 1964637 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964637 WARN (OldIndexDirectoryCleanupThreadForCore-tests= chemaapi_shard1_replica1) [n:127.0.0.1:38484_solr c:testschemaapi s:shard1 = r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.DirectoryFactory /hom= e/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp= /solr.schema.TestManagedSchemaAPI_49B94BCC331D285B-001/tempDir-001/node1/./= testschemaapi_shard1_replica1/data/ does not point to a valid data director= y; skipping clean-up of old index directories. [junit4] 2> 1964637 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore no updateRequestProcessorChain defined as default, creating im= plicit default [junit4] 2> 1964638 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964638 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964638 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964639 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964639 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964640 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964640 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.RequestHandlers Registered paths: /get,/update/json,/config,/admin/logg= ing,/schema,/update/csv,/update,/admin/properties,/select,/update/json/docs= ,/admin/mbeans,/admin/file,/admin/segments,/replication,/admin/threads,/adm= in/plugins,/admin/ping,/admin/system,/admin/luke [junit4] 2> 1964641 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 1964641 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.L= ocalStatsCache [junit4] 2> 1964642 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Up= dateLog [junit4] 2> 1964642 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLU= SH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1964642 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.RequestHandlers Registered paths: /get,/update/json,/config,/admin/logg= ing,/schema,/update/csv,/update,/admin/properties,/select,/update/json/docs= ,/admin/mbeans,/admin/file,/admin/segments,/replication,/admin/threads,/adm= in/plugins,/admin/ping,/admin/system,/admin/luke [junit4] 2> 1964642 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1964642 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1964642 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.L= ocalStatsCache [junit4] 2> 1964643 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@1706959 = lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@11476b3),se= gFN=3Dsegments_1,generation=3D1} [junit4] 2> 1964643 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1964643 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Up= dateLog [junit4] 2> 1964643 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLU= SH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1964643 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.s.SolrIndexSearcher Opening Searcher@18048e5[testschemaapi_shard1_replica= 2] main [junit4] 2> 1964643 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.c.ZkStateReader Load collection config from: [/collections/testschemaap= i] [junit4] 2> 1964643 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.c.ZkStateReader path=3D[/collections/testschemaapi] [configName]=3D[con= f1] specified config exists in ZooKeeper [junit4] 2> 1964643 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestM= anager with znodeBase: /configs/conf1 [junit4] 2> 1964643 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1964644 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /c= onfigs/conf1 [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorag= eIO:path=3D/configs/conf1 [junit4] 2> 1964644 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@15f6188 = lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@fc6806),seg= FN=3Dsegments_1,generation=3D1} [junit4] 2> 1964644 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_man= aged.json [junit4] 2> 1964644 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.s.SolrIndexSearcher Opening Searcher@15930fb[testschemaapi_shard1_replica= 1] main [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using Zoo= KeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1964644 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.c.ZkStateReader Load collection config from: [/collections/testschemaap= i] [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at = /configs/conf1/managed-schema [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /confi= gs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica2:422130= 3514914800 [junit4] 2> 1964644 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.c.ZkStateReader path=3D[/collections/testschemaapi] [configName]=3D[con= f1] specified config exists in ZooKeeper [junit4] 2> 1964644 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1964645 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestM= anager with znodeBase: /configs/conf1 [junit4] 2> 1964645 INFO (searcherExecutor-11694-thread-1-processing-= n:127.0.0.1:42499_solr r:core_node1 s:shard1 c:testschemaapi x:testschemaap= i_shard1_replica2) [n:127.0.0.1:42499_solr c:testschemaapi s:shard1 r:core_= node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shar= d1_replica2] Registered new searcher Searcher@18048e5[testschemaapi_shard1_= replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1964645 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /c= onfigs/conf1 [junit4] 2> 1964645 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1964645 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1964645 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buc= kets from index [junit4] 2> 1964645 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorag= eIO:path=3D/configs/conf1 [junit4] 2> 1964645 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.VersionInfo No terms found for _version_, cannot seed version bucket hi= ghest value from index [junit4] 2> 1964646 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.UpdateLog Could not find max version in index or recent updates, using = new clock 1544477446899761152 [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_man= aged.json [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using Zoo= KeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at = /configs/conf1/managed-schema [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /confi= gs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica1:422130= 3518125503 [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1964646 INFO (searcherExecutor-11695-thread-1-processing-= n:127.0.0.1:38484_solr r:core_node2 s:shard1 c:testschemaapi x:testschemaap= i_shard1_replica1) [n:127.0.0.1:38484_solr c:testschemaapi s:shard1 r:core_= node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shar= d1_replica1] Registered new searcher Searcher@15930fb[testschemaapi_shard1_= replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buc= kets from index [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.VersionInfo No terms found for _version_, cannot seed version bucket hi= ghest value from index [junit4] 2> 1964646 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.UpdateLog Could not find max version in index or recent updates, using = new clock 1544477446899761152 [junit4] 2> 1964647 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 15444= 77446899761152 [junit4] 2> 1964647 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1964648 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.CoreContainer registering core: testschemaapi_shard1_replica2 [junit4] 2> 1964648 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController Register replica - core:testschemaapi_shard1_replica2 addr= ess:http://127.0.0.1:42499/solr collection:testschemaapi shard:shard1 [junit4] 2> 1964648 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 15444= 77446899761152 [junit4] 2> 1964648 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1= /election [junit4] 2> 1964648 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1964648 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.CoreContainer registering core: testschemaapi_shard1_replica1 [junit4] 2> 1964649 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController Register replica - core:testschemaapi_shard1_replica1 addr= ess:http://127.0.0.1:38484/solr collection:testschemaapi shard:shard1 [junit4] 2> 1964649 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1= /election [junit4] 2> 1964649 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.LeaderElector Joined leadership election with path: /collections/testsc= hemaapi/leader_elect/shard1/election/96529840341319685-core_node1-n_0000000= 000 [junit4] 2> 1964650 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ShardLeaderElectionContext Running the leader process for shard=3Dshard= 1 and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 1964650 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.LeaderElector Joined leadership election with path: /collections/testsc= hemaapi/leader_elect/shard1/election/96529840341319686-core_node2-n_0000000= 001 [junit4] 2> 1964650 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1964650 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1964650 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SyncStrategy Sync replicas to http://127.0.0.1:42499/solr/testschemaapi= _shard1_replica2/ [junit4] 2> 1964650 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.PeerSync PeerSync: core=3Dtestschemaapi_shard1_replica2 url=3Dhttp://12= 7.0.0.1:42499/solr START replicas=3D[http://127.0.0.1:38484/solr/testschema= api_shard1_replica1/] nUpdates=3D100 [junit4] 2> 1964650 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.LeaderElector Watching path /collections/testschemaapi/leader_elect/sha= rd1/election/96529840341319685-core_node1-n_0000000000 to know if I could b= e the leader [junit4] 2> 1964650 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi"} current state version: 0 [junit4] 2> 1964651 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.u.PeerSync PeerSync: core=3Dtestschemaapi_shard1_replica2 url=3Dhttp://12= 7.0.0.1:42499/solr DONE. We have no versions. sync failed. [junit4] 2> 1964651 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the = next candidate [junit4] 2> 1964651 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we= can't sync in that case - we were active before, so become leader anyway [junit4] 2> 1964652 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.c.SolrZkClient makePath: /collections/testschemaapi/leaders/shard1 [junit4] 2> 1964654 INFO (qtp21632847-96715) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.S.Request [testschemaapi_shard1_replica1] webapp=3D/solr path=3D/get p= arams=3D{version=3D2&qt=3D/get&distrib=3Dfalse&getVersions=3D100&wt=3Djavab= in&fingerprint=3Dfalse} status=3D0 QTime=3D1 [junit4] 2> 1964654 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ShardLeaderElectionContextBase Creating leader registration node /colle= ctions/testschemaapi/leaders/shard1/leader after winning as /collections/te= stschemaapi/leader_elect/shard1/election/96529840341319685-core_node1-n_000= 0000000 [junit4] 2> 1964655 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42499/= solr/testschemaapi_shard1_replica2/ shard1 [junit4] 2> 1964655 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:42499/solr", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 1964756 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.ZkStat= eWriter going to update_collection /collections/testschemaapi/state.json ve= rsion: 1 [junit4] 2> 1964757 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_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> 1964757 INFO (zkCallback-24248-thread-2-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_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> 1964761 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [1] to [2] [junit4] 2> 1964761 INFO (zkCallback-24248-thread-2-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [1] to [2] [junit4] 2> 1964806 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController We are http://127.0.0.1:42499/solr/testschemaapi_shard1_re= plica2/ and leader is http://127.0.0.1:42499/solr/testschemaapi_shard1_repl= ica2/ [junit4] 2> 1964806 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController No LogReplay needed for core=3Dtestschemaapi_shard1_replic= a2 baseURL=3Dhttp://127.0.0.1:42499/solr [junit4] 2> 1964806 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1964806 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.c.ZkController publishing state=3Dactive [junit4] 2> 1964807 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.Overseer= processMessage: queueSize: 1, message =3D { [junit4] 2> "node_name":"127.0.0.1:42499_solr", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:42499/solr", [junit4] 2> "operation":"state", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 1964807 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.Replic= aMutator Update state numShards=3D1 message=3D{ [junit4] 2> "node_name":"127.0.0.1:42499_solr", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "base_url":"http://127.0.0.1:42499/solr", [junit4] 2> "operation":"state", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "roles":null} [junit4] 2> 1964807 INFO (qtp11938407-96708) [n:127.0.0.1:42499_solr = c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.= s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{versio= n=3D2&qt=3D/admin/cores&name=3Dtestschemaapi_shard1_replica2&action=3DCREAT= E&numShards=3D1&shard=3Dshard1&collection=3Dtestschemaapi&wt=3Djavabin&coll= ection.configName=3Dconf1} status=3D0 QTime=3D1219 [junit4] 2> 1964908 INFO (OverseerStateUpdate-96529840341319686-127.0= .0.1:38484_solr-n_0000000000) [n:127.0.0.1:38484_solr ] o.a.s.c.o.ZkStat= eWriter going to update_collection /collections/testschemaapi/state.json ve= rsion: 2 [junit4] 2> 1964908 INFO (zkCallback-24248-thread-1-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_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> 1964908 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_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> 1964908 INFO (zkCallback-24248-thread-1-processing-n:127.= 0.0.1:38484_solr) [n:127.0.0.1:38484_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [2] to [3] [junit4] 2> 1964908 INFO (zkCallback-24247-thread-1-processing-n:127.= 0.0.1:42499_solr) [n:127.0.0.1:42499_solr ] o.a.s.c.c.ZkStateReader Upda= ting data for [testschemaapi] from [2] to [3] [junit4] 2> 1965651 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController We are http://127.0.0.1:38484/solr/testschemaapi_shard1_re= plica1/ and leader is http://127.0.0.1:42499/solr/testschemaapi_shard1_repl= ica2/ [junit4] 2> 1965651 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController No LogReplay needed for core=3Dtestschemaapi_shard1_replic= a1 baseURL=3Dhttp://127.0.0.1:38484/solr [junit4] 2> 1965651 INFO (qtp21632847-96714) [n:127.0.0.1:38484_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController Core needs to recover:testschemaapi_shard1_replica1 [junit4] 2> 1965652 INFO (updateExecutor-24243-thread-1-processing-n:= 127.0.0.1:38484_solr r:core_node2 s:shard1 c:testschemaapi x:testschemaapi_= shard1_replica1) [n:127.0.0.1:38484_solr c:testschemaapi s:shard1 r:core_no= de2 x:testschemaapi_shard1_replica1] o.a.s.u.D [...truncated too long message...] $1.run(SolrZkClient.java:264) [junit4] 2> =09at java.util.concurrent.Executors$RunnableAdapter.call(= Executors.java:471) [junit4] 2> =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 262) [junit4] 2> =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1145) [junit4] 2> =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:615) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1976357 INFO (jetty-launcher-24237-thread-2) [ ] o.e.j= .s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1878567{/solr,nul= l,UNAVAILABLE} [junit4] 2> 1976357 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42989 4298= 9 [junit4] 2> 1976454 INFO (Thread-6236) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:42989 42989 [junit4] 2> 1976455 WARN (Thread-6236) [ ] o.a.s.c.ZkTestServer Wa= tch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/configs/conf1/managed-schema [junit4] 2> =096=09/solr/configs/conf1 [junit4] 2> =093=09/solr/aliases.json [junit4] 2> =092=09/solr/security.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =093=09/solr/clusterstate.json [junit4] 2> =092=09/solr/collections/testschemaapi/state.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =099=09/solr/overseer/collection-queue-work [junit4] 2> =098=09/solr/overseer/queue [junit4] 2> =093=09/solr/collections [junit4] 2> =093=09/solr/live_nodes [junit4] 2> =093=09/solr/overseer/queue-work [junit4] 2>=20 [junit4] 2> 1976455 INFO (TEST-TestManagedSchemaAPI.test-seed#[49B94B= CC331D285B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestManagedSc= hemaAPI -Dtests.method=3Dtest -Dtests.seed=3D49B94BCC331D285B -Dtests.multi= plier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dsr-BA -Dtests.timezone=3DSys= temV/AST4 -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 13.2s J1 | TestManagedSchemaAPI.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.CloudSolrC= lient$RouteException: Error from server at http://127.0.0.1:42499/solr/test= schemaapi_shard1_replica2: ERROR: [doc=3D2] unknown field 'myNewField1' [junit4] > =09at __randomizedtesting.SeedInfo.seed([49B94BCC331D285B:= C1ED74169DE145A3]:0) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.di= rectUpdate(CloudSolrClient.java:653) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.se= ndRequest(CloudSolrClient.java:1002) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.re= questWithRetryOnStaleState(CloudSolrClient.java:891) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient.re= quest(CloudSolrClient.java:827) [junit4] > =09at org.apache.solr.schema.TestManagedSchemaAPI.testAddF= ieldAndDocument(TestManagedSchemaAPI.java:101) [junit4] > =09at org.apache.solr.schema.TestManagedSchemaAPI.test(Tes= tManagedSchemaAPI.java:69) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClien= t$RemoteSolrException: Error from server at http://127.0.0.1:42499/solr/tes= tschemaapi_shard1_replica2: ERROR: [doc=3D2] unknown field 'myNewField1' [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.exe= cuteMethod(HttpSolrClient.java:577) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:241) [junit4] > =09at org.apache.solr.client.solrj.impl.HttpSolrClient.req= uest(HttpSolrClient.java:230) [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.d= oRequest(LBHttpSolrClient.java:372) [junit4] > =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.r= equest(LBHttpSolrClient.java:325) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient$2.= call(CloudSolrClient.java:628) [junit4] > =09at org.apache.solr.client.solrj.impl.CloudSolrClient$2.= call(CloudSolrClient.java:625) [junit4] > =09at java.util.concurrent.FutureTask.run(FutureTask.java:= 262) [junit4] > =09at org.apache.solr.common.util.ExecutorUtil$MDCAwareThr= eadPoolExecutor$1.run(ExecutorUtil.java:231) [junit4] > =09at java.util.concurrent.ThreadPoolExecutor.runWorker(Th= readPoolExecutor.java:1145) [junit4] > =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(T= hreadPoolExecutor.java:615) [junit4] > =09... 1 more [junit4] 2> 1976457 INFO (SUITE-TestManagedSchemaAPI-seed#[49B94BCC33= 1D285B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.schema= .TestManagedSchemaAPI_49B94BCC331D285B-001 [junit4] 2> Sep 03, 2016 6:48:05 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=3DLucene54, sim=3DRandomSimil= arity(queryNorm=3Dfalse,coord=3Dyes): {}, locale=3Dsr-BA, timezone=3DSystem= V/AST4 [junit4] 2> NOTE: Linux 4.4.0-31-generic i386/Oracle Corporation 1.7.0= _80 (32-bit)/cpus=3D12,threads=3D1,free=3D126561512,total=3D445644800 [junit4] 2> NOTE: All tests run in this JVM: [TestUseDocValuesAsStored= , HighlighterTest, ZkStateWriterTest, MoreLikeThisHandlerTest, TestCodecSup= port, TestDistributedGrouping, TestCursorMarkWithoutUniqueKey, TestDefaultS= tatsCache, TestJsonFacets, ZkControllerTest, TestWordDelimiterFilterFactory= , TestLFUCache, PKIAuthenticationIntegrationTest, TimeZoneUtilsTest, TestDi= stribDocBasedVersion, SortByFunctionTest, TestBlobHandler, SpatialHeatmapFa= cetsTest, ConjunctionSolrSpellCheckerTest, CachingDirectoryFactoryTest, Tes= tInitQParser, TestCloudPivotFacet, BlockCacheTest, DateRangeFieldTest, Spat= ialRPTFieldTypeTest, TestFileDictionaryLookup, TestDFRSimilarityFactory, Te= stSolrConfigHandlerConcurrent, NumericFieldsTest, TestUpdate, TestSimpleTra= ckingShardHandler, DeleteLastCustomShardedReplicaTest, CoreMergeIndexesAdmi= nHandlerTest, HdfsDirectoryTest, TestRawResponseWriter, TestConfigSets, Tes= tOnReconnectListenerSupport, SpellingQueryConverterTest, SolrInfoMBeanTest,= HdfsCollectionsAPIDistributedZkTest, ZkCLITest, DocumentBuilderTest, Colle= ctionTooManyReplicasTest, TestSurroundQueryParser, TestManagedSchemaFieldRe= source, HdfsThreadLeakTest, RollingRestartTest, TestCoreContainer, RankQuer= yTest, TestSolrQueryParserResource, TestFieldTypeCollectionResource, Remote= QueryErrorTest, DirectSolrSpellCheckerTest, TestCustomSort, TestBinaryRespo= nseWriter, TestQuerySenderNoQuery, DistributedFacetPivotLargeTest, SpellChe= ckCollatorTest, BigEndianAscendingWordSerializerTest, TestCopyFieldCollecti= onResource, DistributedTermsComponentTest, TestLeaderElectionZkExpiry, Simp= leCollectionCreateDeleteTest, DistanceUnitsTest, TestExceedMaxTermLength, B= lockJoinFacetRandomTest, TestInfoStreamLogging, CloudExitableDirectoryReade= rTest, MultiThreadedOCPTest, TestShortCircuitedRequests, TestConfigSetsAPIE= xclusivity, TestMiniSolrCloudClusterBase, SimpleMLTQParserTest, SoftAutoCom= mitTest, FileUtilsTest, DistanceFunctionTest, LeaderInitiatedRecoveryOnComm= itTest, ZkSolrClientTest, TestRuleBasedAuthorizationPlugin, TestRestManager= , DistributedIntervalFacetingTest, HdfsRecoveryZkTest, TestSchemaVersionRes= ource, TestAuthenticationFramework, FastVectorHighlighterTest, AddSchemaFie= ldsUpdateProcessorFactoryTest, OverseerTaskQueueTest, SpellPossibilityItera= torTest, TestTrie, TestTrackingShardHandlerFactory, SparseHLLTest, TestFilt= ering, TestSortingResponseWriter, OpenCloseCoreStressTest, LeaderElectionIn= tegrationTest, ClusterStateUpdateTest, TestZkChroot, TestRandomDVFaceting, = ShardRoutingCustomTest, TestFaceting, TestRecovery, TestHashPartitioner, Di= stributedSpellCheckComponentTest, TestRealTimeGet, TestReload, SolrCmdDistr= ibutorTest, PeerSyncTest, ConvertedLegacyTest, BasicFunctionalityTest, Curr= encyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, TestExte= ndedDismaxParser, DocValuesTest, OpenExchangeRatesOrgProviderTest, DateMath= ParserTest, TestSystemIdResolver, DateFieldTest, CircularListTest, TestRTGB= ase, CursorPagingTest, TestEmbeddedSolrServerConstructors, TestJettySolrRun= ner, ActionThrottleTest, AliasIntegrationTest, AsyncMigrateRouteKeyTest, Co= ncurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, ConnectionManager= Test, DeleteInactiveReplicaTest, DistribDocExpirationUpdateProcessorTest, H= ttpPartitionTest, TestConfigSetsAPIZkFailure, TestRandomRequestDistribution= , TestReplicaProperties, TestRequestForwarding, TestStressLiveNodes, TestSo= lrConfigHandler, CoreAdminCreateDiscoverTest, CoreAdminRequestStatusTest, I= nfoHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, Di= stributedDebugComponentTest, DistributedFacetPivotLongTailTest, Distributed= FacetPivotSmallTest, DistributedQueryComponentCustomSortTest, DistributedQu= eryComponentOptimizationTest, DistributedSuggestComponentTest, FacetPivotSm= allTest, SuggestComponentContextFilterQueryTest, TestExpandComponent, TestP= ivotHelperCode, JavabinLoaderTest, WrapperMergePolicyFactoryTest, SmileWrit= erTest, TestMacros, TestChildDocTransformer, TestManagedResource, TestBulkS= chemaAPI, TestDynamicFieldCollectionResource, TestFieldTypeResource, TestRe= moveLastDynamicCopyField, TestSchemaNameResource, TestSerializedLuceneMatch= Version, TestUniqueKeyFieldResource, TestManagedSynonymFilterFactory, BadCo= pyFieldTest, TestBulkSchemaConcurrent, TestManagedSchema, TestManagedSchema= API] [junit4] Completed [523/575 (1!)] on J1 in 14.20s, 1 test, 1 error <<< F= AILURES! [...truncated 49290 lines...] ------=_Part_182_1062132147.1472929212750 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_182_1062132147.1472929212750--