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 71DBB200B8C for ; Mon, 12 Sep 2016 19:55:33 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 70C99160AB8; Mon, 12 Sep 2016 17:55:33 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 1889D160AB2 for ; Mon, 12 Sep 2016 19:55:30 +0200 (CEST) Received: (qmail 74587 invoked by uid 500); 12 Sep 2016 17:55: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 74577 invoked by uid 99); 12 Sep 2016 17:55:29 -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; Mon, 12 Sep 2016 17:55:29 +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 8CCD6C362C for ; Mon, 12 Sep 2016 17:55:28 +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 mx2-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 hN-yU_53q6RA for ; Mon, 12 Sep 2016 17:55:12 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [188.138.57.78]) by mx2-lw-eu.apache.org (ASF Mail Server at mx2-lw-eu.apache.org) with ESMTPS id 46D395F30C for ; Mon, 12 Sep 2016 17:55:12 +0000 (UTC) Received: from serv1 (localhost.localdomain [127.0.0.1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id F32AE4C0761 for ; Mon, 12 Sep 2016 17:55:11 +0000 (UTC) Date: Mon, 12 Sep 2016 17:53:44 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1113337288.207.1473702911999.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.7.0_80) - Build # 429 - Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_206_69955630.1473702911914" X-Jenkins-Job: Lucene-Solr-5.5-Linux X-Jenkins-Result: UNSTABLE archived-at: Mon, 12 Sep 2016 17:55:33 -0000 ------=_Part_206_69955630.1473702911914 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/429/ Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC 1 tests failed. FAILED: org.apache.solr.schema.TestManagedSchemaAPI.test Error Message: Error from server at http://127.0.0.1:40603/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:40603/solr/testschemaapi_shard1_replica2: ERRO= R: [doc=3D2] unknown field 'myNewField1' =09at __randomizedtesting.SeedInfo.seed([E7427EDE8206C7A8:6F1641042CFAAA50]= :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:40603/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 10924 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/J2/temp/solr.schema.TestManagedSchemaAPI_E7= 427EDE8206C7A8-001/init-core-data-001 [junit4] 2> 391505 INFO (SUITE-TestManagedSchemaAPI-seed#[E7427EDE820= 6C7A8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clien= tAuth (false) [junit4] 2> 391508 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 391509 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 391509 INFO (Thread-1058) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 391509 INFO (Thread-1058) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 391609 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.ZkTestServer start zk server on port:46155 [junit4] 2> 391609 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 391609 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 391612 INFO (zkCallback-390-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69b0= 750 name:ZooKeeperConnection Watcher:127.0.0.1:46155 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391612 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 391612 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391612 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml [junit4] 2> 391615 INFO (jetty-launcher-389-thread-1) [ ] o.e.j.s.= Server jetty-9.2.13.v20150730 [junit4] 2> 391615 INFO (jetty-launcher-389-thread-2) [ ] o.e.j.s.= Server jetty-9.2.13.v20150730 [junit4] 2> 391616 INFO (jetty-launcher-389-thread-2) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@4c041bbc{/solr,null,= AVAILABLE} [junit4] 2> 391616 INFO (jetty-launcher-389-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@21b8faa1{/solr,null,= AVAILABLE} [junit4] 2> 391617 INFO (jetty-launcher-389-thread-1) [ ] o.e.j.s.= ServerConnector Started ServerConnector@71652ec1{HTTP/1.1}{127.0.0.1:46254} [junit4] 2> 391617 INFO (jetty-launcher-389-thread-2) [ ] o.e.j.s.= ServerConnector Started ServerConnector@c2be72{HTTP/1.1}{127.0.0.1:40603} [junit4] 2> 391617 INFO (jetty-launcher-389-thread-1) [ ] o.e.j.s.= Server Started @393365ms [junit4] 2> 391617 INFO (jetty-launcher-389-thread-2) [ ] o.e.j.s.= Server Started @393365ms [junit4] 2> 391617 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostPort=3D46254, hostContext=3D/sol= r} [junit4] 2> 391617 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostPort=3D40603, hostContext=3D/sol= r} [junit4] 2> 391618 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.s.= SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoa= der@2876b359 [junit4] 2> 391618 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.s.= SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoa= der@2876b359 [junit4] 2> 391618 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/wor= kspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.= TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1' [junit4] 2> 391618 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/wor= kspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.= TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2' [junit4] 2> 391618 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 391618 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 391618 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= SolrResourceLoader solr home defaulted to 'solr/' (could not find system pr= operty or JNDI) [junit4] 2> 391618 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= SolrResourceLoader solr home defaulted to 'solr/' (could not find system pr= operty or JNDI) [junit4] 2> 391618 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 391618 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 391618 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 391618 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 391619 INFO (zkCallback-391-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@666b= 08e4 name:ZooKeeperConnection Watcher:127.0.0.1:46155/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391619 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 391619 INFO (zkCallback-392-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7fea= 244f name:ZooKeeperConnection Watcher:127.0.0.1:46155/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391619 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391619 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 391620 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391620 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 391620 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 391630 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= CorePropertiesLocator Config-defined core root directory: /home/jenkins/wor= kspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.= TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/. [junit4] 2> 391630 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= CorePropertiesLocator Config-defined core root directory: /home/jenkins/wor= kspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.= TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/. [junit4] 2> 391630 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= CoreContainer New CoreContainer 674339997 [junit4] 2> 391630 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= CoreContainer New CoreContainer 1361124360 [junit4] 2> 391630 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= CoreContainer Loading cores into CoreContainer [instanceDir=3D/home/jenkins= /workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.sch= ema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2] [junit4] 2> 391631 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= CoreContainer Loading cores into CoreContainer [instanceDir=3D/home/jenkins= /workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.sch= ema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1] [junit4] 2> 391630 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from = client sessionid 0x1571f6142140001, likely client has closed socket [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOSe= rverCnxn.java:228) [junit4] 2> =09at org.apache.zookeeper.server.NIOServerCnxnFactory.run= (NIOServerCnxnFactory.java:208) [junit4] 2> =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 391631 WARN (jetty-launcher-389-thread-1) [ ] o.a.s.c.= CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5= .5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSchemaAPI= _E7427EDE8206C7A8-001/tempDir-001/node1/lib to classpath: /home/jenkins/wor= kspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.= TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/lib [junit4] 2> 391631 WARN (jetty-launcher-389-thread-2) [ ] o.a.s.c.= CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5= .5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSchemaAPI= _E7427EDE8206C7A8-001/tempDir-001/node2/lib to classpath: /home/jenkins/wor= kspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.= TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/lib [junit4] 2> 391631 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.h.= c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,c= onnTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePo= olSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue := -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay = : 5000,maxConnectionIdleTime : 40000, [junit4] 2> 391631 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.h.= c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,c= onnTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePo= olSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue := -1,fairnessPolicy : false,useRetries : false,connectionsEvictorSleepDelay = : 5000,maxConnectionIdleTime : 40000, [junit4] 2> 391632 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.u.= UpdateShardHandler Creating UpdateShardHandler HTTP client with params: soc= ketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 391632 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.u.= UpdateShardHandler Creating UpdateShardHandler HTTP client with params: soc= ketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 391632 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.l.= LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 391632 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.l.= LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFacto= ry)] [junit4] 2> 391632 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.l.= LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 391632 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.l.= LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFacto= ry)] [junit4] 2> 391632 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:46155/solr [junit4] 2> 391632 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= ZkController zkHost includes chroot [junit4] 2> 391632 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:46155/solr [junit4] 2> 391632 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 391633 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= ZkController zkHost includes chroot [junit4] 2> 391633 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 391633 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 391633 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 391634 INFO (zkCallback-398-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c55= dff8 name:ZooKeeperConnection Watcher:127.0.0.1:46155 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391635 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 391635 INFO (jetty-launcher-389-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391635 INFO (zkCallback-397-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76be= e0d name:ZooKeeperConnection Watcher:127.0.0.1:46155 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391635 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 391636 INFO (jetty-launcher-389-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391636 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apach= e.solr.cloud.ZkController$6@746b6605 [junit4] 2> 391637 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to = ZooKeeper [junit4] 2> 391637 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apach= e.solr.cloud.ZkController$6@7263b03e [junit4] 2> 391637 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to = ZooKeeper [junit4] 2> 391638 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ConnectionManager Wat= cher org.apache.solr.common.cloud.ConnectionManager@7d72124 name:ZooKeeperC= onnection Watcher:127.0.0.1:46155/solr got event WatchedEvent state:SyncCon= nected type:None path:null path:null type:None [junit4] 2> 391638 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 391638 INFO (zkCallback-400-thread-1-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ConnectionManager Wat= cher org.apache.solr.common.cloud.ConnectionManager@ada06dc name:ZooKeeperC= onnection Watcher:127.0.0.1:46155/solr got event WatchedEvent state:SyncCon= nected type:None path:null path:null type:None [junit4] 2> 391638 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 391638 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 391639 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 391639 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-w= ork [junit4] 2> 391639 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-w= ork [junit4] 2> 391640 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-run= ning [junit4] 2> 391640 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-run= ning [junit4] 2> 391641 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-com= pleted [junit4] 2> 391641 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-com= pleted [junit4] 2> 391642 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-fai= lure [junit4] 2> 391642 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-fai= lure [junit4] 2> 391643 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 391643 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 391643 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 391644 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 391644 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 391644 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 391644 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 391644 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 391645 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 391645 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 391645 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:46254_solr [junit4] 2> 391645 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:40603_solr [junit4] 2> 391645 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46254_= solr [junit4] 2> 391646 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40603_= solr [junit4] 2> 391648 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 391648 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 391648 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 391648 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 391649 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 391649 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 391649 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /= overseer_elect/election/96580419592650758-127.0.0.1:40603_solr-n_0000000000 [junit4] 2> 391649 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /= overseer_elect/election/96580419592650757-127.0.0.1:46254_solr-n_0000000001 [junit4] 2> 391649 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:40603_solr [junit4] 2> 391649 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 391649 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/= 96580419592650758-127.0.0.1:40603_solr-n_0000000000 to know if I could be t= he leader [junit4] 2> 391649 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper= ...=20 [junit4] 2> 391650 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.Overseer Overseer (id=3D96580419592650758-127.0.0.1:4= 0603_solr-n_0000000000) starting [junit4] 2> 391651 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 391653 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAu= toReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D10000 autoReplic= aFailoverWaitAfterExpiration=3D30000 autoReplicaFailoverBadNodeExpiration= =3D60000 [junit4] 2> 391654 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper= ...=20 [junit4] 2> 391654 INFO (OverseerCollectionConfigSetProcessor-9658041= 9592650758-127.0.0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] = o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 391654 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.Overseer = Starting to work on the main queue [junit4] 2> 391659 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping s= etup for authorization module. [junit4] 2> 391659 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 391660 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions un= derneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core= /test/J2/temp/solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir= -001/node1/. [junit4] 2> 391660 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 391661 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/jenkins/workspace= /Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2 [junit4] 2> 391661 INFO (jetty-launcher-389-thread-1) [n:127.0.0.1:46= 254_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 391664 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping s= etup for authorization module. [junit4] 2> 391665 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 391665 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions un= derneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core= /test/J2/temp/solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir= -001/node2/. [junit4] 2> 391665 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 391665 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.s.SolrDispatchFilter user.dir=3D/home/jenkins/workspace= /Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2 [junit4] 2> 391665 INFO (jetty-launcher-389-thread-2) [n:127.0.0.1:40= 603_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 391666 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 391667 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 391675 INFO (zkCallback-401-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b34f= 79 name:ZooKeeperConnection Watcher:127.0.0.1:46155 got event WatchedEvent = state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391675 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 391675 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391676 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 391677 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 391678 INFO (zkCallback-403-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6e5a= decc name:ZooKeeperConnection Watcher:127.0.0.1:46155/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391678 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 391678 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391679 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconf= ig.xml [junit4] 2> 391680 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/managed-= schema [junit4] 2> 391682 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 391684 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 391684 INFO (zkCallback-404-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5ab1= e54c name:ZooKeeperConnection Watcher:127.0.0.1:46155/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 391684 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 391685 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 391685 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooK= eeper...=20 [junit4] 2> 391688 INFO (qtp895387297-2900) [n:127.0.0.1:40603_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams action=3DCREATE&replicationFactor=3D2&numShards=3D1&name=3Dtestschemaap= i&version=3D2&wt=3Djavabin&collection.configName=3Dconf1 and sendToOCPQueue= =3Dtrue [junit4] 2> 391690 INFO (OverseerCollectionConfigSetProcessor-9658041= 9592650758-127.0.0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_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> 391690 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.OverseerCol= lectionMessageHandler OverseerCollectionMessageHandler.processMessage : cre= ate , { [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> 391691 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Create collection testschemaapi [junit4] 2> 391691 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.OverseerCol= lectionMessageHandler creating collections conf node /collections/testschem= aapi=20 [junit4] 2> 391692 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.SolrZkCli= ent makePath: /collections/testschemaapi [junit4] 2> 391692 INFO (zkCallback-400-thread-1-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader A colle= ctions change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/collections], has occurred - updating... [junit4] 2> 391692 INFO (zkCallback-404-thread-1) [ ] o.a.s.c.c.Zk= StateReader A collections change: [WatchedEvent state:SyncConnected type:No= deChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 391692 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader A colle= ctions change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/collections], has occurred - updating... [junit4] 2> 391695 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_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> 391695 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.Cluster= StateMutator building a new cName: testschemaapi [junit4] 2> 391696 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.ZkState= Writer going to create_collection /collections/testschemaapi/state.json [junit4] 2> 391794 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Creating SolrCores for new collection testschemaapi, = shardNames [shard1] , replicationFactor : 2 [junit4] 2> 391794 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Creating core testschemaapi_shard1_replica1 as part o= f shard shard1 of collection testschemaapi on 127.0.0.1:46254_solr [junit4] 2> 391795 INFO (OverseerThreadFactory-1562-thread-1-processi= ng-n:127.0.0.1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Creating core testschemaapi_shard1_replica2 as part o= f shard shard1 of collection testschemaapi on 127.0.0.1:40603_solr [junit4] 2> 391797 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = ] o.a.s.h.a.CoreAdminOperation core create command action=3DCREATE&numSh= ards=3D1&name=3Dtestschemaapi_shard1_replica1&qt=3D/admin/cores&version=3D2= &wt=3Djavabin&collection=3Dtestschemaapi&shard=3Dshard1&collection.configNa= me=3Dconf1 [junit4] 2> 391797 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr = ] o.a.s.h.a.CoreAdminOperation core create command action=3DCREATE&numSha= rds=3D1&name=3Dtestschemaapi_shard1_replica2&qt=3D/admin/cores&version=3D2&= wt=3Djavabin&collection=3Dtestschemaapi&shard=3Dshard1&collection.configNam= e=3Dconf1 [junit4] 2> 391797 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {shard=3Dshard1, name= =3Dtestschemaapi_shard1_replica1, collection=3Dtestschemaapi, configSetProp= erties=3Dconfigsetprops.json, config=3Dsolrconfig.xml, collection.configNam= e=3Dconf1, numShards=3D1, schema=3Dschema.xml, transient=3Dfalse, loadOnSta= rtup=3Dtrue, dataDir=3Ddata/} [junit4] 2> 391797 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=3Dfalse, numS= hards=3D1, name=3Dtestschemaapi_shard1_replica2, collection=3Dtestschemaapi= , loadOnStartup=3Dtrue, dataDir=3Ddata/, collection.configName=3Dconf1, con= figSetProperties=3Dconfigsetprops.json, shard=3Dshard1, config=3Dsolrconfig= .xml, schema=3Dschema.xml} [junit4] 2> 391798 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller publishing state=3Ddown [junit4] 2> 391798 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControl= ler publishing state=3Ddown [junit4] 2> 391799 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControl= ler Registering watch for collection testschemaapi [junit4] 2> 391799 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkState= Reader addZkWatch [testschemaapi] [junit4] 2> 391799 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller Registering watch for collection testschemaapi [junit4] 2> 391799 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader addZkWatch [testschemaapi] [junit4] 2> 391799 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.Overseer = processMessage: queueSize: 2, message =3D { [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:40603/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "node_name":"127.0.0.1:40603_solr", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testschemaapi_shard1_replica2"} current state ve= rsion: 0 [junit4] 2> 391799 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkState= Reader Add data for [testschemaapi] ver [0] [junit4] 2> 391799 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader Add data for [testschemaapi] ver [0] [junit4] 2> 391799 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControl= ler look for our core node name [junit4] 2> 391799 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller look for our core node name [junit4] 2> 391799 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.Replica= Mutator Update state numShards=3D1 message=3D{ [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:40603/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "node_name":"127.0.0.1:40603_solr", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testschemaapi_shard1_replica2"} [junit4] 2> 391800 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.Overseer = processMessage: queueSize: 1, message =3D { [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:46254/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "node_name":"127.0.0.1:46254_solr", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testschemaapi_shard1_replica1"} current state ve= rsion: 0 [junit4] 2> 391801 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.Replica= Mutator Update state numShards=3D1 message=3D{ [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:46254/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "node_name":"127.0.0.1:46254_solr", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testschemaapi_shard1_replica1"} [junit4] 2> 391903 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 0 [junit4] 2> 391903 INFO (zkCallback-400-thread-1-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/testschemaapi/state.json] for collection [testschemaapi] has= occurred - updating... (live nodes size: [2]) [junit4] 2> 391903 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/testschemaapi/state.json] for collection [testschemaapi] has= occurred - updating... (live nodes size: [2]) [junit4] 2> 391904 INFO (zkCallback-400-thread-1-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [0] to [1] [junit4] 2> 391904 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [0] to [1] [junit4] 2> 392800 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControl= ler waiting to find shard id in clusterstate for testschemaapi_shard1_repli= ca2 [junit4] 2> 392800 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392800 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControl= ler Check for collection zkNode:testschemaapi [junit4] 2> 392800 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller Check for collection zkNode:testschemaapi [junit4] 2> 392800 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControl= ler Collection zkNode exists [junit4] 2> 392800 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkState= Reader Load collection config from: [/collections/testschemaapi] [junit4] 2> 392800 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkContro= ller Collection zkNode exists [junit4] 2> 392800 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStat= eReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 392800 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkState= Reader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specified= config exists in ZooKeeper [junit4] 2> 392800 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResou= rceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lu= cene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManag= edSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/testschemaapi_shard1_rep= lica2' [junit4] 2> 392800 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392801 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2/temp/solr.schema.TestMana= gedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/testschemaapi_shard1_re= plica1' [junit4] 2> 392801 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResou= rceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 392801 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResou= rceLoader solr home defaulted to 'solr/' (could not find system property or= JNDI) [junit4] 2> 392801 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrReso= urceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 392801 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392802 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config lo= aded config solrconfig.xml with version 0=20 [junit4] 2> 392802 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392805 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfi= g current version of requestparams : -1 [junit4] 2> 392805 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConf= ig current version of requestparams : -1 [junit4] 2> 392812 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConf= ig Using Lucene MatchVersion: 5.5.4 [junit4] 2> 392813 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfi= g Using Lucene MatchVersion: 5.5.4 [junit4] 2> 392828 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConf= ig Loaded SolrConfig: solrconfig.xml [junit4] 2> 392828 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfi= g Loaded SolrConfig: solrconfig.xml [junit4] 2> 392831 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSche= ma [testschemaapi_shard1_replica2] Schema name=3Dminimal [junit4] 2> 392831 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSch= ema [testschemaapi_shard1_replica1] Schema name=3Dminimal [junit4] 2> 392844 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSche= ma unique key field: id [junit4] 2> 392845 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ConfigSet= Properties 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/t= est/J2 [junit4] 2> 392845 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreConta= iner Creating SolrCore 'testschemaapi_shard1_replica2' using configuration = from collection testschemaapi [junit4] 2> 392846 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 392846 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [/ho= me/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/tem= p/solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/t= estschemaapi_shard1_replica2], dataDir=3D[/home/jenkins/workspace/Lucene-So= lr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSchem= aAPI_E7427EDE8206C7A8-001/tempDir-001/node2/./testschemaapi_shard1_replica2= /data/] [junit4] 2> 392846 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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_repl= ica2 [junit4] 2> 392847 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSch= ema unique key field: id [junit4] 2> 392847 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.CachingDirectoryFactory return new directory for /home/jenkins/workspace= /Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestMa= nagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/./testschemaapi_shard= 1_replica2/data [junit4] 2> 392847 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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/wo= rkspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema= .TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/./testschemaap= i_shard1_replica2/data/index/ [junit4] 2> 392848 WARN (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.SolrCore [testschemaapi_shard1_replica2] Solr index directory '/home/jen= kins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr= .schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/./tests= chemaapi_shard1_replica2/data/index' doesn't exist. Creating new index... [junit4] 2> 392848 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.CachingDirectoryFactory return new directory for /home/jenkins/workspace= /Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestMa= nagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/./testschemaapi_shard= 1_replica2/data/index [junit4] 2> 392848 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2 [junit4] 2> 392848 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392848 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 392848 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2/te= mp/solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/= testschemaapi_shard1_replica1], dataDir=3D[/home/jenkins/workspace/Lucene-S= olr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSche= maAPI_E7427EDE8206C7A8-001/tempDir-001/node1/./testschemaapi_shard1_replica= 1/data/] [junit4] 2> 392848 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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@7cfff3ac= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@7d6e151),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 392848 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392848 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 392849 INFO (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica2) [n:127.0.0.1:40603_solr c:testschemaapi s:shard1 r= :core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Looking for o= ld index directories to cleanup for core testschemaapi_shard1_replica2 in /= home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/t= emp/solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2= /./testschemaapi_shard1_replica2/data/ [junit4] 2> 392849 WARN (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica2) [n:127.0.0.1:40603_solr c:testschemaapi s:shard1 r= :core_node1 x:testschemaapi_shard1_replica2] o.a.s.c.DirectoryFactory /home= /jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/= solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node2/./t= estschemaapi_shard1_replica2/data/ does not point to a valid data directory= ; skipping clean-up of old index directories. [junit4] 2> 392849 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2/temp/solr.schema.TestM= anagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/./testschemaapi_shar= d1_replica1/data [junit4] 2> 392850 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2/temp/solr.schem= a.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/./testschemaa= pi_shard1_replica1/data/index/ [junit4] 2> 392850 WARN (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2/temp/sol= r.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/./test= schemaapi_shard1_replica1/data/index' doesn't exist. Creating new index... [junit4] 2> 392850 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/J2/temp/solr.schema.TestM= anagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/./testschemaapi_shar= d1_replica1/data/index [junit4] 2> 392850 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.SolrCore no updateRequestProcessorChain defined as default, creating imp= licit default [junit4] 2> 392851 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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@7c04efee= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@282431de),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 392851 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 392851 INFO (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica1) [n:127.0.0.1:46254_solr c:testschemaapi s:shard1 r= :core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Looking for o= ld index directories to cleanup for core testschemaapi_shard1_replica1 in /= home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/t= emp/solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1= /./testschemaapi_shard1_replica1/data/ [junit4] 2> 392851 WARN (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica1) [n:127.0.0.1:46254_solr c:testschemaapi s:shard1 r= :core_node2 x:testschemaapi_shard1_replica1] o.a.s.c.DirectoryFactory /home= /jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/= solr.schema.TestManagedSchemaAPI_E7427EDE8206C7A8-001/tempDir-001/node1/./t= estschemaapi_shard1_replica1/data/ does not point to a valid data directory= ; skipping clean-up of old index directories. [junit4] 2> 392852 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392852 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392853 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392853 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392853 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392854 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392854 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392855 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392855 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.RequestHandlers Registered paths: /replication,/get,/admin/mbeans,/admin= /luke,/select,/admin/plugins,/admin/ping,/admin/file,/admin/segments,/admin= /threads,/admin/properties,/config,/update,/admin/system,/update/json/docs,= /update/csv,/admin/logging,/update/json,/schema [junit4] 2> 392856 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 392856 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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.Lo= calStatsCache [junit4] 2> 392856 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upd= ateLog [junit4] 2> 392856 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUS= H numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 392857 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.RequestHandlers Registered paths: /replication,/get,/admin/mbeans,/admi= n/luke,/select,/admin/plugins,/admin/ping,/admin/file,/admin/segments,/admi= n/threads,/admin/properties,/config,/update,/admin/system,/update/json/docs= ,/update/csv,/admin/logging,/update/json,/schema [junit4] 2> 392857 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 392857 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 392857 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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@7cfff3ac= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@7d6e151),s= egFN=3Dsegments_1,generation=3D1} [junit4] 2> 392857 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 392857 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392857 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .s.SolrIndexSearcher Opening Searcher@6f4f0776[testschemaapi_shard1_replica= 2] main [junit4] 2> 392858 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.c.ZkStateReader Load collection config from: [/collections/testschemaapi= ] [junit4] 2> 392858 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392859 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392859 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.c.ZkStateReader path=3D[/collections/testschemaapi] [configName]=3D[conf= 1] specified config exists in ZooKeeper [junit4] 2> 392859 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestMa= nager with znodeBase: /configs/conf1 [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /co= nfigs/conf1 [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorage= IO:path=3D/configs/conf1 [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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_mana= ged.json [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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 ZooK= eeperStorageIO:path=3D/configs/conf1 [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 392860 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392860 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /config= s/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica2:6194215= 03542211 [junit4] 2> 392860 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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@7c04efee= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@282431de),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 392861 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 392861 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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 buck= ets from index [junit4] 2> 392861 INFO (searcherExecutor-1567-thread-1-processing-r:= core_node1 c:testschemaapi n:127.0.0.1:40603_solr x:testschemaapi_shard1_re= plica2 s:shard1) [n:127.0.0.1:40603_solr c:testschemaapi s:shard1 r:core_no= de1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1= _replica2] Registered new searcher Searcher@6f4f0776[testschemaapi_shard1_r= eplica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 392861 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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 hig= hest value from index [junit4] 2> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.s.SolrIndexSearcher Opening Searcher@2f5c4937[testschemaapi_shard1_replic= a1] main [junit4] 2> 392861 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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 n= ew clock 1545286714899038208 [junit4] 2> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392861 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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:619421= 506148509 [junit4] 2> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392862 INFO (searcherExecutor-1568-thread-1-processing-r:= core_node2 n:127.0.0.1:46254_solr c:testschemaapi s:shard1 x:testschemaapi_= shard1_replica1) [n:127.0.0.1:46254_solr c:testschemaapi s:shard1 r:core_no= de2 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1= _replica1] Registered new searcher Searcher@2f5c4937[testschemaapi_shard1_r= eplica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 392862 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392863 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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 154528= 6714899038208 [junit4] 2> 392863 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 392863 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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 1545286714901135360 [junit4] 2> 392863 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ZkController watch zkdir /configs/conf1 [junit4] 2> 392863 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.CoreContainer registering core: testschemaapi_shard1_replica2 [junit4] 2> 392863 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ZkController Register replica - core:testschemaapi_shard1_replica2 addre= ss:http://127.0.0.1:40603/solr collection:testschemaapi shard:shard1 [junit4] 2> 392863 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392864 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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 15452= 86714901135360 [junit4] 2> 392864 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 392865 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.CoreContainer registering core: testschemaapi_shard1_replica1 [junit4] 2> 392865 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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:46254/solr collection:testschemaapi shard:shard1 [junit4] 2> 392865 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.LeaderElector Joined leadership election with path: /collections/testsch= emaapi/leader_elect/shard1/election/96580419592650758-core_node1-n_00000000= 00 [junit4] 2> 392866 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/96580419592650757-core_node2-n_0000000= 001 [junit4] 2> 392866 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ShardLeaderElectionContext Running the leader process for shard=3Dshard1= and weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 392866 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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/96580419592650758-core_node1-n_0000000000 to know if I could b= e the leader [junit4] 2> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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:40603/solr/testschemaapi_= shard1_replica2/ [junit4] 2> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .u.PeerSync PeerSync: core=3Dtestschemaapi_shard1_replica2 url=3Dhttp://127= .0.0.1:40603/solr START replicas=3D[http://127.0.0.1:46254/solr/testschemaa= pi_shard1_replica1/] nUpdates=3D100 [junit4] 2> 392867 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_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> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .u.PeerSync PeerSync: core=3Dtestschemaapi_shard1_replica2 url=3Dhttp://127= .0.0.1:40603/solr DONE. We have no versions. sync failed. [junit4] 2> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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 n= ext candidate [junit4] 2> 392867 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392868 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 392869 INFO (qtp1808554705-2901) [n:127.0.0.1:46254_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{qt=3D/get&version=3D2&wt=3Djavabin&distrib=3Dfalse&fingerprint=3Df= alse&getVersions=3D100} status=3D0 QTime=3D0 [junit4] 2> 392869 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ShardLeaderElectionContextBase Creating leader registration node /collec= tions/testschemaapi/leaders/shard1/leader after winning as /collections/tes= tschemaapi/leader_elect/shard1/election/96580419592650758-core_node1-n_0000= 000000 [junit4] 2> 392872 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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:40603/s= olr/testschemaapi_shard1_replica2/ shard1 [junit4] 2> 392872 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_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:40603/solr", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 392973 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 1 [junit4] 2> 392974 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/testschemaapi/state.json] for collection [testschemaapi] has= occurred - updating... (live nodes size: [2]) [junit4] 2> 392974 INFO (zkCallback-400-thread-2-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/testschemaapi/state.json] for collection [testschemaapi] has= occurred - updating... (live nodes size: [2]) [junit4] 2> 392974 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [1] to [2] [junit4] 2> 392974 INFO (zkCallback-400-thread-2-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [1] to [2] [junit4] 2> 393022 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ZkController We are http://127.0.0.1:40603/solr/testschemaapi_shard1_rep= lica2/ and leader is http://127.0.0.1:40603/solr/testschemaapi_shard1_repli= ca2/ [junit4] 2> 393022 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ZkController No LogReplay needed for core=3Dtestschemaapi_shard1_replica= 2 baseURL=3Dhttp://127.0.0.1:40603/solr [junit4] 2> 393022 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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> 393022 INFO (qtp895387297-2902) [n:127.0.0.1:40603_solr c= :testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s= .c.ZkController publishing state=3Dactive [junit4] 2> 393024 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.Overseer = processMessage: queueSize: 1, message =3D { [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:40603/solr", [junit4] 2> "state":"active", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "node_name":"127.0.0.1:40603_solr", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testschemaapi_shard1_replica2"} current state ve= rsion: 0 [junit4] 2> 393024 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.Replica= Mutator Update state numShards=3D1 message=3D{ [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:40603/solr", [junit4] 2> "state":"active", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "node_name":"127.0.0.1:40603_solr", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testschemaapi_shard1_replica2"} [junit4] 2> 393024 INFO (qtp895387297-2902) [n:127.0.0.1:40603_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{action= =3DCREATE&numShards=3D1&name=3Dtestschemaapi_shard1_replica2&qt=3D/admin/co= res&version=3D2&wt=3Djavabin&collection=3Dtestschemaapi&shard=3Dshard1&coll= ection.configName=3Dconf1} status=3D0 QTime=3D1227 [junit4] 2> 393125 INFO (OverseerStateUpdate-96580419592650758-127.0.= 0.1:40603_solr-n_0000000000) [n:127.0.0.1:40603_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 2 [junit4] 2> 393126 INFO (zkCallback-400-thread-1-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/testschemaapi/state.json] for collection [testschemaapi] has= occurred - updating... (live nodes size: [2]) [junit4] 2> 393126 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected type:NodeDataChanged pat= h:/collections/testschemaapi/state.json] for collection [testschemaapi] has= occurred - updating... (live nodes size: [2]) [junit4] 2> 393126 INFO (zkCallback-400-thread-1-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [2] to [3] [junit4] 2> 393126 INFO (zkCallback-399-thread-1-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [2] to [3] [junit4] 2> 393867 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_solr = c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.= s.c.ZkController We are http://127.0.0.1:46254/solr/testschemaapi_shard1_re= plica1/ and leader is http://127.0.0.1:40603/solr/testschemaapi_shard1_repl= ica2/ [junit4] 2> 393867 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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:46254/solr [junit4] 2> 393867 INFO (qtp1808554705-2899) [n:127.0.0.1:46254_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> 393868 INFO (updateExecutor-395-thread-1-processing-r:cor= e_node2 n:127.0.0.1:46254_solr c:testschemaapi s:shard1 x:testschemaapi_sha= rd1_replica1) [n:127.0.0.1:46254_solr c:testschemaapi s:shard1 r:core_node2= x:testschemaapi_shard1_replica1] o.a.s.u.DefaultSolrCoreState Running reco= very [junit4] 2> 393868 INFO (recoveryExecutor [...truncated too long message...] e.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:342) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getData(So= lrZkClient.java:342) [junit4] 2> =09at org.apache.solr.schema.ManagedIndexSchemaFactory.cre= ate(ManagedIndexSchemaFactory.java:128) [junit4] 2> =09... 7 more [junit4] 2> 402104 WARN (Thread-1095) [n:127.0.0.1:40603_solr ] o.= a.s.c.ZkController listener throws error [junit4] 2> org.apache.solr.common.SolrException: Unable to reload cor= e [testschemaapi_shard1_replica2] [junit4] 2> =09at org.apache.solr.core.CoreContainer.reload(CoreContai= ner.java:927) [junit4] 2> =09at org.apache.solr.core.SolrCore$11.run(SolrCore.java:2= 623) [junit4] 2> =09at org.apache.solr.cloud.ZkController$5.run(ZkControlle= r.java:2480) [junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not= load conf for core testschemaapi_shard1_replica2: Error attempting to acce= ss /configs/conf1/managed-schema [junit4] 2> =09at org.apache.solr.core.ConfigSetService.getConfig(Conf= igSetService.java:84) [junit4] 2> =09at org.apache.solr.core.CoreContainer.reload(CoreContai= ner.java:920) [junit4] 2> =09... 2 more [junit4] 2> Caused by: org.apache.solr.common.SolrException: Error att= empting to access /configs/conf1/managed-schema [junit4] 2> =09at org.apache.solr.schema.ManagedIndexSchemaFactory.cre= ate(ManagedIndexSchemaFactory.java:143) [junit4] 2> =09at org.apache.solr.schema.ManagedIndexSchemaFactory.cre= ate(ManagedIndexSchemaFactory.java:47) [junit4] 2> =09at org.apache.solr.schema.IndexSchemaFactory.buildIndex= Schema(IndexSchemaFactory.java:70) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.createIndexSch= ema(ConfigSetService.java:108) [junit4] 2> =09at org.apache.solr.core.ConfigSetService.getConfig(Conf= igSetService.java:79) [junit4] 2> =09... 3 more [junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExp= iredException: KeeperErrorCode =3D Session expired for /configs/conf1/manag= ed-schema [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.jav= a:1155) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$7.execute(= SolrZkClient.java:345) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$7.execute(= SolrZkClient.java:342) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:60) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getData(So= lrZkClient.java:342) [junit4] 2> =09at org.apache.solr.schema.ManagedIndexSchemaFactory.cre= ate(ManagedIndexSchemaFactory.java:128) [junit4] 2> =09... 7 more [junit4] 2> 403590 WARN (zkCallback-399-thread-2-processing-n:127.0.0= .1:46254_solr) [n:127.0.0.1:46254_solr ] o.a.s.c.c.ZkStateReader ZooKeep= er watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessio= n expired for /live_nodes] [junit4] 2> 403590 WARN (zkCallback-400-thread-8-processing-n:127.0.0= .1:40603_solr) [n:127.0.0.1:40603_solr ] o.a.s.c.c.ZkStateReader ZooKeep= er watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Sessio= n expired for /live_nodes] [junit4] 2> 403591 INFO (jetty-launcher-389-thread-2) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@21b8faa1{/solr,null,= UNAVAILABLE} [junit4] 2> 403591 INFO (jetty-launcher-389-thread-1) [ ] o.e.j.s.= h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c041bbc{/solr,null,= UNAVAILABLE} [junit4] 2> 403592 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46155 46155 [junit4] 2> 403613 INFO (Thread-1058) [ ] o.a.s.c.ZkTestServer con= necting to 127.0.0.1:46155 46155 [junit4] 2> 403613 WARN (Thread-1058) [ ] o.a.s.c.ZkTestServer Wat= ch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/configs/conf1 [junit4] 2> =096=09/solr/configs/conf1/managed-schema [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/overseer/queue-work [junit4] 2> =093=09/solr/collections [junit4] 2> =093=09/solr/live_nodes [junit4] 2>=20 [junit4] 2> 403613 INFO (TEST-TestManagedSchemaAPI.test-seed#[E7427ED= E8206C7A8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestManagedSc= hemaAPI -Dtests.method=3Dtest -Dtests.seed=3DE7427EDE8206C7A8 -Dtests.multi= plier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dnl -Dtests.timezone=3DAsia/J= akarta -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 12.1s J2 | TestManagedSchemaAPI.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.CloudSolrC= lient$RouteException: Error from server at http://127.0.0.1:40603/solr/test= schemaapi_shard1_replica2: ERROR: [doc=3D2] unknown field 'myNewField1' [junit4] > =09at __randomizedtesting.SeedInfo.seed([E7427EDE8206C7A8:= 6F1641042CFAAA50]: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:40603/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> 403616 INFO (SUITE-TestManagedSchemaAPI-seed#[E7427EDE820= 6C7A8]-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/J2/temp/solr.schema= .TestManagedSchemaAPI_E7427EDE8206C7A8-001 [junit4] 2> Sep 12, 2016 5:11:02 PM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene54): {_vers= ion_=3DBlockTreeOrds(blocksize=3D128), id=3DPostingsFormat(name=3DDirect), = myNewField=3DPostingsFormat(name=3DMemory doPackFST=3D true)}, docValues:{}= , sim=3DDefaultSimilarity, locale=3Dnl, timezone=3DAsia/Jakarta [junit4] 2> NOTE: Linux 4.4.0-36-generic amd64/Oracle Corporation 1.7.= 0_80 (64-bit)/cpus=3D12,threads=3D1,free=3D207390144,total=3D507641856 [junit4] 2> NOTE: All tests run in this JVM: [SolrCoreCheckLockOnStart= upTest, TestInitParams, BadComponentTest, TestCollationField, TestJsonReque= st, DistributedDebugComponentTest, CheckHdfsIndexTest, BadIndexSchemaTest, = TestRandomRequestDistribution, DirectUpdateHandlerTest, HdfsRestartWhileUpd= atingTest, LeaderInitiatedRecoveryOnShardRestartTest, TestReRankQParserPlug= in, TestOverriddenPrefixQueryForCustomFieldType, SuggesterWFSTTest, TestPos= tingsSolrHighlighter, DistributedSuggestComponentTest, TestObjectReleaseTra= cker, HdfsChaosMonkeyNothingIsSafeTest, TestManagedResource, TestConfigSets= APIZkFailure, TestTrieFacet, PluginInfoTest, DistributedQueueTest, TestClou= dSchemaless, CoreSorterTest, DebugComponentTest, InfoHandlerTest, DateMathP= arserTest, TestManagedSchemaFieldTypeResource, TestAuthorizationFramework, = TestRecovery, FullSolrCloudDistribCmdsTest, IgnoreCommitOptimizeUpdateProce= ssorFactoryTest, TestJmxIntegration, BlockJoinFacetSimpleTest, TestAnalyzeI= nfixSuggestions, TestCoreDiscovery, TestCSVResponseWriter, StatsComponentTe= st, TestFieldSortValues, DistributedFacetPivotWhiteBoxTest, PeerSyncReplica= tionTest, TestHdfsUpdateLog, TestManagedSchemaAPI] [junit4] Completed [109/575 (1!)] on J2 in 13.13s, 1 test, 1 error <<< F= AILURES! [...truncated 58121 lines...] ------=_Part_206_69955630.1473702911914 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_206_69955630.1473702911914--