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 D7AB5200C27 for ; Sun, 12 Feb 2017 00:07:17 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id D63A5160B5D; Sat, 11 Feb 2017 23:07:17 +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 4AD75160B5B for ; Sun, 12 Feb 2017 00:07:15 +0100 (CET) Received: (qmail 6064 invoked by uid 500); 11 Feb 2017 23:07:14 -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 6036 invoked by uid 99); 11 Feb 2017 23:07:13 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd1-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 11 Feb 2017 23:07:13 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd1-us-west.apache.org (ASF Mail Server at spamd1-us-west.apache.org) with ESMTP id 0C55CC19C8 for ; Sat, 11 Feb 2017 23:07:13 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd1-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2.001 X-Spam-Level: ** X-Spam-Status: No, score=2.001 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, RCVD_IN_DNSWL_NONE=-0.0001, SPF_PASS=-0.001, URIBL_BLOCKED=0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd1-us-west.apache.org [10.40.0.7]) (amavisd-new, port 10024) with ESMTP id A8K6LNDg1LM3 for ; Sat, 11 Feb 2017 23:07:05 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTPS id C09575F342 for ; Sat, 11 Feb 2017 23:07:04 +0000 (UTC) Received: from serv1 (localhost.localdomain [127.0.0.1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id C50AF4C03E1 for ; Sat, 11 Feb 2017 23:06:58 +0000 (UTC) Date: Sat, 11 Feb 2017 23:05:42 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1466593492.479.1486854418809.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-5.5-Linux (32bit/jdk1.7.0_80) - Build # 461 - Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_478_1552231960.1486854418751" X-Jenkins-Job: Lucene-Solr-5.5-Linux X-Jenkins-Result: UNSTABLE archived-at: Sat, 11 Feb 2017 23:07:18 -0000 ------=_Part_478_1552231960.1486854418751 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/461/ Java: 32bit/jdk1.7.0_80 -client -XX:+UseParallelGC 1 tests failed. FAILED: org.apache.solr.schema.TestManagedSchemaAPI.test Error Message: Error from server at http://127.0.0.1:38644/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:38644/solr/testschemaapi_shard1_replica2: ERRO= R: [doc=3D2] unknown field 'myNewField1' =09at __randomizedtesting.SeedInfo.seed([5F57653C84414398:D7035AE62ABD2E60]= :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:38644/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 11258 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_5F= 57653C84414398-001/init-core-data-001 [junit4] 2> 630168 INFO (SUITE-TestManagedSchemaAPI-seed#[5F57653C844= 14398]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clien= tAuth (true) [junit4] 2> 630169 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 630170 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 630170 INFO (Thread-1541) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 630170 INFO (Thread-1541) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 630270 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.ZkTestServer start zk server on port:33113 [junit4] 2> 630271 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 630271 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 630273 INFO (zkCallback-768-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11d2= e47 name:ZooKeeperConnection Watcher:127.0.0.1:33113 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630273 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 630273 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630273 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml [junit4] 2> 630276 INFO (jetty-launcher-767-thread-2) [ ] o.e.j.s.= Server jetty-9.2.13.v20150730 [junit4] 2> 630276 INFO (jetty-launcher-767-thread-1) [ ] o.e.j.s.= Server jetty-9.2.13.v20150730 [junit4] 2> 630276 INFO (jetty-launcher-767-thread-2) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@312745{/solr,null,AV= AILABLE} [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@1095873{/solr,null,A= VAILABLE} [junit4] 2> 630277 INFO (jetty-launcher-767-thread-2) [ ] o.e.j.s.= ServerConnector Started ServerConnector@17a5285{HTTP/1.1}{127.0.0.1:38644} [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.e.j.s.= ServerConnector Started ServerConnector@45c4e3{HTTP/1.1}{127.0.0.1:43268} [junit4] 2> 630277 INFO (jetty-launcher-767-thread-2) [ ] o.e.j.s.= Server Started @631489ms [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.e.j.s.= Server Started @631489ms [junit4] 2> 630277 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D3864= 4} [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostPort=3D43268, hostContext=3D/sol= r} [junit4] 2> 630277 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.s.= SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoa= der@b0f0ae [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.s.= SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoa= der@b0f0ae [junit4] 2> 630277 INFO (jetty-launcher-767-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_5F57653C84414398-001/tempDir-001/node2' [junit4] 2> 630277 INFO (jetty-launcher-767-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_5F57653C84414398-001/tempDir-001/node1' [junit4] 2> 630277 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 630277 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= SolrResourceLoader solr home defaulted to 'solr/' (could not find system pr= operty or JNDI) [junit4] 2> 630277 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= SolrResourceLoader solr home defaulted to 'solr/' (could not find system pr= operty or JNDI) [junit4] 2> 630278 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 630278 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 630278 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 630278 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 630278 INFO (zkCallback-769-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c5a= be name:ZooKeeperConnection Watcher:127.0.0.1:33113/solr got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630278 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 630278 INFO (zkCallback-770-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@48ae= fa name:ZooKeeperConnection Watcher:127.0.0.1:33113/solr got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630279 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 630279 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630279 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630279 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 630279 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 630292 INFO (jetty-launcher-767-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_5F57653C84414398-001/tempDir-001/node1/. [junit4] 2> 630292 INFO (jetty-launcher-767-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_5F57653C84414398-001/tempDir-001/node2/. [junit4] 2> 630292 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= CoreContainer New CoreContainer 2647634 [junit4] 2> 630292 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 0x15a2f4d4fc40001, 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> 630293 INFO (jetty-launcher-767-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_5F57653C84414398-001/tempDir-001/node1] [junit4] 2> 630293 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= CoreContainer New CoreContainer 5611765 [junit4] 2> 630293 INFO (jetty-launcher-767-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_5F57653C84414398-001/tempDir-001/node2] [junit4] 2> 630293 WARN (jetty-launcher-767-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= _5F57653C84414398-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_5F57653C84414398-001/tempDir-001/node1/lib [junit4] 2> 630293 WARN (jetty-launcher-767-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= _5F57653C84414398-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_5F57653C84414398-001/tempDir-001/node2/lib [junit4] 2> 630293 INFO (jetty-launcher-767-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> 630293 INFO (jetty-launcher-767-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> 630294 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.u.= UpdateShardHandler Creating UpdateShardHandler HTTP client with params: soc= ketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 630294 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.u.= UpdateShardHandler Creating UpdateShardHandler HTTP client with params: soc= ketTimeout=3D340000&connTimeout=3D45000&retry=3Dtrue [junit4] 2> 630295 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.l.= LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 630295 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.l.= LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFacto= ry)] [junit4] 2> 630295 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:33113/solr [junit4] 2> 630295 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.l.= LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 630295 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= ZkController zkHost includes chroot [junit4] 2> 630295 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.l.= LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFacto= ry)] [junit4] 2> 630295 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 630295 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:33113/solr [junit4] 2> 630295 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= ZkController zkHost includes chroot [junit4] 2> 630295 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 630295 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 630296 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 630296 INFO (zkCallback-775-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1fca= bb name:ZooKeeperConnection Watcher:127.0.0.1:33113 got event WatchedEvent = state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630296 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 630296 INFO (zkCallback-776-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@12bd= 7b1 name:ZooKeeperConnection Watcher:127.0.0.1:33113 got event WatchedEvent= state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630296 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 630296 INFO (jetty-launcher-767-thread-2) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630297 INFO (jetty-launcher-767-thread-1) [ ] o.a.s.c.= c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630297 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apach= e.solr.cloud.ZkController$6@e70912 [junit4] 2> 630297 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apach= e.solr.cloud.ZkController$6@494cc [junit4] 2> 630297 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 0x15a2f4d4fc40004, 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> 630297 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to = ZooKeeper [junit4] 2> 630297 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to = ZooKeeper [junit4] 2> 630298 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_solr ] o.a.s.c.c.ConnectionManager Wat= cher org.apache.solr.common.cloud.ConnectionManager@180ab23 name:ZooKeeperC= onnection Watcher:127.0.0.1:33113/solr got event WatchedEvent state:SyncCon= nected type:None path:null path:null type:None [junit4] 2> 630298 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 630298 INFO (zkCallback-778-thread-1-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.c.ConnectionManager Wat= cher org.apache.solr.common.cloud.ConnectionManager@16e96e1 name:ZooKeeperC= onnection Watcher:127.0.0.1:33113/solr got event WatchedEvent state:SyncCon= nected type:None path:null path:null type:None [junit4] 2> 630298 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 630299 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 630299 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 630299 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-w= ork [junit4] 2> 630299 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-w= ork [junit4] 2> 630301 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-run= ning [junit4] 2> 630301 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-run= ning [junit4] 2> 630302 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-com= pleted [junit4] 2> 630302 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-com= pleted [junit4] 2> 630302 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-fai= lure [junit4] 2> 630302 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-fai= lure [junit4] 2> 630304 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 630304 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 630305 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 630305 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 630305 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 630305 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 630306 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 630306 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 630306 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 630307 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 630307 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:43268_solr [junit4] 2> 630307 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:38644_solr [junit4] 2> 630307 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43268_= solr [junit4] 2> 630307 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:38644_= solr [junit4] 2> 630308 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 630308 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 630309 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 630309 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 630309 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 630309 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 630310 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /= overseer_elect/election/97442351039119366-127.0.0.1:43268_solr-n_0000000000 [junit4] 2> 630310 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /= overseer_elect/election/97442351039119365-127.0.0.1:38644_solr-n_0000000001 [junit4] 2> 630310 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:43268_solr [junit4] 2> 630310 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 630310 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/= 97442351039119366-127.0.0.1:43268_solr-n_0000000000 to know if I could be t= he leader [junit4] 2> 630310 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper= ...=20 [junit4] 2> 630311 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.Overseer Overseer (id=3D97442351039119366-127.0.0.1:4= 3268_solr-n_0000000000) starting [junit4] 2> 630311 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 630313 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAu= toReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=3D10000 autoReplic= aFailoverWaitAfterExpiration=3D30000 autoReplicaFailoverBadNodeExpiration= =3D60000 [junit4] 2> 630314 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper= ...=20 [junit4] 2> 630314 INFO (OverseerCollectionConfigSetProcessor-9744235= 1039119366-127.0.0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] = o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 630314 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.Overseer = Starting to work on the main queue [junit4] 2> 630335 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping s= etup for authorization module. [junit4] 2> 630335 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 630336 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_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_5F57653C84414398-001/tempDir= -001/node1/. [junit4] 2> 630336 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 630336 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_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> 630336 INFO (jetty-launcher-767-thread-1) [n:127.0.0.1:43= 268_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 630336 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping s= etup for authorization module. [junit4] 2> 630336 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 630337 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_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_5F57653C84414398-001/tempDir= -001/node2/. [junit4] 2> 630337 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions [junit4] 2> 630337 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_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> 630337 INFO (jetty-launcher-767-thread-2) [n:127.0.0.1:38= 644_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 630337 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 630338 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 630346 INFO (zkCallback-779-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d48= c name:ZooKeeperConnection Watcher:127.0.0.1:33113 got event WatchedEvent s= tate:SyncConnected type:None path:null path:null type:None [junit4] 2> 630346 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 630347 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630347 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 630348 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 630348 INFO (zkCallback-781-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1abb= 415 name:ZooKeeperConnection Watcher:127.0.0.1:33113/solr got event Watched= Event state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630348 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 630349 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630350 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconf= ig.xml [junit4] 2> 630351 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/managed-= schema [junit4] 2> 630353 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvid= er [junit4] 2> 630353 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connec= t to ZooKeeper [junit4] 2> 630353 INFO (zkCallback-782-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e50a= 33 name:ZooKeeperConnection Watcher:127.0.0.1:33113/solr got event WatchedE= vent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 630354 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKe= eper [junit4] 2> 630354 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 630354 INFO (TEST-TestManagedSchemaAPI.test-seed#[5F57653= C84414398]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooK= eeper...=20 [junit4] 2> 630357 INFO (qtp5891478-4782) [n:127.0.0.1:38644_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with param= s action=3DCREATE&version=3D2&collection.configName=3Dconf1&replicationFact= or=3D2&numShards=3D1&wt=3Djavabin&name=3Dtestschemaapi and sendToOCPQueue= =3Dtrue [junit4] 2> 630358 INFO (OverseerCollectionConfigSetProcessor-9744235= 1039119366-127.0.0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_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> 630358 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_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> 630359 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Create collection testschemaapi [junit4] 2> 630359 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.OverseerCol= lectionMessageHandler creating collections conf node /collections/testschem= aapi=20 [junit4] 2> 630359 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.c.SolrZkCli= ent makePath: /collections/testschemaapi [junit4] 2> 630360 INFO (zkCallback-778-thread-1-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.c.ZkStateReader A colle= ctions change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/collections], has occurred - updating... [junit4] 2> 630360 INFO (zkCallback-782-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> 630360 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_solr ] o.a.s.c.c.ZkStateReader A colle= ctions change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged p= ath:/collections], has occurred - updating... [junit4] 2> 630361 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_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> 630361 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.Cluster= StateMutator building a new cName: testschemaapi [junit4] 2> 630361 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.ZkState= Writer going to create_collection /collections/testschemaapi/state.json [junit4] 2> 630461 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.OverseerCol= lectionMessageHandler Creating SolrCores for new collection testschemaapi, = shardNames [shard1] , replicationFactor : 2 [junit4] 2> 630461 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_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:38644_solr [junit4] 2> 630461 INFO (OverseerThreadFactory-2376-thread-1-processi= ng-n:127.0.0.1:43268_solr) [n:127.0.0.1:43268_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:43268_solr [junit4] 2> 630463 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr = ] o.a.s.h.a.CoreAdminOperation core create command action=3DCREATE&collecti= on=3Dtestschemaapi&collection.configName=3Dconf1&qt=3D/admin/cores&version= =3D2&numShards=3D1&wt=3Djavabin&name=3Dtestschemaapi_shard1_replica2&shard= =3Dshard1 [junit4] 2> 630463 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {dataDir=3Ddata/, configSe= tProperties=3Dconfigsetprops.json, schema=3Dschema.xml, name=3Dtestschemaap= i_shard1_replica2, collection=3Dtestschemaapi, loadOnStartup=3Dtrue, shard= =3Dshard1, config=3Dsolrconfig.xml, transient=3Dfalse, numShards=3D1, colle= ction.configName=3Dconf1} [junit4] 2> 630464 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr = ] o.a.s.h.a.CoreAdminOperation core create command action=3DCREATE&collecti= on=3Dtestschemaapi&collection.configName=3Dconf1&qt=3D/admin/cores&version= =3D2&numShards=3D1&wt=3Djavabin&name=3Dtestschemaapi_shard1_replica1&shard= =3Dshard1 [junit4] 2> 630464 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr = ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=3Dsolrconfig.xml, = schema=3Dschema.xml, collection.configName=3Dconf1, configSetProperties=3Dc= onfigsetprops.json, transient=3Dfalse, loadOnStartup=3Dtrue, shard=3Dshard1= , name=3Dtestschemaapi_shard1_replica1, collection=3Dtestschemaapi, numShar= ds=3D1, dataDir=3Ddata/} [junit4] 2> 630464 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControlle= r publishing state=3Ddown [junit4] 2> 630464 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkControlle= r publishing state=3Ddown [junit4] 2> 630464 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControlle= r Registering watch for collection testschemaapi [junit4] 2> 630465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateRe= ader addZkWatch [testschemaapi] [junit4] 2> 630465 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.Overseer = processMessage: queueSize: 1, message =3D { [junit4] 2> "base_url":"http://127.0.0.1:38644/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:38644_solr", [junit4] 2> "shard":"shard1"} current state version: 0 [junit4] 2> 630465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateRe= ader Add data for [testschemaapi] ver [0] [junit4] 2> 630465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkControlle= r Registering watch for collection testschemaapi [junit4] 2> 630465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControlle= r look for our core node name [junit4] 2> 630465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateRe= ader addZkWatch [testschemaapi] [junit4] 2> 630465 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.Replica= Mutator Update state numShards=3D1 message=3D{ [junit4] 2> "base_url":"http://127.0.0.1:38644/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:38644_solr", [junit4] 2> "shard":"shard1"} [junit4] 2> 630465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateRe= ader Add data for [testschemaapi] ver [0] [junit4] 2> 630465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkControlle= r look for our core node name [junit4] 2> 630466 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.Overseer = processMessage: queueSize: 1, message =3D { [junit4] 2> "base_url":"http://127.0.0.1:43268/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "core":"testschemaapi_shard1_replica1", [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:43268_solr", [junit4] 2> "shard":"shard1"} current state version: 0 [junit4] 2> 630466 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.Replica= Mutator Update state numShards=3D1 message=3D{ [junit4] 2> "base_url":"http://127.0.0.1:43268/solr", [junit4] 2> "state":"down", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "core":"testschemaapi_shard1_replica1", [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:43268_solr", [junit4] 2> "shard":"shard1"} [junit4] 2> 630567 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 0 [junit4] 2> 630567 INFO (zkCallback-778-thread-2-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_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> 630567 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_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> 630568 INFO (zkCallback-778-thread-2-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [0] to [1] [junit4] 2> 630568 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [0] to [1] [junit4] 2> 631465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControlle= r waiting to find shard id in clusterstate for testschemaapi_shard1_replica= 2 [junit4] 2> 631465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControlle= r Check for collection zkNode:testschemaapi [junit4] 2> 631465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkControlle= r waiting to find shard id in clusterstate for testschemaapi_shard1_replica= 1 [junit4] 2> 631465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkControlle= r Check for collection zkNode:testschemaapi [junit4] 2> 631465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkControlle= r Collection zkNode exists [junit4] 2> 631465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkControlle= r Collection zkNode exists [junit4] 2> 631465 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateRe= ader Load collection config from: [/collections/testschemaapi] [junit4] 2> 631465 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateRe= ader Load collection config from: [/collections/testschemaapi] [junit4] 2> 631466 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateRe= ader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specified c= onfig exists in ZooKeeper [junit4] 2> 631466 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateRe= ader path=3D[/collections/testschemaapi] [configName]=3D[conf1] specified c= onfig exists in ZooKeeper [junit4] 2> 631466 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourc= eLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Luce= ne-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManaged= SchemaAPI_5F57653C84414398-001/tempDir-001/node2/testschemaapi_shard1_repli= ca2' [junit4] 2> 631466 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourc= eLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Luce= ne-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManaged= SchemaAPI_5F57653C84414398-001/tempDir-001/node1/testschemaapi_shard1_repli= ca1' [junit4] 2> 631466 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourc= eLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 631466 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourc= eLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 631466 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourc= eLoader solr home defaulted to 'solr/' (could not find system property or J= NDI) [junit4] 2> 631466 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourc= eLoader solr home defaulted to 'solr/' (could not find system property or J= NDI) [junit4] 2> 631468 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config load= ed config solrconfig.xml with version 0=20 [junit4] 2> 631468 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config load= ed config solrconfig.xml with version 0=20 [junit4] 2> 631472 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig = current version of requestparams : -1 [junit4] 2> 631472 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig = current version of requestparams : -1 [junit4] 2> 631482 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig = Using Lucene MatchVersion: 5.5.4 [junit4] 2> 631482 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig = Using Lucene MatchVersion: 5.5.4 [junit4] 2> 631503 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig = Loaded SolrConfig: solrconfig.xml [junit4] 2> 631503 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig = Loaded SolrConfig: solrconfig.xml [junit4] 2> 631507 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema= [testschemaapi_shard1_replica1] Schema name=3Dminimal [junit4] 2> 631507 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema= [testschemaapi_shard1_replica2] Schema name=3Dminimal [junit4] 2> 631516 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema= unique key field: id [junit4] 2> 631516 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema= unique key field: id [junit4] 2> 631517 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ConfigSetPr= operties Did not find ConfigSet properties, assuming default properties: Ca= n't find resource 'configsetprops.json' in classpath or '/configs/conf1', c= wd=3D/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/tes= t/J2 [junit4] 2> 631517 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreContain= er Creating SolrCore 'testschemaapi_shard1_replica2' using configuration fr= om collection testschemaapi [junit4] 2> 631517 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ConfigSetPr= operties Did not find ConfigSet properties, assuming default properties: Ca= n't find resource 'configsetprops.json' in classpath or '/configs/conf1', c= wd=3D/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/tes= t/J2 [junit4] 2> 631518 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreContain= er Creating SolrCore 'testschemaapi_shard1_replica1' using configuration fr= om collection testschemaapi [junit4] 2> 631518 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 631518 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 631518 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [/home= /jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/= solr.schema.TestManagedSchemaAPI_5F57653C84414398-001/tempDir-001/node2/tes= tschemaapi_shard1_replica2], dataDir=3D[/home/jenkins/workspace/Lucene-Solr= -5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSchemaA= PI_5F57653C84414398-001/tempDir-001/node2/./testschemaapi_shard1_replica2/d= ata/] [junit4] 2> 631518 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore [[testschemaapi_shard1_replica1] ] Opening new SolrCore at [/home= /jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/= solr.schema.TestManagedSchemaAPI_5F57653C84414398-001/tempDir-001/node1/tes= tschemaapi_shard1_replica1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr= -5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSchemaA= PI_5F57653C84414398-001/tempDir-001/node1/./testschemaapi_shard1_replica1/d= ata/] [junit4] 2> 631518 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replic= a2 [junit4] 2> 631518 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replic= a1 [junit4] 2> 631519 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .CachingDirectoryFactory return new directory for /home/jenkins/workspace/L= ucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestMana= gedSchemaAPI_5F57653C84414398-001/tempDir-001/node2/./testschemaapi_shard1_= replica2/data [junit4] 2> 631519 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/work= space/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.T= estManagedSchemaAPI_5F57653C84414398-001/tempDir-001/node2/./testschemaapi_= shard1_replica2/data/index/ [junit4] 2> 631519 WARN (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore [testschemaapi_shard1_replica2] Solr index directory '/home/jenki= ns/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.s= chema.TestManagedSchemaAPI_5F57653C84414398-001/tempDir-001/node2/./testsch= emaapi_shard1_replica2/data/index' doesn't exist. Creating new index... [junit4] 2> 631519 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .CachingDirectoryFactory return new directory for /home/jenkins/workspace/L= ucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestMana= gedSchemaAPI_5F57653C84414398-001/tempDir-001/node1/./testschemaapi_shard1_= replica1/data [junit4] 2> 631520 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore New index directory detected: old=3Dnull new=3D/home/jenkins/work= space/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.T= estManagedSchemaAPI_5F57653C84414398-001/tempDir-001/node1/./testschemaapi_= shard1_replica1/data/index/ [junit4] 2> 631520 WARN (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore [testschemaapi_shard1_replica1] Solr index directory '/home/jenki= ns/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.s= chema.TestManagedSchemaAPI_5F57653C84414398-001/tempDir-001/node1/./testsch= emaapi_shard1_replica1/data/index' doesn't exist. Creating new index... [junit4] 2> 631520 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .CachingDirectoryFactory return new directory for /home/jenkins/workspace/L= ucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestMana= gedSchemaAPI_5F57653C84414398-001/tempDir-001/node2/./testschemaapi_shard1_= replica2/data/index [junit4] 2> 631520 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .CachingDirectoryFactory return new directory for /home/jenkins/workspace/L= ucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestMana= gedSchemaAPI_5F57653C84414398-001/tempDir-001/node1/./testschemaapi_shard1_= replica1/data/index [junit4] 2> 631520 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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@abdd8 lo= ckFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@135df1d),segF= N=3Dsegments_1,generation=3D1} [junit4] 2> 631520 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 631520 INFO (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica2) [n:127.0.0.1:38644_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_5F57653C84414398-001/tempDir-001/node2= /./testschemaapi_shard1_replica2/data/ [junit4] 2> 631520 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi 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@8b449c l= ockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@145881a),seg= FN=3Dsegments_1,generation=3D1} [junit4] 2> 631520 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 631520 WARN (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica2) [n:127.0.0.1:38644_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_5F57653C84414398-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> 631521 INFO (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica1) [n:127.0.0.1:43268_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_5F57653C84414398-001/tempDir-001/node1= /./testschemaapi_shard1_replica1/data/ [junit4] 2> 631521 WARN (OldIndexDirectoryCleanupThreadForCore-testsc= hemaapi_shard1_replica1) [n:127.0.0.1:43268_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_5F57653C84414398-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> 631521 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore no updateRequestProcessorChain defined as default, creating impli= cit default [junit4] 2> 631522 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore no updateRequestProcessorChain defined as default, creating impli= cit default [junit4] 2> 631523 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631523 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631524 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631525 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631525 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631526 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631526 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631526 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .RequestHandlers Registered paths: /config,/admin/system,/admin/plugins,/ad= min/mbeans,/admin/file,/admin/luke,/admin/threads,/select,/admin/segments,/= update/csv,/update/json/docs,/update/json,/replication,/schema,/admin/prope= rties,/get,/admin/logging,/update,/admin/ping [junit4] 2> 631528 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h= .l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 631528 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrCore Using default statsCache cache: org.apache.solr.search.stats.Loca= lStatsCache [junit4] 2> 631529 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Updat= eLog [junit4] 2> 631529 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH = numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 631529 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .RequestHandlers Registered paths: /config,/admin/system,/admin/plugins,/ad= min/mbeans,/admin/file,/admin/luke,/admin/threads,/select,/admin/segments,/= update/csv,/update/json/docs,/update/json,/replication,/schema,/admin/prope= rties,/get,/admin/logging,/update,/admin/ping [junit4] 2> 631530 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .CommitTracker Hard AutoCommit: disabled [junit4] 2> 631530 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .CommitTracker Soft AutoCommit: disabled [junit4] 2> 631530 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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@abdd8 lo= ckFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@135df1d),segF= N=3Dsegments_1,generation=3D1} [junit4] 2> 631530 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 631530 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.s= .SolrIndexSearcher Opening Searcher@15fa3ac[testschemaapi_shard1_replica2] = main [junit4] 2> 631530 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .c.ZkStateReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .c.ZkStateReader path=3D[/collections/testschemaapi] [configName]=3D[conf1]= specified config exists in ZooKeeper [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .ManagedResourceStorage Setting up ZooKeeper-based storage for the RestMana= ger with znodeBase: /configs/conf1 [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /conf= igs/conf1 [junit4] 2> 631531 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrCore Using default statsCache cache: org.apache.solr.search.stats.Loca= lStatsCache [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .RestManager Initializing RestManager with initArgs: {} [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO= :path=3D/configs/conf1 [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .ManagedResourceStorage No data found for znode /configs/conf1/_rest_manage= d.json [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKee= perStorageIO:path=3D/configs/conf1 [junit4] 2> 631531 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Updat= eLog [junit4] 2> 631531 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.r= .RestManager Initializing 0 registered ManagedResources [junit4] 2> 631532 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH = numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.s= .ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /co= nfigs/conf1/managed-schema [junit4] 2> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/= conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica2:555876686= 2276788 [junit4] 2> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.h= .ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 631532 INFO (searcherExecutor-2381-thread-1-processing-c:= testschemaapi x:testschemaapi_shard1_replica2 n:127.0.0.1:38644_solr r:core= _node1 s:shard1) [n:127.0.0.1:38644_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@15fa3ac[testschemaapi_shard1_re= plica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .VersionInfo Refreshing highest value of _version_ for 65536 version bucket= s from index [junit4] 2> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .VersionInfo No terms found for _version_, cannot seed version bucket highe= st value from index [junit4] 2> 631532 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .UpdateLog Could not find max version in index or recent updates, using new= clock 1559077618051973120 [junit4] 2> 631533 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .CommitTracker Hard AutoCommit: disabled [junit4] 2> 631533 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .CommitTracker Soft AutoCommit: disabled [junit4] 2> 631534 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi 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@8b449c l= ockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@145881a),seg= FN=3Dsegments_1,generation=3D1} [junit4] 2> 631534 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .SolrDeletionPolicy newest commit generation =3D 1 [junit4] 2> 631534 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.s= .SolrIndexSearcher Opening Searcher@156df12[testschemaapi_shard1_replica1] = main [junit4] 2> 631534 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.u= .UpdateLog Took 1.0ms to seed version buckets with highest version 15590776= 18051973120 [junit4] 2> 631534 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController watch zkdir /configs/conf1 [junit4] 2> 631534 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .c.ZkStateReader Load collection config from: [/collections/testschemaapi] [junit4] 2> 631534 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .CoreContainer registering core: testschemaapi_shard1_replica2 [junit4] 2> 631534 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .c.ZkStateReader path=3D[/collections/testschemaapi] [configName]=3D[conf1]= specified config exists in ZooKeeper [junit4] 2> 631534 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController Register replica - core:testschemaapi_shard1_replica2 address= :http://127.0.0.1:38644/solr collection:testschemaapi shard:shard1 [junit4] 2> 631534 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .ManagedResourceStorage Setting up ZooKeeper-based storage for the RestMana= ger with znodeBase: /configs/conf1 [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /conf= igs/conf1 [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .RestManager Initializing RestManager with initArgs: {} [junit4] 2> 631535 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1/el= ection [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO= :path=3D/configs/conf1 [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .ManagedResourceStorage No data found for znode /configs/conf1/_rest_manage= d.json [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKee= perStorageIO:path=3D/configs/conf1 [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.r= .RestManager Initializing 0 registered ManagedResources [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.s= .ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /co= nfigs/conf1/managed-schema [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/= conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica1:555876686= 2387721 [junit4] 2> 631535 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.h= .ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 631536 INFO (searcherExecutor-2382-thread-1-processing-x:= testschemaapi_shard1_replica1 c:testschemaapi n:127.0.0.1:43268_solr r:core= _node2 s:shard1) [n:127.0.0.1:43268_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@156df12[testschemaapi_shard1_re= plica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 631536 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi 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> 631536 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .VersionInfo Refreshing highest value of _version_ for 65536 version bucket= s from index [junit4] 2> 631536 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .VersionInfo No terms found for _version_, cannot seed version bucket highe= st value from index [junit4] 2> 631536 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi 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 1559077618056167424 [junit4] 2> 631537 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .LeaderElector Joined leadership election with path: /collections/testschem= aapi/leader_elect/shard1/election/97442351039119365-core_node1-n_0000000000 [junit4] 2> 631537 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ShardLeaderElectionContext Running the leader process for shard=3Dshard1 a= nd weAreReplacement=3Dfalse and leaderVoteWait=3D10000 [junit4] 2> 631537 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.u= .UpdateLog Took 1.0ms to seed version buckets with highest version 15590776= 18056167424 [junit4] 2> 631537 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .ZkController watch zkdir /configs/conf1 [junit4] 2> 631538 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ShardLeaderElectionContext Waiting until we see more replicas up for shard= shard1: total=3D2 found=3D1 timeoutin=3D9999ms [junit4] 2> 631538 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_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> 631538 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .CoreContainer registering core: testschemaapi_shard1_replica1 [junit4] 2> 631538 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .ZkController Register replica - core:testschemaapi_shard1_replica1 address= :http://127.0.0.1:43268/solr collection:testschemaapi shard:shard1 [junit4] 2> 631539 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .LeaderElector Joined leadership election with path: /collections/testschem= aapi/leader_elect/shard1/election/97442351039119366-core_node2-n_0000000001 [junit4] 2> 631539 INFO (qtp5891478-4785) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .LeaderElector Watching path /collections/testschemaapi/leader_elect/shard1= /election/97442351039119365-core_node1-n_0000000000 to know if I could be t= he leader [junit4] 2> 631639 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 1 [junit4] 2> 631640 INFO (zkCallback-778-thread-2-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_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> 631640 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_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> 631640 INFO (zkCallback-778-thread-2-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [1] to [2] [junit4] 2> 631640 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [1] to [2] [junit4] 2> 632038 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 632038 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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> 632038 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .SyncStrategy Sync replicas to http://127.0.0.1:38644/solr/testschemaapi_sh= ard1_replica2/ [junit4] 2> 632038 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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:38644/solr START replicas=3D[http://127.0.0.1:43268/solr/testschemaapi= _shard1_replica1/] nUpdates=3D100 [junit4] 2> 632039 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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:38644/solr DONE. We have no versions. sync failed. [junit4] 2> 632039 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi 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 nex= t candidate [junit4] 2> 632039 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ShardLeaderElectionContext We failed sync, but we have no versions - we ca= n't sync in that case - we were active before, so become leader anyway [junit4] 2> 632040 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .c.SolrZkClient makePath: /collections/testschemaapi/leaders/shard1 [junit4] 2> 632040 INFO (qtp5891478-4787) [n:127.0.0.1:43268_solr c:t= estschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica1] o.a.s.c= .S.Request [testschemaapi_shard1_replica1] webapp=3D/solr path=3D/get para= ms=3D{getVersions=3D100&fingerprint=3Dfalse&version=3D2&qt=3D/get&distrib= =3Dfalse&wt=3Djavabin} status=3D0 QTime=3D0 [junit4] 2> 632040 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ShardLeaderElectionContextBase Creating leader registration node /collecti= ons/testschemaapi/leaders/shard1/leader after winning as /collections/tests= chemaapi/leader_elect/shard1/election/97442351039119365-core_node1-n_000000= 0000 [junit4] 2> 632041 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38644/sol= r/testschemaapi_shard1_replica2/ shard1 [junit4] 2> 632042 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_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:38644/solr", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 632143 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 2 [junit4] 2> 632143 INFO (zkCallback-778-thread-1-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_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> 632143 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_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> 632143 INFO (zkCallback-778-thread-1-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [2] to [3] [junit4] 2> 632143 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_solr ] o.a.s.c.c.ZkStateReader Updatin= g data for [testschemaapi] from [2] to [3] [junit4] 2> 632192 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController We are http://127.0.0.1:38644/solr/testschemaapi_shard1_repli= ca2/ and leader is http://127.0.0.1:38644/solr/testschemaapi_shard1_replica= 2/ [junit4] 2> 632192 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController No LogReplay needed for core=3Dtestschemaapi_shard1_replica2 = baseURL=3Dhttp://127.0.0.1:38644/solr [junit4] 2> 632192 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController I am the leader, no recovery necessary [junit4] 2> 632192 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.c= .ZkController publishing state=3Dactive [junit4] 2> 632193 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.Overseer = processMessage: queueSize: 1, message =3D { [junit4] 2> "base_url":"http://127.0.0.1:38644/solr", [junit4] 2> "state":"active", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "node_name":"127.0.0.1:38644_solr", [junit4] 2> "shard":"shard1"} current state version: 0 [junit4] 2> 632193 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.Replica= Mutator Update state numShards=3D1 message=3D{ [junit4] 2> "base_url":"http://127.0.0.1:38644/solr", [junit4] 2> "state":"active", [junit4] 2> "collection":"testschemaapi", [junit4] 2> "core":"testschemaapi_shard1_replica2", [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "node_name":"127.0.0.1:38644_solr", [junit4] 2> "shard":"shard1"} [junit4] 2> 632193 INFO (qtp5891478-4784) [n:127.0.0.1:38644_solr c:t= estschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica2] o.a.s.s= .HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{action=3D= CREATE&collection=3Dtestschemaapi&version=3D2&qt=3D/admin/cores&collection.= configName=3Dconf1&numShards=3D1&wt=3Djavabin&shard=3Dshard1&name=3Dtestsch= emaapi_shard1_replica2} status=3D0 QTime=3D1730 [junit4] 2> 632294 INFO (OverseerStateUpdate-97442351039119366-127.0.= 0.1:43268_solr-n_0000000000) [n:127.0.0.1:43268_solr ] o.a.s.c.o.ZkState= Writer going to update_collection /collections/testschemaapi/state.json ver= sion: 3 [junit4] 2> 632294 INFO (zkCallback-778-thread-1-processing-n:127.0.0= .1:43268_solr) [n:127.0.0.1:43268_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> 632294 INFO (zkCallback-777-thread-1-processing-n:127.0.0= .1:38644_solr) [n:127.0.0.1:38644_solr ] o.a.s.c.c.ZkStateReader A clust= er state change: [WatchedEvent state:SyncConnected=20 [...truncated too long message...] xecute(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> =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> =09at org.apache.solr.core.CoreContainer.reload(CoreContai= ner.java:920) [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> 640841 INFO (Thread-1579) [n:127.0.0.1:38644_solr ] o.= a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 640841 WARN (Thread-1578) [n:127.0.0.1:43268_solr ] o.= a.s.c.ZkController listener throws error [junit4] 2> org.apache.solr.common.SolrException: Unable to reload cor= e [testschemaapi_shard1_replica1] [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_replica1: 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> 640841 ERROR (Thread-1579) [n:127.0.0.1:38644_solr ] o.= a.s.s.ManagedIndexSchemaFactory Error attempting to access /configs/conf1/m= anaged-schema [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredExcepti= on: KeeperErrorCode =3D Session expired for /configs/conf1/managed-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> =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> =09at org.apache.solr.core.CoreContainer.reload(CoreContai= ner.java:920) [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> 640842 WARN (Thread-1579) [n:127.0.0.1:38644_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> NOTE: test params are: codec=3DAsserting(Lucene54), sim=3D= DefaultSimilarity, locale=3Det, timezone=3DAsia/Istanbul [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.7.0= _80 (32-bit)/cpus=3D12,threads=3D1,free=3D268035256,total=3D400556032 [junit4] 2> NOTE: All tests run in this JVM: [TestRestoreCore, SSLMigr= ationTest, PluginInfoTest, TestSerializedLuceneMatchVersion, SolrTestCaseJ4= Test, TestRecovery, ReplicationFactorTest, TestHdfsUpdateLog, TestExpandCom= ponent, TestCSVResponseWriter, FieldAnalysisRequestHandlerTest, NoCacheHead= erTest, TestRawTransformer, TestReRankQParserPlugin, CurrencyFieldXmlFileTe= st, UpdateParamsTest, TestManagedResource, TestBulkSchemaAPI, DistributedQu= eueTest, TestShardHandlerFactory, TestDynamicLoading, RuleEngineTest, TestS= tressRecovery, SortSpecParsingTest, DirectSolrConnectionTest, BasicDistribu= tedZk2Test, TestWriterPerf, DateMathParserTest, SuggesterWFSTTest, TestAnal= yzeInfixSuggestions, DistributedSuggestComponentTest, ShowFileRequestHandle= rTest, OverseerTest, TestFuzzyAnalyzedSuggestions, TestIndexSearcher, Block= JoinFacetSimpleTest, TestSweetSpotSimilarityFactory, TestReload, JavabinLoa= derTest, SliceStateTest, TestClusterStateMutator, FullSolrCloudDistribCmdsT= est, TestStressReorder, TestExtendedDismaxParser, TestFieldTypeResource, Ov= erseerRolesTest, TestCloudSchemaless, TestCoreDiscovery, SuggesterTSTTest, = AtomicUpdatesTest, TestFreeTextSuggestions, ClusterStateTest, TestLeaderIni= tiatedRecoveryThread, DocValuesMultiTest, HdfsChaosMonkeyNothingIsSafeTest,= TestClassicSimilarityFactory, PeerSyncReplicationTest, TestPHPSerializedRe= sponseWriter, DirectUpdateHandlerOptimizeTest, LukeRequestHandlerTest, Stat= elessScriptUpdateProcessorFactoryTest, BadComponentTest, HighlighterConfigT= est, BitVectorTest, SolrCloudExampleTest, BlockDirectoryTest, DataDrivenBlo= ckJoinTest, BJQParserTest, InfoHandlerTest, TestManagedSchemaAPI] [junit4] Completed [216/575 (1!)] on J2 in 10.93s, 1 test, 1 error <<< F= AILURES! [...truncated 50248 lines...] ------=_Part_478_1552231960.1486854418751 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_478_1552231960.1486854418751--