Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 5EE1FECA1 for ; Thu, 28 Feb 2013 01:51:19 +0000 (UTC) Received: (qmail 73382 invoked by uid 500); 28 Feb 2013 01:51:17 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 73331 invoked by uid 500); 28 Feb 2013 01:51:17 -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 73317 invoked by uid 99); 28 Feb 2013 01:51:17 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 28 Feb 2013 01:51:17 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=NORMAL_HTTP_TO_IP,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of jenkins@thetaphi.de designates 188.138.97.18 as permitted sender) Received: from [188.138.97.18] (HELO mail.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 28 Feb 2013 01:50:54 +0000 Received: from serv1 (localhost.localdomain [127.0.0.1]) by mail.sd-datasolutions.de (Postfix) with ESMTP id B049214AA051 for ; Thu, 28 Feb 2013 01:50:33 +0000 (UTC) Date: Thu, 28 Feb 2013 01:49:41 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <218536071.9.1362016233089.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b65) - Build # 4484 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_8_1581766628.1362016181583" X-Jenkins-Job: Lucene-Solr-4.x-Linux X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_8_1581766628.1362016181583 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4484/ Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseParallelGC 1 tests failed. FAILED: org.apache.solr.cloud.UnloadDistributedZkTest.testDistribSearch Error Message: Still found shard Stack Trace: java.lang.AssertionError: Still found shard =09at __randomizedtesting.SeedInfo.seed([21E8CCAD63FCE607:A00E42B514A3863B]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndColle= ction(UnloadDistributedZkTest.java:120) =09at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistribute= dZkTest.java:73) =09at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseD= istributedSearchTestCase.java:805) =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:474) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1559) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(Random= izedRunner.java:79) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:773) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:787) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFi= eldCacheSanity.java:51) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:782) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:442) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:746) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:648) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:682) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:693) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:53) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRul= e$1.evaluate(SystemPropertiesInvariantRule.java:55) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:43) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:70) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:358) =09at java.lang.Thread.run(Thread.java:722) Build Log: [...truncated 9640 lines...] [junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest [junit4:junit4] 2> 0 T2937 oas.BaseDistributedSearchTestCase.initHostCont= ext Setting hostContext system property: / [junit4:junit4] 2> 3 T2937 oas.SolrTestCaseJ4.setUp ###Starting testDistr= ibSearch [junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-So= lr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTes= t-1362016023233 [junit4:junit4] 2> 4 T2937 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4:junit4] 2> 4 T2938 oasc.ZkTestServer$ZKServerMain.runFromConfig S= tarting server [junit4:junit4] 2> 5 T2938 oazs.ZooKeeperServer.setTickTime tickTime set = to 1000 [junit4:junit4] 2> 5 T2938 oazs.ZooKeeperServer.setMinSessionTimeout minS= essionTimeout set to -1 [junit4:junit4] 2> 5 T2938 oazs.ZooKeeperServer.setMaxSessionTimeout maxS= essionTimeout set to -1 [junit4:junit4] 2> 5 T2938 oazs.NIOServerCnxnFactory.configure binding to= port 0.0.0.0/0.0.0.0:0 [junit4:junit4] 2> 6 T2938 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to= /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test= /J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023232/zookeeper= /server1/data/version-2/snapshot.0 [junit4:junit4] 2> 104 T2937 oasc.ZkTestServer.run start zk server on por= t:44988 [junit4:junit4] 2> 105 T2937 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:44988 sessionTimeout=3D10000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@38dbc19d [junit4:junit4] 2> 105 T2937 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 105 T2943 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:44988. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 106 T2943 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:44988, initi= ating session [junit4:junit4] 2> 106 T2939 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:59430 [junit4:junit4] 2> 106 T2939 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:59430 [junit4:junit4] 2> 106 T2941 oazsp.FileTxnLog.append Creating new log fil= e: log.1 [junit4:junit4] 2> 108 T2941 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13d1e7b32c30000 with negotiated timeout 10000 for client /= 127.0.0.1:59430 [junit4:junit4] 2> 108 T2943 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:44988, s= essionid =3D 0x13d1e7b32c30000, negotiated timeout =3D 10000 [junit4:junit4] 2> 109 T2944 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@38dbc19d name:ZooKeeperConnectio= n Watcher:127.0.0.1:44988 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4:junit4] 2> 109 T2937 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 109 T2937 oascc.SolrZkClient.makePath makePath: /solr [junit4:junit4] 2> 111 T2942 oazs.PrepRequestProcessor.pRequest2Txn Proce= ssed session termination for sessionid: 0x13d1e7b32c30000 [junit4:junit4] 2> 111 T2944 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 111 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c300= 00 closed [junit4:junit4] 2> 111 T2937 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@1313401a [junit4:junit4] 2> 112 T2939 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:59430 which had sessionid 0x13d1e7b32c30000 [junit4:junit4] 2> 112 T2945 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:44988. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 112 T2937 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 113 T2945 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:44988, initi= ating session [junit4:junit4] 2> 113 T2939 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:59431 [junit4:junit4] 2> 113 T2939 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:59431 [junit4:junit4] 2> 113 T2941 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13d1e7b32c30001 with negotiated timeout 10000 for client /= 127.0.0.1:59431 [junit4:junit4] 2> 113 T2945 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:44988, s= essionid =3D 0x13d1e7b32c30001, negotiated timeout =3D 10000 [junit4:junit4] 2> 114 T2946 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1313401a name:ZooKeeperConnectio= n Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 114 T2937 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 114 T2937 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4:junit4] 2> 116 T2937 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4:junit4] 2> 117 T2937 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4:junit4] 2> 118 T2937 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4:junit4] 2> 119 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4:junit4] 2> 119 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4:junit4] 2> 122 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4:junit4] 2> 122 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4:junit4] 2> 124 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4:junit4] 2> 125 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4:junit4] 2> 126 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4:junit4] 2> 126 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4:junit4] 2> 127 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4:junit4] 2> 128 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4:junit4] 2> 129 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-excha= nge-rates.json [junit4:junit4] 2> 130 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4:junit4] 2> 133 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping= -ISOLatin1Accent.txt [junit4:junit4] 2> 133 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4:junit4] 2> 135 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4:junit4] 2> 135 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4:junit4] 2> 139 T2937 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/= solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4:junit4] 2> 140 T2937 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4:junit4] 2> 141 T2942 oazs.PrepRequestProcessor.pRequest2Txn Proce= ssed session termination for sessionid: 0x13d1e7b32c30001 [junit4:junit4] 2> 142 T2939 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:59431 which had sessionid 0x13d1e7b32c30001 [junit4:junit4] 2> 142 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c300= 01 closed [junit4:junit4] 2> 142 T2946 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 188 T2937 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 194 T2937 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:55361 [junit4:junit4] 2> 194 T2937 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4:junit4] 2> 195 T2937 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 195 T2937 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x= -Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribut= edZkTest-controljetty-1362016023371 [junit4:junit4] 2> 195 T2937 oasc.CoreContainer$Initializer.initialize lo= oking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/b= uild/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-cont= roljetty-1362016023371/solr.xml [junit4:junit4] 2> 195 T2937 oasc.CoreContainer. New CoreContainer = 460101674 [junit4:junit4] 2> 196 T2937 oasc.CoreContainer.load Loading CoreContaine= r using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/b= uild/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-cont= roljetty-1362016023371/' [junit4:junit4] 2> 196 T2937 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/= solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTes= t-controljetty-1362016023371/' [junit4:junit4] 2> 210 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 120000 [junit4:junit4] 2> 210 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4:junit4] 2> 211 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4:junit4] 2> 211 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 211 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4:junit4] 2> 211 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 212 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4:junit4] 2> 212 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4:junit4] 2> 212 T2937 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4:junit4] 2> 213 T2937 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&s= ocketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 221 T2937 oasc.CoreContainer.load Registering Log List= ener [junit4:junit4] 2> 229 T2937 oasc.CoreContainer.initZooKeeper Zookeeper c= lient=3D127.0.0.1:44988/solr [junit4:junit4] 2> 229 T2937 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4:junit4] 2> 230 T2937 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:44988 sessionTimeout=3D60000 watcher=3Dorg= .apache.solr.common.cloud.ConnectionManager@2e66d086 [junit4:junit4] 2> 230 T2937 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 230 T2956 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:44988. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 231 T2956 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:44988, initi= ating session [junit4:junit4] 2> 231 T2939 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:59432 [junit4:junit4] 2> 231 T2939 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:59432 [junit4:junit4] 2> 232 T2941 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13d1e7b32c30002 with negotiated timeout 20000 for client /= 127.0.0.1:59432 [junit4:junit4] 2> 232 T2956 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:44988, s= essionid =3D 0x13d1e7b32c30002, negotiated timeout =3D 20000 [junit4:junit4] 2> 232 T2957 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@2e66d086 name:ZooKeeperConnectio= n Watcher:127.0.0.1:44988 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4:junit4] 2> 232 T2937 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 233 T2942 oazs.PrepRequestProcessor.pRequest2Txn Proce= ssed session termination for sessionid: 0x13d1e7b32c30002 [junit4:junit4] 2> 234 T2957 oaz.ClientCnxn$EventThread.run EventThread s= hut down [junit4:junit4] 2> 234 T2939 oazs.NIOServerCnxn.closeSock Closed socket c= onnection for client /127.0.0.1:59432 which had sessionid 0x13d1e7b32c30002 [junit4:junit4] 2> 234 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c300= 02 closed [junit4:junit4] 2> 234 T2937 oascsi.HttpClientUtil.createClient Creating = new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&soc= ketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 236 T2937 oaz.ZooKeeper. Initiating client conne= ction, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@2604b17c [junit4:junit4] 2> 237 T2937 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4:junit4] 2> 237 T2958 oaz.ClientCnxn$SendThread.logStartConnect Op= ening socket connection to server localhost.localdomain/127.0.0.1:44988. Wi= ll not attempt to authenticate using SASL (access denied ("javax.security.a= uth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 237 T2958 oaz.ClientCnxn$SendThread.primeConnection So= cket connection established to localhost.localdomain/127.0.0.1:44988, initi= ating session [junit4:junit4] 2> 237 T2939 oazs.NIOServerCnxnFactory.run Accepted socke= t connection from /127.0.0.1:59433 [junit4:junit4] 2> 238 T2939 oazs.ZooKeeperServer.processConnectRequest C= lient attempting to establish new session at /127.0.0.1:59433 [junit4:junit4] 2> 238 T2941 oazs.ZooKeeperServer.finishSessionInit Estab= lished session 0x13d1e7b32c30003 with negotiated timeout 20000 for client /= 127.0.0.1:59433 [junit4:junit4] 2> 238 T2958 oaz.ClientCnxn$SendThread.onConnected Sessio= n establishment complete on server localhost.localdomain/127.0.0.1:44988, s= essionid =3D 0x13d1e7b32c30003, negotiated timeout =3D 20000 [junit4:junit4] 2> 238 T2959 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@2604b17c name:ZooKeeperConnectio= n Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4:junit4] 2> 239 T2937 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4:junit4] 2> 239 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:crea= te cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Erro= r:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 240 T2937 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4:junit4] 2> 241 T2937 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:55361_ [junit4:junit4] 2> 241 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:dele= te cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/12= 7.0.0.1:55361_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0.= 0.1:55361_ [junit4:junit4] 2> 242 T2937 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:55361_ [junit4:junit4] 2> 243 T2937 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4:junit4] 2> 245 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:dele= te cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_ele= ct/leader Error:KeeperErrorCode =3D NoNode for /solr/overseer_elect/leader [junit4:junit4] 2> 246 T2937 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4:junit4] 2> 247 T2937 oasc.Overseer.start Overseer (id=3D892610820= 98728963-127.0.0.1:55361_-n_0000000000) starting [junit4:junit4] 2> 247 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:crea= te cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 248 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:crea= te cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 248 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:crea= te cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 249 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:crea= te cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 249 T2961 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4:junit4] 2> 249 T2937 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4:junit4] 2> 251 T2937 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 252 T2960 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4:junit4] 2> 254 T2962 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x= -Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribut= edZkTest-controljetty-1362016023371/collection1 [junit4:junit4] 2> 254 T2962 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4:junit4] 2> 254 T2962 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4:junit4] 2> 254 T2962 oasc.ZkController.readConfigName Load collec= tion config from:/collections/control_collection [junit4:junit4] 2> 255 T2962 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/= solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTes= t-controljetty-1362016023371/collection1/' [junit4:junit4] 2> 256 T2962 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1= 362016023371/collection1/lib/README' to classloader [junit4:junit4] 2> 256 T2962 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1= 362016023371/collection1/lib/classes/' to classloader [junit4:junit4] 2> 274 T2962 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_42 [junit4:junit4] 2> 276 T1786 oasc.OverseerCollectionProcessor.run WARNING= Overseer cannot talk to ZK [junit4:junit4] 2> 297 T2962 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4:junit4] 2> 297 T2962 oass.IndexSchema.readSchema Reading Solr Sch= ema [junit4:junit4] 2> 300 T2962 oass.IndexSchema.readSchema Schema name=3Dte= st [junit4:junit4] 2> 575 T2962 oass.OpenExchangeRatesOrgProvider.init Initi= alized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 580 T2962 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4:junit4] 2> 582 T2962 oass.IndexSchema.readSchema unique key field= : id [junit4:junit4] 2> 589 T2962 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 591 T2962 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4:junit4] 2> 593 T2962 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 594 T2962 oass.OpenExchangeRatesOrgProvider.reload Rel= oading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 595 T2962 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/= solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controlje= tty-1362016023371/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDis= tributedZkTest-1362016023232/control/data/ [junit4:junit4] 2> 595 T2962 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@36314ab8 [junit4:junit4] 2> 595 T2962 oasc.SolrCore.initDirectoryFactory org.apach= e.solr.core.MockDirectoryFactory [junit4:junit4] 2> 596 T2962 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023= 232/control/data forceNew:false [junit4:junit4] 2> 596 T2962 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr= -core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023232= /control/data [junit4:junit4] 2> 596 T2962 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZk= Test-1362016023232/control/data/index/ [junit4:junit4] 2> 597 T2962 oasc.SolrCore.initIndex WARNING [collection1= ] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-136= 2016023232/control/data/index' doesn't exist. Creating new index... [junit4:junit4] 2> 597 T2962 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/s= olr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023= 232/control/data/index forceNew:false [junit4:junit4] 2> 598 T2962 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@7b607a75 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@3f9a5790),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 598 T2962 oasc.SolrDeletionPolicy.updateCommits newest= commit =3D 1[segments_1] [junit4:junit4] 2> 599 T2962 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr= -core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023232= /control/data/index [junit4:junit4] 2> 604 T2962 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4:junit4] 2> 604 T2962 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4:junit4] 2> 604 T2962 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4:junit4] 2> 605 T2962 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4:junit4] 2> 605 T2962 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 605 T2962 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 605 T2962 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4:junit4] 2> 606 T2962 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 606 T2962 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 609 T2962 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4:junit4] 2> 612 T2962 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr= -core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023232= /control/data [junit4:junit4] 2> 612 T2962 oass.SolrIndexSearcher. Opening Search= er@46ba129e main [junit4:junit4] 2> 612 T2962 oasu.UpdateHandler.clearLog Clearing tlog fi= les, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/control/data/tlog [junit4:junit4] 2> 613 T2962 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4:junit4] 2> 613 T2962 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4:junit4] 2> 617 T2962 oasc.CachingDirectoryFactory.close Releasing= directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr= -core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023232= /control/data [junit4:junit4] 2> 618 T2963 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@46ba129e main{StandardDirectoryReader(seg= ments_1:1)} [junit4:junit4] 2> 624 T2962 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4:junit4] 2> 624 T2962 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4:junit4] 2> 625 T2942 oazs.PrepRequestProcessor.pRequest Got user-= level KeeperException when processing sessionid:0x13d1e7b32c30003 type:crea= te cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/que= ue Error:KeeperErrorCode =3D NoNode for /solr/overseer/queue [junit4:junit4] 2> 1753 T2960 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 1754 T2960 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:55361", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"control_collection", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:55361_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 1754 T2960 oasc.Overseer$ClusterStateUpdater.createCol= lection Create collection control_collection with numShards 1 [junit4:junit4] 2> 1754 T2960 oasc.Overseer$ClusterStateUpdater.updateSta= te Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 1755 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30003 type:cre= ate cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/qu= eue-work Error:KeeperErrorCode =3D NoNode for /solr/overseer/queue-work [junit4:junit4] 2> 1757 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 1) [junit4:junit4] 2> 1888 T1796 oasc.OverseerCollectionProcessor.run WARNIN= G Overseer cannot talk to ZK [junit4:junit4] 2> 2628 T2962 oasc.CoreContainer.registerCore registering= core: collection1 [junit4:junit4] 2> 2629 T2962 oasc.ZkController.register Register replica= - core:collection1 address:http://127.0.0.1:55361 collection:control_colle= ction shard:shard1 [junit4:junit4] 2> 2629 T2962 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leader_elect/shard1/election [junit4:junit4] 2> 2633 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30003 type:del= ete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections= /control_collection/leaders Error:KeeperErrorCode =3D NoNode for /solr/coll= ections/control_collection/leaders [junit4:junit4] 2> 2633 T2962 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 2634 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30003 type:cre= ate cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 2635 T2962 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 2635 T2962 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 2635 T2962 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:55361/collection1/ [junit4:junit4] 2> 2635 T2962 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 2635 T2962 oasc.SyncStrategy.syncToMe http://127.0.0.1= :55361/collection1/ has no replicas [junit4:junit4] 2> 2636 T2962 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:55361/collection1/ [junit4:junit4] 2> 2636 T2962 oascc.SolrZkClient.makePath makePath: /coll= ections/control_collection/leaders/shard1 [junit4:junit4] 2> 2638 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30003 type:cre= ate cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3262 T2960 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3268 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 1) [junit4:junit4] 2> 3290 T2962 oasc.ZkController.register We are http://12= 7.0.0.1:55361/collection1/ and leader is http://127.0.0.1:55361/collection1= / [junit4:junit4] 2> 3291 T2962 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:55361 [junit4:junit4] 2> 3291 T2962 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 3291 T2962 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Dactive [junit4:junit4] 2> 3291 T2962 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 3293 T2962 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 3294 T2937 oass.SolrDispatchFilter.init user.dir=3D/mn= t/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 3295 T2937 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 3296 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 3301 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config: [junit4:junit4] 2> 3302 T2937 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D10000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@2d3ccf44 [junit4:junit4] 2> 3303 T2937 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3303 T2964 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:44988. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 3304 T2964 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:44988, init= iating session [junit4:junit4] 2> 3304 T2939 oazs.NIOServerCnxnFactory.run Accepted sock= et connection from /127.0.0.1:59439 [junit4:junit4] 2> 3305 T2939 oazs.ZooKeeperServer.processConnectRequest = Client attempting to establish new session at /127.0.0.1:59439 [junit4:junit4] 2> 3305 T2941 oazs.ZooKeeperServer.finishSessionInit Esta= blished session 0x13d1e7b32c30004 with negotiated timeout 10000 for client = /127.0.0.1:59439 [junit4:junit4] 2> 3305 T2964 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:44988, = sessionid =3D 0x13d1e7b32c30004, negotiated timeout =3D 10000 [junit4:junit4] 2> 3306 T2965 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@2d3ccf44 name:ZooKeeperConnecti= on Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 3306 T2937 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3307 T2937 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3309 T2937 oasc.ChaosMonkey.monkeyLog monkey: init - e= xpire sessions:true cause connection loss:true [junit4:junit4] 2> 3376 T2937 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 3377 T2937 oejs.AbstractConnector.doStart Started Sele= ctChannelConnector@127.0.0.1:53427 [junit4:junit4] 2> 3378 T2937 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 3378 T2937 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 3379 T2937 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.= x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-jetty1-1362016026539 [junit4:junit4] 2> 3379 T2937 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty1-1362016026539/solr.xml [junit4:junit4] 2> 3379 T2937 oasc.CoreContainer. New CoreContainer= 967168916 [junit4:junit4] 2> 3380 T2937 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty1-1362016026539/' [junit4:junit4] 2> 3380 T2937 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-jetty1-1362016026539/' [junit4:junit4] 2> 3394 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 120000 [junit4:junit4] 2> 3395 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 3395 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 15000 [junit4:junit4] 2> 3395 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 3395 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 3396 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 3396 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 3396 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 3397 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 3397 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 3402 T2937 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 3410 T2937 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:44988/solr [junit4:junit4] 2> 3410 T2937 oasc.ZkController.checkChrootPath zkHost in= cludes chroot [junit4:junit4] 2> 3410 T2937 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:44988 sessionTimeout=3D60000 watcher=3Dor= g.apache.solr.common.cloud.ConnectionManager@34dd3c9a [junit4:junit4] 2> 3411 T2937 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3411 T2975 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:44988. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 3412 T2975 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:44988, init= iating session [junit4:junit4] 2> 3412 T2939 oazs.NIOServerCnxnFactory.run Accepted sock= et connection from /127.0.0.1:59440 [junit4:junit4] 2> 3412 T2939 oazs.ZooKeeperServer.processConnectRequest = Client attempting to establish new session at /127.0.0.1:59440 [junit4:junit4] 2> 3413 T2941 oazs.ZooKeeperServer.finishSessionInit Esta= blished session 0x13d1e7b32c30005 with negotiated timeout 20000 for client = /127.0.0.1:59440 [junit4:junit4] 2> 3413 T2975 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:44988, = sessionid =3D 0x13d1e7b32c30005, negotiated timeout =3D 20000 [junit4:junit4] 2> 3413 T2976 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@34dd3c9a name:ZooKeeperConnecti= on Watcher:127.0.0.1:44988 got event WatchedEvent state:SyncConnected type:= None path:null path:null type:None [junit4:junit4] 2> 3413 T2937 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3414 T2942 oazs.PrepRequestProcessor.pRequest2Txn Proc= essed session termination for sessionid: 0x13d1e7b32c30005 [junit4:junit4] 2> 3414 T2976 oaz.ClientCnxn$EventThread.run EventThread = shut down [junit4:junit4] 2> 3414 T2939 oazs.NIOServerCnxn.closeSock Closed socket = connection for client /127.0.0.1:59440 which had sessionid 0x13d1e7b32c3000= 5 [junit4:junit4] 2> 3414 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c30= 005 closed [junit4:junit4] 2> 3415 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&so= cketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 3417 T2937 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@11b6999f [junit4:junit4] 2> 3417 T2977 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:44988. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 3417 T2937 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 3418 T2977 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:44988, init= iating session [junit4:junit4] 2> 3418 T2939 oazs.NIOServerCnxnFactory.run Accepted sock= et connection from /127.0.0.1:59441 [junit4:junit4] 2> 3418 T2939 oazs.ZooKeeperServer.processConnectRequest = Client attempting to establish new session at /127.0.0.1:59441 [junit4:junit4] 2> 3418 T2941 oazs.ZooKeeperServer.finishSessionInit Esta= blished session 0x13d1e7b32c30006 with negotiated timeout 20000 for client = /127.0.0.1:59441 [junit4:junit4] 2> 3418 T2977 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:44988, = sessionid =3D 0x13d1e7b32c30006, negotiated timeout =3D 20000 [junit4:junit4] 2> 3419 T2978 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@11b6999f name:ZooKeeperConnecti= on Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 3419 T2937 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 3419 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30006 type:cre= ate cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3420 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30006 type:cre= ate cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 3421 T2937 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 3642 T1813 oasc.OverseerCollectionProcessor.run WARNIN= G Overseer cannot talk to ZK [junit4:junit4] 2> 4422 T2937 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:53427_ [junit4:junit4] 2> 4423 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30006 type:del= ete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/1= 27.0.0.1:53427_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0= .0.1:53427_ [junit4:junit4] 2> 4423 T2937 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:53427_ [junit4:junit4] 2> 4425 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 1) [junit4:junit4] 2> 4425 T2978 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 4425 T2965 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 4426 T2959 oascc.ZkStateReader$3.process Updating live= nodes... (2) [junit4:junit4] 2> 4429 T2979 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.= x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-jetty1-1362016026539/collection1 [junit4:junit4] 2> 4429 T2979 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 4429 T2979 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 4430 T2979 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 4430 T2979 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-jetty1-1362016026539/collection1/' [junit4:junit4] 2> 4431 T2979 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-136201= 6026539/collection1/lib/README' to classloader [junit4:junit4] 2> 4431 T2979 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-136201= 6026539/collection1/lib/classes/' to classloader [junit4:junit4] 2> 4463 T2979 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_42 [junit4:junit4] 2> 4488 T2979 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 4489 T2979 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 4491 T2979 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 4763 T2979 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 4767 T2979 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 4769 T2979 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 4771 T2960 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 4772 T2960 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:55361", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"control_collection", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:55361_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 4774 T2978 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 4774 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 4775 T2965 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 4777 T2979 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4779 T2979 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 4780 T2979 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4781 T2979 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 4782 T2979 oasc.SolrCore. [collection1] Opening = new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1= 362016026539/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistribu= tedZkTest-1362016023232/jetty1/ [junit4:junit4] 2> 4782 T2979 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@36314ab8 [junit4:junit4] 2> 4782 T2979 oasc.SolrCore.initDirectoryFactory org.apac= he.solr.core.MockDirectoryFactory [junit4:junit4] 2> 4783 T2979 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/= solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602= 3232/jetty1 forceNew:false [junit4:junit4] 2> 4783 T2979 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/jetty1 [junit4:junit4] 2> 4783 T2979 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZ= kTest-1362016023232/jetty1/index/ [junit4:junit4] 2> 4783 T2979 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-13= 62016023232/jetty1/index' doesn't exist. Creating new index... [junit4:junit4] 2> 4783 T2979 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/= solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602= 3232/jetty1/index forceNew:false [junit4:junit4] 2> 4784 T2979 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@36c782a4 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@41b7c380),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 4784 T2979 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 4785 T2979 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/jetty1/index [junit4:junit4] 2> 4786 T2979 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 4786 T2979 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 4786 T2979 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 4786 T2979 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 4787 T2979 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 4787 T2979 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 4787 T2979 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 4787 T2979 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 4787 T2979 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 4789 T2979 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 4791 T2979 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/jetty1 [junit4:junit4] 2> 4791 T2979 oass.SolrIndexSearcher. Opening Searc= her@1859e0f0 main [junit4:junit4] 2> 4791 T2979 oasu.UpdateHandler.clearLog Clearing tlog f= iles, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty1/tlog [junit4:junit4] 2> 4792 T2979 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 4792 T2979 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 4795 T2980 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@1859e0f0 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 4796 T2979 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 4796 T2979 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 6277 T2960 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 6277 T2960 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:53427", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:53427_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 6277 T2960 oasc.Overseer$ClusterStateUpdater.createCol= lection Create collection collection1 with numShards 2 [junit4:junit4] 2> 6277 T2960 oasc.Overseer$ClusterStateUpdater.updateSta= te Assigning new node to shard shard=3Dshard2 [junit4:junit4] 2> 6279 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 6279 T2978 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 6279 T2965 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 6797 T2979 oasc.CoreContainer.registerCore registering= core: collection1 [junit4:junit4] 2> 6798 T2979 oasc.ZkController.register Register replica= - core:collection1 address:http://127.0.0.1:53427 collection:collection1 s= hard:shard2 [junit4:junit4] 2> 6798 T2979 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leader_elect/shard2/election [junit4:junit4] 2> 6802 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30006 type:del= ete cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections= /collection1/leaders Error:KeeperErrorCode =3D NoNode for /solr/collections= /collection1/leaders [junit4:junit4] 2> 6802 T2979 oasc.ShardLeaderElectionContext.runLeaderPr= ocess Running the leader process. [junit4:junit4] 2> 6802 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30006 type:cre= ate cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 6803 T2979 oasc.ShardLeaderElectionContext.waitForRepl= icasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 6803 T2979 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I may be the new leader - try and sync [junit4:junit4] 2> 6804 T2979 oasc.SyncStrategy.sync Sync replicas to htt= p://127.0.0.1:53427/collection1/ [junit4:junit4] 2> 6804 T2979 oasc.SyncStrategy.syncReplicas Sync Success= - now sync replicas to me [junit4:junit4] 2> 6804 T2979 oasc.SyncStrategy.syncToMe http://127.0.0.1= :53427/collection1/ has no replicas [junit4:junit4] 2> 6804 T2979 oasc.ShardLeaderElectionContext.runLeaderPr= ocess I am the new leader: http://127.0.0.1:53427/collection1/ [junit4:junit4] 2> 6804 T2979 oascc.SolrZkClient.makePath makePath: /coll= ections/collection1/leaders/shard2 [junit4:junit4] 2> 6806 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30006 type:cre= ate cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 7719 T1817 oasc.OverseerCollectionProcessor.run WARNIN= G Overseer cannot talk to ZK [junit4:junit4] 2> 7782 T2960 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7786 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 7787 T2978 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 7787 T2965 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 7809 T2979 oasc.ZkController.register We are http://12= 7.0.0.1:53427/collection1/ and leader is http://127.0.0.1:53427/collection1= / [junit4:junit4] 2> 7809 T2979 oasc.ZkController.register No LogReplay nee= ded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:53427 [junit4:junit4] 2> 7810 T2979 oasc.ZkController.checkRecovery I am the le= ader, no recovery necessary [junit4:junit4] 2> 7810 T2979 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Dactive [junit4:junit4] 2> 7810 T2979 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 7811 T2979 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 7812 T2937 oass.SolrDispatchFilter.init user.dir=3D/mn= t/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 7813 T2937 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() done [junit4:junit4] 2> 7813 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&fo= llowRedirects=3Dfalse [junit4:junit4] 2> 7883 T2937 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 7888 T2937 oejs.AbstractConnector.doStart Started Sele= ctChannelConnector@127.0.0.1:57300 [junit4:junit4] 2> 7889 T2937 oass.SolrDispatchFilter.init SolrDispatchFi= lter.init() [junit4:junit4] 2> 7890 T2937 oasc.SolrResourceLoader.locateSolrHome JNDI= not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 7890 T2937 oasc.SolrResourceLoader.locateSolrHome usin= g system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.= x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-jetty2-1362016031046 [junit4:junit4] 2> 7891 T2937 oasc.CoreContainer$Initializer.initialize l= ooking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty2-1362016031046/solr.xml [junit4:junit4] 2> 7891 T2937 oasc.CoreContainer. New CoreContainer= 1283479965 [junit4:junit4] 2> 7892 T2937 oasc.CoreContainer.load Loading CoreContain= er using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/= build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jet= ty2-1362016031046/' [junit4:junit4] 2> 7892 T2937 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-jetty2-1362016031046/' [junit4:junit4] 2> 7917 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting socketTimeout to: 120000 [junit4:junit4] 2> 7917 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting urlScheme to: http:// [junit4:junit4] 2> 7918 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting connTimeout to: 15000 [junit4:junit4] 2> 7918 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 7919 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting corePoolSize to: 0 [junit4:junit4] 2> 7919 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 7920 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 7920 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting sizeOfQueue to: -1 [junit4:junit4] 2> 7921 T2937 oashc.HttpShardHandlerFactory.getParameter = Setting fairnessPolicy to: false [junit4:junit4] 2> 7921 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000&= socketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 7930 T2937 oasc.CoreContainer.load Registering Log Lis= tener [junit4:junit4] 2> 7943 T2937 oasc.CoreContainer.initZooKeeper Zookeeper = client=3D127.0.0.1:44988/solr [junit4:junit4] 2> 7944 T2937 oasc.ZkController.checkChrootPath zkHost in= cludes chroot [junit4:junit4] 2> 7944 T2937 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:44988 sessionTimeout=3D60000 watcher=3Dor= g.apache.solr.common.cloud.ConnectionManager@62699400 [junit4:junit4] 2> 7945 T2937 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 7946 T2990 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:44988. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 7947 T2990 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:44988, init= iating session [junit4:junit4] 2> 7947 T2939 oazs.NIOServerCnxnFactory.run Accepted sock= et connection from /127.0.0.1:59466 [junit4:junit4] 2> 7947 T2939 oazs.ZooKeeperServer.processConnectRequest = Client attempting to establish new session at /127.0.0.1:59466 [junit4:junit4] 2> 7948 T2941 oazs.ZooKeeperServer.finishSessionInit Esta= blished session 0x13d1e7b32c30007 with negotiated timeout 20000 for client = /127.0.0.1:59466 [junit4:junit4] 2> 7948 T2990 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:44988, = sessionid =3D 0x13d1e7b32c30007, negotiated timeout =3D 20000 [junit4:junit4] 2> 7948 T2991 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@62699400 name:ZooKeeperConnecti= on Watcher:127.0.0.1:44988 got event WatchedEvent state:SyncConnected type:= None path:null path:null type:None [junit4:junit4] 2> 7949 T2937 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 7949 T2942 oazs.PrepRequestProcessor.pRequest2Txn Proc= essed session termination for sessionid: 0x13d1e7b32c30007 [junit4:junit4] 2> 7950 T2939 oazs.NIOServerCnxn.closeSock Closed socket = connection for client /127.0.0.1:59466 which had sessionid 0x13d1e7b32c3000= 7 [junit4:junit4] 2> 7950 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c30= 007 closed [junit4:junit4] 2> 7950 T2991 oaz.ClientCnxn$EventThread.run EventThread = shut down [junit4:junit4] 2> 7951 T2937 oascsi.HttpClientUtil.createClient Creating= new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&so= cketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 7954 T2937 oaz.ZooKeeper. Initiating client conn= ection, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D30000 watcher= =3Dorg.apache.solr.common.cloud.ConnectionManager@4edc18fa [junit4:junit4] 2> 7955 T2992 oaz.ClientCnxn$SendThread.logStartConnect O= pening socket connection to server localhost.localdomain/127.0.0.1:44988. W= ill not attempt to authenticate using SASL (access denied ("javax.security.= auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 7955 T2937 oascc.ConnectionManager.waitForConnected Wa= iting for client to connect to ZooKeeper [junit4:junit4] 2> 7956 T2939 oazs.NIOServerCnxnFactory.run Accepted sock= et connection from /127.0.0.1:59467 [junit4:junit4] 2> 7956 T2992 oaz.ClientCnxn$SendThread.primeConnection S= ocket connection established to localhost.localdomain/127.0.0.1:44988, init= iating session [junit4:junit4] 2> 7956 T2939 oazs.ZooKeeperServer.processConnectRequest = Client attempting to establish new session at /127.0.0.1:59467 [junit4:junit4] 2> 7957 T2941 oazs.ZooKeeperServer.finishSessionInit Esta= blished session 0x13d1e7b32c30008 with negotiated timeout 20000 for client = /127.0.0.1:59467 [junit4:junit4] 2> 7957 T2992 oaz.ClientCnxn$SendThread.onConnected Sessi= on establishment complete on server localhost.localdomain/127.0.0.1:44988, = sessionid =3D 0x13d1e7b32c30008, negotiated timeout =3D 20000 [junit4:junit4] 2> 7957 T2993 oascc.ConnectionManager.process Watcher org= .apache.solr.common.cloud.ConnectionManager@4edc18fa name:ZooKeeperConnecti= on Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4:junit4] 2> 7957 T2937 oascc.ConnectionManager.waitForConnected Cl= ient is connected to ZooKeeper [junit4:junit4] 2> 7958 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30008 type:cre= ate cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 7959 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30008 type:cre= ate cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Err= or:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 8002 T2937 oascc.ZkStateReader.createClusterStateWatch= ersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 9004 T2937 oasc.ZkController.createEphemeralLiveNode R= egister node as live in ZooKeeper:/live_nodes/127.0.0.1:57300_ [junit4:junit4] 2> 9005 T2942 oazs.PrepRequestProcessor.pRequest Got user= -level KeeperException when processing sessionid:0x13d1e7b32c30008 type:del= ete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/1= 27.0.0.1:57300_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.0= .0.1:57300_ [junit4:junit4] 2> 9005 T2937 oascc.SolrZkClient.makePath makePath: /live= _nodes/127.0.0.1:57300_ [junit4:junit4] 2> 9007 T2965 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 2) [junit4:junit4] 2> 9007 T2978 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 9007 T2978 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 9007 T2993 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 9008 T2959 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 9008 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/= live_nodes, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 9008 T2965 oascc.ZkStateReader$3.process Updating live= nodes... (3) [junit4:junit4] 2> 9013 T2994 oasc.CoreContainer.create Creating SolrCore= 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.= x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistribu= tedZkTest-jetty2-1362016031046/collection1 [junit4:junit4] 2> 9014 T2994 oasc.ZkController.createCollectionZkNode Ch= eck for collection zkNode:collection1 [junit4:junit4] 2> 9014 T2994 oasc.ZkController.createCollectionZkNode Co= llection zkNode exists [junit4:junit4] 2> 9014 T2994 oasc.ZkController.readConfigName Load colle= ction config from:/collections/collection1 [junit4:junit4] 2> 9016 T2994 oasc.SolrResourceLoader. new SolrReso= urceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux= /solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTe= st-jetty2-1362016031046/collection1/' [junit4:junit4] 2> 9016 T2994 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-136201= 6031046/collection1/lib/README' to classloader [junit4:junit4] 2> 9016 T2994 oasc.SolrResourceLoader.replaceClassLoader = Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-136201= 6031046/collection1/lib/classes/' to classloader [junit4:junit4] 2> 9046 T2994 oasc.SolrConfig. Using Lucene MatchVe= rsion: LUCENE_42 [junit4:junit4] 2> 9089 T2994 oasc.SolrConfig. Loaded SolrConfig: s= olrconfig.xml [junit4:junit4] 2> 9089 T2994 oass.IndexSchema.readSchema Reading Solr Sc= hema [junit4:junit4] 2> 9094 T2994 oass.IndexSchema.readSchema Schema name=3Dt= est [junit4:junit4] 2> 9290 T2960 oascc.ZkStateReader.updateClusterState Upda= ting cloud state from ZooKeeper...=20 [junit4:junit4] 2> 9290 T2960 oasc.Overseer$ClusterStateUpdater.updateSta= te Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:53427", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:53427_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 9294 T2978 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 9294 T2993 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 9294 T2959 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 9294 T2965 oascc.ZkStateReader$2.process A cluster sta= te change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clus= terstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 9472 T2994 oass.OpenExchangeRatesOrgProvider.init Init= ialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 9479 T2994 oass.IndexSchema.readSchema default search = field in schema is text [junit4:junit4] 2> 9481 T2994 oass.IndexSchema.readSchema unique key fiel= d: id [junit4:junit4] 2> 9488 T2994 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 9491 T2994 oass.FileExchangeRateProvider.reload Reload= ing exchange rates from file currency.xml [junit4:junit4] 2> 9494 T2994 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9495 T2994 oass.OpenExchangeRatesOrgProvider.reload Re= loading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 9495 T2994 oasc.SolrCore. [collection1] Opening = new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1= 362016031046/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistribu= tedZkTest-1362016023232/jetty2/ [junit4:junit4] 2> 9496 T2994 oasc.JmxMonitoredMap. JMX monitoring = is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMB= eanServer@36314ab8 [junit4:junit4] 2> 9496 T2994 oasc.SolrCore.initDirectoryFactory org.apac= he.solr.core.MockDirectoryFactory [junit4:junit4] 2> 9497 T2994 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/= solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602= 3232/jetty2 forceNew:false [junit4:junit4] 2> 9497 T2994 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/jetty2 [junit4:junit4] 2> 9497 T2994 oasc.SolrCore.getNewIndexDir New index dire= ctory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributedZ= kTest-1362016023232/jetty2/index/ [junit4:junit4] 2> 9497 T2994 oasc.SolrCore.initIndex WARNING [collection= 1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-13= 62016023232/jetty2/index' doesn't exist. Creating new index... [junit4:junit4] 2> 9498 T2994 oasc.CachingDirectoryFactory.get return new= directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/= solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602= 3232/jetty2/index forceNew:false [junit4:junit4] 2> 9499 T2994 oasc.SolrDeletionPolicy.onCommit SolrDeleti= onPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@3c7759c5 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@5954430f),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 9499 T2994 oasc.SolrDeletionPolicy.updateCommits newes= t commit =3D 1[segments_1] [junit4:junit4] 2> 9500 T2994 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/jetty2/index [junit4:junit4] 2> 9501 T2994 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= edupe" [junit4:junit4] 2> 9501 T2994 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "s= tored_sig" [junit4:junit4] 2> 9502 T2994 oasup.UpdateRequestProcessorChain.init inse= rting DistributedUpdateProcessorFactory into updateRequestProcessorChain "d= istrib-dup-test-chain-implicit" [junit4:junit4] 2> 9502 T2994 oasc.RequestHandlers.initHandlersFromConfig= created standard: solr.StandardRequestHandler [junit4:junit4] 2> 9502 T2994 oasc.RequestHandlers.initHandlersFromConfig= created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 9503 T2994 oasc.RequestHandlers.initHandlersFromConfig= adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 9503 T2994 oasc.RequestHandlers.initHandlersFromConfig= created /replication: solr.ReplicationHandler [junit4:junit4] 2> 9503 T2994 oasc.RequestHandlers.initHandlersFromConfig= created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 9504 T2994 oasc.RequestHandlers.initHandlersFromConfig= created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 9507 T2994 oashl.XMLLoader.init xsltCacheLifetimeSecon= ds=3D60 [junit4:junit4] 2> 9510 T2994 oasc.CachingDirectoryFactory.close Releasin= g directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/sol= r-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-136201602323= 2/jetty2 [junit4:junit4] 2> 9510 T2994 oass.SolrIndexSearcher. Opening Searc= her@237975e7 main [junit4:junit4] 2> 9510 T2994 oasu.UpdateHandler.clearLog Clearing tlog f= iles, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty2/tlog [junit4:junit4] 2> 9511 T2994 oasu.CommitTracker. Hard AutoCommit: = disabled [junit4:junit4] 2> 9511 T2994 oasu.CommitTracker. Soft AutoCommit: = disabled [junit4:junit4] 2> 9514 T2995 oasc.SolrCore.registerSearcher [collection1= ] Registered new searcher Searcher@237975e7 main{StandardDirectoryReader(se= gments_1:1)} [junit4:junit4] 2> 9515 T2994 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 9516 T2994 oasc.ZkController.publish numShards not fou= nd on descriptor - reading it from system property [junit4:junit4] 2> 9891 T1832 oasc.OverseerCollectionProcessor.run WARNIN= G Overseer cannot talk to ZK [junit4:junit4] 2> 10797 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 10797 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:57300", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:57300_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 10798 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Collection already exists with numShards=3D2 [junit4:junit4] 2> 10798 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 10800 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10800 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10800 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 10800 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 11519 T2994 oasc.CoreContainer.registerCore registerin= g core: collection1 [junit4:junit4] 2> 11519 T2994 oasc.ZkController.register Register replic= a - core:collection1 address:http://127.0.0.1:57300 collection:collection1 = shard:shard1 [junit4:junit4] 2> 11520 T2994 oascc.SolrZkClient.makePath makePath: /col= lections/collection1/leader_elect/shard1/election [junit4:junit4] 2> 11523 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c30008 type:de= lete cxid:0x45 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collection= s/collection1/leaders/shard1 Error:KeeperErrorCode =3D NoNode for /solr/col= lections/collection1/leaders/shard1 [junit4:junit4] 2> 11523 T2994 oasc.ShardLeaderElectionContext.runLeaderP= rocess Running the leader process. [junit4:junit4] 2> 11524 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c30008 type:cr= eate cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer E= rror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 11525 T2994 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 11525 T2994 oasc.ShardLeaderElectionContext.runLeaderP= rocess I may be the new leader - try and sync [junit4:junit4] 2> 11525 T2994 oasc.SyncStrategy.sync Sync replicas to ht= tp://127.0.0.1:57300/collection1/ [junit4:junit4] 2> 11525 T2994 oasc.SyncStrategy.syncReplicas Sync Succes= s - now sync replicas to me [junit4:junit4] 2> 11525 T2994 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:57300/collection1/ has no replicas [junit4:junit4] 2> 11525 T2994 oasc.ShardLeaderElectionContext.runLeaderP= rocess I am the new leader: http://127.0.0.1:57300/collection1/ [junit4:junit4] 2> 11525 T2994 oascc.SolrZkClient.makePath makePath: /col= lections/collection1/leaders/shard1 [junit4:junit4] 2> 11527 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c30008 type:cr= eate cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer E= rror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 12302 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12306 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12307 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12307 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12306 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 3) [junit4:junit4] 2> 12330 T2994 oasc.ZkController.register We are http://1= 27.0.0.1:57300/collection1/ and leader is http://127.0.0.1:57300/collection= 1/ [junit4:junit4] 2> 12330 T2994 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:57300 [junit4:junit4] 2> 12330 T2994 oasc.ZkController.checkRecovery I am the l= eader, no recovery necessary [junit4:junit4] 2> 12330 T2994 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Dactive [junit4:junit4] 2> 12330 T2994 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 12332 T2994 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 12333 T2937 oass.SolrDispatchFilter.init user.dir=3D/m= nt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 12333 T2937 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 12334 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 12401 T2937 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 12403 T2937 oejs.AbstractConnector.doStart Started Sel= ectChannelConnector@127.0.0.1:48840 [junit4:junit4] 2> 12404 T2937 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 12404 T2937 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 12405 T2937 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4= .x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty3-1362016035565 [junit4:junit4] 2> 12405 T2937 oasc.CoreContainer$Initializer.initialize = looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty3-1362016035565/solr.xml [junit4:junit4] 2> 12405 T2937 oasc.CoreContainer. New CoreContaine= r 854834686 [junit4:junit4] 2> 12406 T2937 oasc.CoreContainer.load Loading CoreContai= ner using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty3-1362016035565/' [junit4:junit4] 2> 12406 T2937 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty3-1362016035565/' [junit4:junit4] 2> 12420 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 120000 [junit4:junit4] 2> 12421 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 12421 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 15000 [junit4:junit4] 2> 12421 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 12422 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 12422 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 12422 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 12422 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 12423 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 12423 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 12436 T2937 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 12444 T2937 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:44988/solr [junit4:junit4] 2> 12444 T2937 oasc.ZkController.checkChrootPath zkHost i= ncludes chroot [junit4:junit4] 2> 12445 T2937 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:44988 sessionTimeout=3D60000 watcher=3Do= rg.apache.solr.common.cloud.ConnectionManager@4bd985a2 [junit4:junit4] 2> 12446 T2937 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 12446 T3005 oaz.ClientCnxn$SendThread.logStartConnect = Opening socket connection to server localhost.localdomain/127.0.0.1:44988. = Will not attempt to authenticate using SASL (access denied ("javax.security= .auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 12447 T3005 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost.localdomain/127.0.0.1:44988, ini= tiating session [junit4:junit4] 2> 12447 T2939 oazs.NIOServerCnxnFactory.run Accepted soc= ket connection from /127.0.0.1:59478 [junit4:junit4] 2> 12447 T2939 oazs.ZooKeeperServer.processConnectRequest= Client attempting to establish new session at /127.0.0.1:59478 [junit4:junit4] 2> 12447 T2941 oazs.ZooKeeperServer.finishSessionInit Est= ablished session 0x13d1e7b32c30009 with negotiated timeout 20000 for client= /127.0.0.1:59478 [junit4:junit4] 2> 12448 T3005 oaz.ClientCnxn$SendThread.onConnected Sess= ion establishment complete on server localhost.localdomain/127.0.0.1:44988,= sessionid =3D 0x13d1e7b32c30009, negotiated timeout =3D 20000 [junit4:junit4] 2> 12448 T3006 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@4bd985a2 name:ZooKeeperConnect= ion Watcher:127.0.0.1:44988 got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4:junit4] 2> 12448 T2937 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 12449 T2942 oazs.PrepRequestProcessor.pRequest2Txn Pro= cessed session termination for sessionid: 0x13d1e7b32c30009 [junit4:junit4] 2> 12450 T3006 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> 12450 T2939 oazs.NIOServerCnxn.closeSock Closed socket= connection for client /127.0.0.1:59478 which had sessionid 0x13d1e7b32c300= 09 [junit4:junit4] 2> 12450 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c3= 0009 closed [junit4:junit4] 2> 12450 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&s= ocketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 12459 T2937 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D30000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@50691e2b [junit4:junit4] 2> 12460 T2937 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 12460 T3007 oaz.ClientCnxn$SendThread.logStartConnect = Opening socket connection to server localhost.localdomain/127.0.0.1:44988. = Will not attempt to authenticate using SASL (access denied ("javax.security= .auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 12461 T3007 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost.localdomain/127.0.0.1:44988, ini= tiating session [junit4:junit4] 2> 12461 T2939 oazs.NIOServerCnxnFactory.run Accepted soc= ket connection from /127.0.0.1:59479 [junit4:junit4] 2> 12461 T2939 oazs.ZooKeeperServer.processConnectRequest= Client attempting to establish new session at /127.0.0.1:59479 [junit4:junit4] 2> 12462 T2941 oazs.ZooKeeperServer.finishSessionInit Est= ablished session 0x13d1e7b32c3000a with negotiated timeout 20000 for client= /127.0.0.1:59479 [junit4:junit4] 2> 12462 T3007 oaz.ClientCnxn$SendThread.onConnected Sess= ion establishment complete on server localhost.localdomain/127.0.0.1:44988,= sessionid =3D 0x13d1e7b32c3000a, negotiated timeout =3D 20000 [junit4:junit4] 2> 12462 T3008 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@50691e2b name:ZooKeeperConnect= ion Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 12462 T2937 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 12463 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c3000a type:cr= eate cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 12463 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c3000a type:cr= eate cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 12464 T2937 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 13466 T2937 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48840_ [junit4:junit4] 2> 13466 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c3000a type:de= lete cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/= 127.0.0.1:48840_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.= 0.0.1:48840_ [junit4:junit4] 2> 13467 T2937 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:48840_ [junit4:junit4] 2> 13469 T2965 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 13469 T3008 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 13469 T2959 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 13469 T2978 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 13469 T2993 oascc.ZkStateReader$3.process Updating liv= e nodes... (4) [junit4:junit4] 2> 13469 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13469 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13469 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13469 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13473 T3009 oasc.CoreContainer.create Creating SolrCor= e 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4= .x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty3-1362016035565/collection1 [junit4:junit4] 2> 13473 T3009 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 13474 T3009 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 13474 T3009 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 13474 T3009 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty3-1362016035565/collection1/' [junit4:junit4] 2> 13475 T3009 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-13620= 16035565/collection1/lib/README' to classloader [junit4:junit4] 2> 13475 T3009 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-13620= 16035565/collection1/lib/classes/' to classloader [junit4:junit4] 2> 13495 T3009 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_42 [junit4:junit4] 2> 13520 T3009 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 13521 T3009 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 13523 T3009 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 13791 T3009 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 13795 T3009 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 13797 T3009 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 13804 T3009 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 13806 T3009 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 13808 T3009 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 13809 T3009 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 13809 T3009 oasc.SolrCore. [collection1] Opening= new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-= 1362016035565/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistrib= utedZkTest-1362016023232/jetty3/ [junit4:junit4] 2> 13810 T3009 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@36314ab8 [junit4:junit4] 2> 13810 T3009 oasc.SolrCore.initDirectoryFactory org.apa= che.solr.core.MockDirectoryFactory [junit4:junit4] 2> 13811 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 13811 T3009 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160= 23232/jetty3 forceNew:false [junit4:junit4] 2> 13812 T3009 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty3 [junit4:junit4] 2> 13812 T3009 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributed= ZkTest-1362016023232/jetty3/index/ [junit4:junit4] 2> 13812 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:57300", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:57300_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 13812 T3009 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1= 362016023232/jetty3/index' doesn't exist. Creating new index... [junit4:junit4] 2> 13812 T3009 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160= 23232/jetty3/index forceNew:false [junit4:junit4] 2> 13813 T3009 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@9ffcec4 lockFactory=3Dorg.apache.lucene.store.Native= FSLockFactory@77d2c4a4),segFN=3Dsegments_1,generation=3D1,filenames=3D[segm= ents_1] [junit4:junit4] 2> 13814 T3009 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 13814 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13814 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13814 T3009 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty3/index [junit4:junit4] 2> 13814 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13814 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13814 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 13816 T3009 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 13816 T3009 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 13816 T3009 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 13817 T3009 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 13817 T3009 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 13817 T3009 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 13817 T3009 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 13818 T3009 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 13818 T3009 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 13820 T3009 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 13821 T3009 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty3 [junit4:junit4] 2> 13822 T3009 oass.SolrIndexSearcher. Opening Sear= cher@1f9e6299 main [junit4:junit4] 2> 13822 T3009 oasu.UpdateHandler.clearLog Clearing tlog = files, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023= 232/jetty3/tlog [junit4:junit4] 2> 13823 T3009 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 13823 T3009 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 13826 T3010 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1f9e6299 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 13827 T3009 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 13827 T3009 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 15318 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 15318 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:48840", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:48840_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 15319 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Collection already exists with numShards=3D2 [junit4:junit4] 2> 15319 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Assigning new node to shard shard=3Dshard2 [junit4:junit4] 2> 15452 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15452 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15452 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15452 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15452 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 15834 T3009 oasc.CoreContainer.registerCore registerin= g core: collection1 [junit4:junit4] 2> 15835 T3009 oasc.ZkController.register Register replic= a - core:collection1 address:http://127.0.0.1:48840 collection:collection1 = shard:shard2 [junit4:junit4] 2> 15837 T3009 oasc.ZkController.register We are http://1= 27.0.0.1:48840/collection1/ and leader is http://127.0.0.1:53427/collection= 1/ [junit4:junit4] 2> 15837 T3009 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:48840 [junit4:junit4] 2> 15837 T3009 oasc.ZkController.checkRecovery Core needs= to recover:collection1 [junit4:junit4] 2> 15837 T3009 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> 15838 T3009 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> ASYNC NEW_CORE C151 name=3Dcollection1 org.apache.sol= r.core.SolrCore@5a33d9b9 url=3Dhttp://127.0.0.1:48840/collection1 node=3D12= 7.0.0.1:48840_ C151_STATE=3Dcoll:collection1 core:collection1 props:{base_u= rl=3Dhttp://127.0.0.1:48840, collection=3Dcollection1, state=3Ddown, node_n= ame=3D127.0.0.1:48840_, core=3Dcollection1} [junit4:junit4] 2> 15838 T3011 C151 P48840 oasc.RecoveryStrategy.run Star= ting recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 15839 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry ###### startupVersions=3D[] [junit4:junit4] 2> 15839 T3011 C151 P48840 oasc.ZkController.publish publ= ishing core=3Dcollection1 state=3Drecovering [junit4:junit4] 2> 15839 T3011 C151 P48840 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 15839 T2937 oass.SolrDispatchFilter.init user.dir=3D/m= nt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 15840 T2937 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 15840 T3011 C151 P48840 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 15840 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 15925 T2937 oejs.Server.doStart jetty-8.1.8.v20121106 [junit4:junit4] 2> 15927 T2937 oejs.AbstractConnector.doStart Started Sel= ectChannelConnector@127.0.0.1:44018 [junit4:junit4] 2> 15928 T2937 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() [junit4:junit4] 2> 15929 T2937 oasc.SolrResourceLoader.locateSolrHome JND= I not configured for solr (NoInitialContextEx) [junit4:junit4] 2> 15929 T2937 oasc.SolrResourceLoader.locateSolrHome usi= ng system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4= .x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty4-1362016039073 [junit4:junit4] 2> 15930 T2937 oasc.CoreContainer$Initializer.initialize = looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty4-1362016039073/solr.xml [junit4:junit4] 2> 15930 T2937 oasc.CoreContainer. New CoreContaine= r 26852862 [junit4:junit4] 2> 15931 T2937 oasc.CoreContainer.load Loading CoreContai= ner using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr= /build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-je= tty4-1362016039073/' [junit4:junit4] 2> 15931 T2937 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty4-1362016039073/' [junit4:junit4] 2> 15955 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting socketTimeout to: 120000 [junit4:junit4] 2> 15955 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting urlScheme to: http:// [junit4:junit4] 2> 15956 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting connTimeout to: 15000 [junit4:junit4] 2> 15956 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting maxConnectionsPerHost to: 20 [junit4:junit4] 2> 15956 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting corePoolSize to: 0 [junit4:junit4] 2> 15957 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting maximumPoolSize to: 2147483647 [junit4:junit4] 2> 15957 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting maxThreadIdleTime to: 5 [junit4:junit4] 2> 15958 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting sizeOfQueue to: -1 [junit4:junit4] 2> 15958 T2937 oashc.HttpShardHandlerFactory.getParameter= Setting fairnessPolicy to: false [junit4:junit4] 2> 15959 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnectionsPerHost=3D20&maxConnections=3D10000= &socketTimeout=3D120000&connTimeout=3D15000&retry=3Dfalse [junit4:junit4] 2> 15967 T2937 oasc.CoreContainer.load Registering Log Li= stener [junit4:junit4] 2> 15978 T2937 oasc.CoreContainer.initZooKeeper Zookeeper= client=3D127.0.0.1:44988/solr [junit4:junit4] 2> 15979 T2937 oasc.ZkController.checkChrootPath zkHost i= ncludes chroot [junit4:junit4] 2> 15979 T2937 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:44988 sessionTimeout=3D60000 watcher=3Do= rg.apache.solr.common.cloud.ConnectionManager@2a7e435c [junit4:junit4] 2> 15980 T2937 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 15980 T3021 oaz.ClientCnxn$SendThread.logStartConnect = Opening socket connection to server localhost.localdomain/127.0.0.1:44988. = Will not attempt to authenticate using SASL (access denied ("javax.security= .auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 15981 T3021 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost.localdomain/127.0.0.1:44988, ini= tiating session [junit4:junit4] 2> 15981 T2939 oazs.NIOServerCnxnFactory.run Accepted soc= ket connection from /127.0.0.1:59494 [junit4:junit4] 2> 15981 T2939 oazs.ZooKeeperServer.processConnectRequest= Client attempting to establish new session at /127.0.0.1:59494 [junit4:junit4] 2> 15982 T2941 oazs.ZooKeeperServer.finishSessionInit Est= ablished session 0x13d1e7b32c3000b with negotiated timeout 20000 for client= /127.0.0.1:59494 [junit4:junit4] 2> 15982 T3021 oaz.ClientCnxn$SendThread.onConnected Sess= ion establishment complete on server localhost.localdomain/127.0.0.1:44988,= sessionid =3D 0x13d1e7b32c3000b, negotiated timeout =3D 20000 [junit4:junit4] 2> 15982 T3022 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@2a7e435c name:ZooKeeperConnect= ion Watcher:127.0.0.1:44988 got event WatchedEvent state:SyncConnected type= :None path:null path:null type:None [junit4:junit4] 2> 15983 T2937 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 15983 T2942 oazs.PrepRequestProcessor.pRequest2Txn Pro= cessed session termination for sessionid: 0x13d1e7b32c3000b [junit4:junit4] 2> 15984 T3022 oaz.ClientCnxn$EventThread.run EventThread= shut down [junit4:junit4] 2> 15984 T2939 oazs.NIOServerCnxn.closeSock Closed socket= connection for client /127.0.0.1:59494 which had sessionid 0x13d1e7b32c300= 0b [junit4:junit4] 2> 15984 T2937 oaz.ZooKeeper.close Session: 0x13d1e7b32c3= 000b closed [junit4:junit4] 2> 15984 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D500&maxConnectionsPerHost=3D16&s= ocketTimeout=3D120000&connTimeout=3D15000 [junit4:junit4] 2> 15986 T2937 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D30000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@225846a0 [junit4:junit4] 2> 15987 T3023 oaz.ClientCnxn$SendThread.logStartConnect = Opening socket connection to server localhost.localdomain/127.0.0.1:44988. = Will not attempt to authenticate using SASL (access denied ("javax.security= .auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 15987 T2937 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 15988 T3023 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost.localdomain/127.0.0.1:44988, ini= tiating session [junit4:junit4] 2> 15988 T2939 oazs.NIOServerCnxnFactory.run Accepted soc= ket connection from /127.0.0.1:59495 [junit4:junit4] 2> 15988 T2939 oazs.ZooKeeperServer.processConnectRequest= Client attempting to establish new session at /127.0.0.1:59495 [junit4:junit4] 2> 15989 T2941 oazs.ZooKeeperServer.finishSessionInit Est= ablished session 0x13d1e7b32c3000c with negotiated timeout 20000 for client= /127.0.0.1:59495 [junit4:junit4] 2> 15989 T3023 oaz.ClientCnxn$SendThread.onConnected Sess= ion establishment complete on server localhost.localdomain/127.0.0.1:44988,= sessionid =3D 0x13d1e7b32c3000c, negotiated timeout =3D 20000 [junit4:junit4] 2> 15989 T3024 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@225846a0 name:ZooKeeperConnect= ion Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 15989 T2937 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 15990 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c3000c type:cr= eate cxid:0x1 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 15990 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c3000c type:cr= eate cxid:0x2 zxid:0xa3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Er= ror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 15991 T2937 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 16955 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 16956 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:48840", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:48840_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 16959 T3024 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16959 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16959 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16959 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16959 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16960 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16992 T2937 oasc.ZkController.createEphemeralLiveNode = Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44018_ [junit4:junit4] 2> 16993 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c3000c type:de= lete cxid:0xb zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/= 127.0.0.1:44018_ Error:KeeperErrorCode =3D NoNode for /solr/live_nodes/127.= 0.0.1:44018_ [junit4:junit4] 2> 16994 T2937 oascc.SolrZkClient.makePath makePath: /liv= e_nodes/127.0.0.1:44018_ [junit4:junit4] 2> 16995 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 4) [junit4:junit4] 2> 16995 T3024 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 16995 T3024 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 16995 T2959 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 16995 T2993 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 16996 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 16995 T2978 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 16995 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 16996 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 16996 T3008 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 16996 T2965 oascc.ZkStateReader$3.process Updating liv= e nodes... (5) [junit4:junit4] 2> 16996 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:= /live_nodes, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 17007 T3025 oasc.CoreContainer.create Creating SolrCor= e 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4= .x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistrib= utedZkTest-jetty4-1362016039073/collection1 [junit4:junit4] 2> 17007 T3025 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:collection1 [junit4:junit4] 2> 17007 T3025 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 17007 T3025 oasc.ZkController.readConfigName Load coll= ection config from:/collections/collection1 [junit4:junit4] 2> 17008 T3025 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty4-1362016039073/collection1/' [junit4:junit4] 2> 17009 T3025 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-13620= 16039073/collection1/lib/README' to classloader [junit4:junit4] 2> 17009 T3025 oasc.SolrResourceLoader.replaceClassLoader= Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/s= olr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-13620= 16039073/collection1/lib/classes/' to classloader [junit4:junit4] 2> 17039 T3025 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_42 [junit4:junit4] 2> 17080 T3025 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 17081 T3025 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 17085 T3025 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 17715 T3025 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 17720 T3025 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 17722 T3025 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 17729 T3025 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 17732 T3025 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 17734 T3025 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 17739 T3025 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 17740 T3025 oasc.SolrCore. [collection1] Opening= new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-= 1362016039073/collection1/, dataDir=3D./org.apache.solr.cloud.UnloadDistrib= utedZkTest-1362016023232/jetty4/ [junit4:junit4] 2> 17741 T3025 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@36314ab8 [junit4:junit4] 2> 17741 T3025 oasc.SolrCore.initDirectoryFactory org.apa= che.solr.core.MockDirectoryFactory [junit4:junit4] 2> 17749 T3025 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160= 23232/jetty4 forceNew:false [junit4:junit4] 2> 17749 T3025 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty4 [junit4:junit4] 2> 17749 T3025 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D./org.apache.solr.cloud.UnloadDistributed= ZkTest-1362016023232/jetty4/index/ [junit4:junit4] 2> 17750 T3025 oasc.SolrCore.initIndex WARNING [collectio= n1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1= 362016023232/jetty4/index' doesn't exist. Creating new index... [junit4:junit4] 2> 17750 T3025 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160= 23232/jetty4/index forceNew:false [junit4:junit4] 2> 17755 T3025 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@3feb966a lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@71e10be5),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 17756 T3025 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 17756 T3025 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty4/index [junit4:junit4] 2> 17758 T3025 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 17758 T3025 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 17758 T3025 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 17759 T3025 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 17759 T3025 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 17759 T3025 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 17760 T3025 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 17760 T3025 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 17760 T3025 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 17763 T3025 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 17769 T3025 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty4 [junit4:junit4] 2> 17769 T3025 oass.SolrIndexSearcher. Opening Sear= cher@1cbac719 main [junit4:junit4] 2> 17769 T3025 oasu.UpdateHandler.clearLog Clearing tlog = files, tlogDir=3D./org.apache.solr.cloud.UnloadDistributedZkTest-1362016023= 232/jetty4/tlog [junit4:junit4] 2> 17770 T3025 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 17770 T3025 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 17781 T3026 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@1cbac719 main{StandardDirectoryReader(s= egments_1:1)} [junit4:junit4] 2> 17782 T3025 oasc.ZkController.publish publishing core= =3Dcollection1 state=3Ddown [junit4:junit4] 2> 17783 T3025 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> 18465 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18465 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:44018", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:44018_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 18466 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Collection already exists with numShards=3D2 [junit4:junit4] 2> 18466 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 18469 T3024 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18469 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18469 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18469 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18469 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18469 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 18784 T3025 oasc.CoreContainer.registerCore registerin= g core: collection1 [junit4:junit4] 2> 18784 T3025 oasc.ZkController.register Register replic= a - core:collection1 address:http://127.0.0.1:44018 collection:collection1 = shard:shard1 [junit4:junit4] 2> 18786 T3025 oasc.ZkController.register We are http://1= 27.0.0.1:44018/collection1/ and leader is http://127.0.0.1:57300/collection= 1/ [junit4:junit4] 2> 18786 T3025 oasc.ZkController.register No LogReplay ne= eded for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:44018 [junit4:junit4] 2> 18786 T3025 oasc.ZkController.checkRecovery Core needs= to recover:collection1 [junit4:junit4] 2> 18786 T3025 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C152 name=3Dcollection1 org.apache.sol= r.core.SolrCore@18a57e93 url=3Dhttp://127.0.0.1:44018/collection1 node=3D12= 7.0.0.1:44018_ C152_STATE=3Dcoll:collection1 core:collection1 props:{base_u= rl=3Dhttp://127.0.0.1:44018, collection=3Dcollection1, state=3Ddown, node_n= ame=3D127.0.0.1:44018_, core=3Dcollection1} [junit4:junit4] 2> 18787 T3027 C152 P44018 oasc.RecoveryStrategy.run Star= ting recovery process. core=3Dcollection1 recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 18787 T3025 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18787 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry ###### startupVersions=3D[] [junit4:junit4] 2> 18787 T3027 C152 P44018 oasc.ZkController.publish publ= ishing core=3Dcollection1 state=3Drecovering [junit4:junit4] 2> 18787 T3027 C152 P44018 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 18788 T2937 oass.SolrDispatchFilter.init user.dir=3D/m= nt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0 [junit4:junit4] 2> 18788 T3027 C152 P44018 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> 18788 T2937 oass.SolrDispatchFilter.init SolrDispatchF= ilter.init() done [junit4:junit4] 2> 18789 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 18792 T2937 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 18793 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 18796 T2972 oasc.CoreContainer.create Creating SolrCor= e 'unloadcollection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-= Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadD= istributedZkTest-jetty1-1362016026539/unloadcollection1 [junit4:junit4] 2> 18796 T2972 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:unloadcollection [junit4:junit4] 2> 18796 T2972 oasc.ZkController.createCollectionZkNode C= reating collection in ZooKeeper:unloadcollection [junit4:junit4] 2> 18797 T2972 oasc.ZkController.getConfName Looking for = collection configName [junit4:junit4] 2> 18797 T2972 oasc.ZkController.getConfName Only one con= fig set found in zk - using it:conf1 [junit4:junit4] 2> 18797 T2972 oascc.SolrZkClient.makePath makePath: /col= lections/unloadcollection [junit4:junit4] 2> 18798 T2972 oasc.ZkController.readConfigName Load coll= ection config from:/collections/unloadcollection [junit4:junit4] 2> 18799 T2972 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty1-1362016026539/unloadcollection1/' [junit4:junit4] 2> 18824 T2972 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_42 [junit4:junit4] 2> 18849 T2972 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 18850 T2972 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 18853 T2972 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 19118 T2972 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 19122 T2972 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 19124 T2972 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 19131 T2972 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 19133 T2972 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 19135 T2972 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 19135 T2972 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 19136 T2972 oasc.SolrCore. [unloadcollection1] O= pening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/sol= r/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-j= etty1-1362016026539/unloadcollection1/, dataDir=3D/mnt/ssd/jenkins/workspac= e/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistr= ibutedZkTest-1362016023233/1362016042024unloadcollection1_1n/ [junit4:junit4] 2> 19136 T2972 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@36314ab8 [junit4:junit4] 2> 19137 T2972 oasc.SolrCore.initDirectoryFactory org.apa= che.solr.core.MockDirectoryFactory [junit4:junit4] 2> 19137 T2972 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016= 042024unloadcollection1_1n forceNew:false [junit4:junit4] 2> 19137 T2972 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016042= 024unloadcollection1_1n [junit4:junit4] 2> 19138 T2972 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-4.= x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-136= 2016023233/1362016042024unloadcollection1_1n/index/ [junit4:junit4] 2> 19138 T2972 oasc.SolrCore.initIndex WARNING [unloadcol= lection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-= Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-13620= 16023233/1362016042024unloadcollection1_1n/index' doesn't exist. Creating n= ew index... [junit4:junit4] 2> 19138 T2972 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016= 042024unloadcollection1_1n/index forceNew:false [junit4:junit4] 2> 19139 T2972 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@ec03416 lockFactory=3Dorg.apache.lucene.store.Native= FSLockFactory@5b12bb5a),segFN=3Dsegments_1,generation=3D1,filenames=3D[segm= ents_1] [junit4:junit4] 2> 19139 T2972 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 19140 T2972 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016042= 024unloadcollection1_1n/index [junit4:junit4] 2> 19141 T2972 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 19141 T2972 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 19141 T2972 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 19142 T2972 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 19142 T2972 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 19142 T2972 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 19142 T2972 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 19143 T2972 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 19143 T2972 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 19145 T2972 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 19146 T2972 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016042= 024unloadcollection1_1n [junit4:junit4] 2> 19147 T2972 oass.SolrIndexSearcher. Opening Sear= cher@3eca852d main [junit4:junit4] 2> 19147 T2972 oasu.UpdateHandler.clearLog Clearing tlog = files, tlogDir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/buil= d/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/136201= 6042024unloadcollection1_1n/tlog [junit4:junit4] 2> 19148 T2972 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 19148 T2972 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 19150 T3028 oasc.SolrCore.registerSearcher [unloadcoll= ection1] Registered new searcher Searcher@3eca852d main{StandardDirectoryRe= ader(segments_1:1)} [junit4:junit4] 2> 19151 T2972 oasc.ZkController.publish publishing core= =3Dunloadcollection1 state=3Ddown [junit4:junit4] 2> C151_STATE=3Dcoll:collection1 core:collection1 props:= {base_url=3Dhttp://127.0.0.1:48840, collection=3Dcollection1, shard=3Dshard= 2, state=3Drecovering, node_name=3D127.0.0.1:48840_, core=3Dcollection1} [junit4:junit4] 2> 19846 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:53427/collection1/ core=3Dc= ollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 19846 T3011 C151 P48840 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:48840 START replicas=3D[http://127= .0.0.1:53427/collection1/] nUpdates=3D100 [junit4:junit4] 2> 19846 T3011 C151 P48840 oasu.PeerSync.sync WARNING no = frame of reference to tell of we've missed updates [junit4:junit4] 2> 19847 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 19847 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 19847 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 19847 T3011 C151 P48840 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 19847 T3011 C151 P48840 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:53427/collection1/. core=3D= collection1 [junit4:junit4] 2> 19847 T3011 C151 P48840 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C153 name=3Dcollection1 org.apache.sol= r.core.SolrCore@4b1a22f3 url=3Dhttp://127.0.0.1:53427/collection1 node=3D12= 7.0.0.1:53427_ C153_STATE=3Dcoll:collection1 core:collection1 props:{base_u= rl=3Dhttp://127.0.0.1:53427, collection=3Dcollection1, shard=3Dshard2, stat= e=3Dactive, node_name=3D127.0.0.1:53427_, core=3Dcollection1, leader=3Dtrue= } [junit4:junit4] 2> 19851 T2973 C153 P53427 REQ /get {wt=3Djavabin&distrib= =3Dfalse&version=3D2&qt=3D/get&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 19852 T2974 C153 P53427 oasu.DirectUpdateHandler2.comm= it start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtrue,= expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4:junit4] 2> 19853 T2974 C153 P53427 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty1 [junit4:junit4] 2> 19854 T2974 C153 P53427 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@36c782a4 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@41b7c380),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 19855 T2974 C153 P53427 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1[segments_1] [junit4:junit4] 2> 19855 T2974 C153 P53427 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@36c782a4 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@41b7c380),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@36c782a4 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@41b7c380),segFN=3Dsegments_2,generation=3D2,filenames=3D[seg= ments_2] [junit4:junit4] 2> 19856 T2974 C153 P53427 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 19856 T2974 C153 P53427 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty1 [junit4:junit4] 2> 19857 T2974 C153 P53427 oass.SolrIndexSearcher. = Opening Searcher@6da54b86 realtime [junit4:junit4] 2> 19857 T2974 C153 P53427 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 19857 T2974 C153 P53427 /update {wt=3Djavabin&commit= =3Dtrue&version=3D2&softCommit=3Dfalse&commit_end_point=3Dtrue&waitSearcher= =3Dtrue&openSearcher=3Dfalse} {commit=3D} 0 5 [junit4:junit4] 2> 19858 T3011 C151 P48840 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 19858 T3011 C151 P48840 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 19867 T2971 C153 P53427 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 19867 T2971 C153 P53427 REQ /replication {wt=3Djavabin= &version=3D2&qt=3D/replication&command=3Dindexversion} status=3D0 QTime=3D8= =20 [junit4:junit4] 2> 19868 T3011 C151 P48840 oash.SnapPuller.fetchLatestInd= ex Master's generation: 2 [junit4:junit4] 2> 19868 T3011 C151 P48840 oash.SnapPuller.fetchLatestInd= ex Slave's generation: 1 [junit4:junit4] 2> 19868 T3011 C151 P48840 oash.SnapPuller.fetchLatestInd= ex Starting replication process [junit4:junit4] 2> 19869 T2973 C153 P53427 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty1 [junit4:junit4] 2> 19870 T2973 C153 P53427 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty1/index [junit4:junit4] 2> 19870 T2973 C153 P53427 REQ /replication {wt=3Djavabin= &version=3D2&qt=3D/replication&command=3Dfilelist&generation=3D2} status=3D= 0 QTime=3D1=20 [junit4:junit4] 2> 19870 T3011 C151 P48840 oash.SnapPuller.fetchLatestInd= ex Number of files in latest index in master: 1 [junit4:junit4] 2> 19872 T3011 C151 P48840 oasc.CachingDirectoryFactory.g= et return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-1362016023232/jetty3/index.20130227174723099 forceNew:false [junit4:junit4] 2> 19872 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19872 T3011 C151 P48840 oash.SnapPuller.fetchLatestInd= ex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory= @2bdab87a lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@32740bc= 7) fullCopy=3Dtrue [junit4:junit4] 2> 19874 T2974 C153 P53427 REQ /replication {wt=3Dfilestr= eam&qt=3D/replication&file=3Dsegments_2&command=3Dfilecontent&checksum=3Dtr= ue&generation=3D2} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 19878 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3/index [junit4:junit4] 2> 19878 T3011 C151 P48840 oash.SnapPuller.fetchLatestInd= ex Total time taken for download : 0 secs [junit4:junit4] 2> 19878 T3011 C151 P48840 oash.SnapPuller.modifyIndexPro= ps New index installed. Updating index properties... index=3Dindex.20130227= 174723099 [junit4:junit4] 2> 19879 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19891 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19891 T3011 C151 P48840 oasc.SolrCore.getNewIndexDir N= ew index directory detected: old=3D./org.apache.solr.cloud.UnloadDistribute= dZkTest-1362016023232/jetty3/index/ new=3D./org.apache.solr.cloud.UnloadDis= tributedZkTest-1362016023232/jetty3/index.20130227174723099 [junit4:junit4] 2> 19892 T3011 C151 P48840 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@2bdab87a lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@32740bc7),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_= 2] [junit4:junit4] 2> 19892 T3011 C151 P48840 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 19892 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19892 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19892 T3011 C151 P48840 oasu.DefaultSolrCoreState.newI= ndexWriter Creating new IndexWriter... [junit4:junit4] 2> 19892 T3011 C151 P48840 oasu.DefaultSolrCoreState.newI= ndexWriter Waiting until IndexWriter is unused... core=3Dcollection1 [junit4:junit4] 2> 19893 T3011 C151 P48840 oasu.DefaultSolrCoreState.newI= ndexWriter Rollback old IndexWriter... core=3Dcollection1 [junit4:junit4] 2> 19893 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3/index.20130227174723099 [junit4:junit4] 2> 19893 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19893 T3011 C151 P48840 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@2bdab87a lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@32740bc7),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_= 2] [junit4:junit4] 2> 19894 T3011 C151 P48840 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 19894 T3011 C151 P48840 oasu.DefaultSolrCoreState.newI= ndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 19894 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3 [junit4:junit4] 2> 19894 T3011 C151 P48840 oass.SolrIndexSearcher. = Opening Searcher@4040f689 main [junit4:junit4] 2> 19895 T3010 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@4040f689 main{StandardDirectoryReader(s= egments_2:1:nrt)} [junit4:junit4] 2> 19895 T3010 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty3/index [junit4:junit4] 2> 19895 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3/index.20130227174723099 [junit4:junit4] 2> 19895 T3011 C151 P48840 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty3/index [junit4:junit4] 2> 19895 T3011 C151 P48840 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 19895 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 19895 T3011 C151 P48840 oasc.ZkController.publish publ= ishing core=3Dcollection1 state=3Dactive [junit4:junit4] 2> 19896 T3011 C151 P48840 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 19902 T3011 C151 P48840 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 19972 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 19972 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:44018", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "state":"recovering", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:44018_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 19975 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:53427", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"unloadcollection", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:53427_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"unloadcollection1"} [junit4:junit4] 2> 19975 T2960 oasc.Overseer$ClusterStateUpdater.createCo= llection Create collection unloadcollection with numShards 1 [junit4:junit4] 2> 19975 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 19976 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:48840", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":"shard2", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:48840_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 19978 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19978 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19978 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19978 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19978 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 19978 T3024 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 20152 T2972 oasc.CoreContainer.registerCore registerin= g core: unloadcollection1 [junit4:junit4] 2> 20153 T2972 oasc.ZkController.register Register replic= a - core:unloadcollection1 address:http://127.0.0.1:53427 collection:unload= collection shard:shard1 [junit4:junit4] 2> 20153 T2972 oascc.SolrZkClient.makePath makePath: /col= lections/unloadcollection/leader_elect/shard1/election [junit4:junit4] 2> 20157 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c30006 type:de= lete cxid:0xa6 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/collection= s/unloadcollection/leaders Error:KeeperErrorCode =3D NoNode for /solr/colle= ctions/unloadcollection/leaders [junit4:junit4] 2> 20157 T2972 oasc.ShardLeaderElectionContext.runLeaderP= rocess Running the leader process. [junit4:junit4] 2> 20157 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c30006 type:cr= eate cxid:0xa7 zxid:0xc4 txntype:-1 reqpath:n/a Error Path:/solr/overseer E= rror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 20158 T2972 oasc.ShardLeaderElectionContext.waitForRep= licasToComeUp Enough replicas found to continue. [junit4:junit4] 2> 20158 T2972 oasc.ShardLeaderElectionContext.runLeaderP= rocess I may be the new leader - try and sync [junit4:junit4] 2> 20159 T2972 oasc.SyncStrategy.sync Sync replicas to ht= tp://127.0.0.1:53427/unloadcollection1/ [junit4:junit4] 2> 20159 T2972 oasc.SyncStrategy.syncReplicas Sync Succes= s - now sync replicas to me [junit4:junit4] 2> 20159 T2972 oasc.SyncStrategy.syncToMe http://127.0.0.= 1:53427/unloadcollection1/ has no replicas [junit4:junit4] 2> 20159 T2972 oasc.ShardLeaderElectionContext.runLeaderP= rocess I am the new leader: http://127.0.0.1:53427/unloadcollection1/ [junit4:junit4] 2> 20159 T2972 oascc.SolrZkClient.makePath makePath: /col= lections/unloadcollection/leaders/shard1 [junit4:junit4] 2> 20161 T2942 oazs.PrepRequestProcessor.pRequest Got use= r-level KeeperException when processing sessionid:0x13d1e7b32c30006 type:cr= eate cxid:0xb1 zxid:0xc8 txntype:-1 reqpath:n/a Error Path:/solr/overseer E= rror:KeeperErrorCode =3D NodeExists for /solr/overseer [junit4:junit4] 2> 21483 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 21489 T3024 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21489 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21489 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21489 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21489 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21493 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 21517 T2972 oasc.ZkController.register We are http://1= 27.0.0.1:53427/unloadcollection1/ and leader is http://127.0.0.1:53427/unlo= adcollection1/ [junit4:junit4] 2> 21517 T2972 oasc.ZkController.register No LogReplay ne= eded for core=3Dunloadcollection1 baseURL=3Dhttp://127.0.0.1:53427 [junit4:junit4] 2> 21517 T2972 oasc.ZkController.checkRecovery I am the l= eader, no recovery necessary [junit4:junit4] 2> 21518 T2972 oasc.ZkController.publish publishing core= =3Dunloadcollection1 state=3Dactive [junit4:junit4] 2> 21519 T2972 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 21522 T2972 oasc.CoreContainer.persistFile Persisting = cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1= 362016026539/solr.xml [junit4:junit4] 2> 21531 T2972 oasc.SolrXMLSerializer.persistFile Persist= ing cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/b= uild/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jett= y1-1362016026539/solr.xml [junit4:junit4] 2> 21532 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config: [junit4:junit4] 2> 21534 T2937 oaz.ZooKeeper. Initiating client con= nection, connectString=3D127.0.0.1:44988/solr sessionTimeout=3D10000 watche= r=3Dorg.apache.solr.common.cloud.ConnectionManager@55f8ebd4 [junit4:junit4] 2> 21535 T3031 oaz.ClientCnxn$SendThread.logStartConnect = Opening socket connection to server localhost.localdomain/127.0.0.1:44988. = Will not attempt to authenticate using SASL (access denied ("javax.security= .auth.AuthPermission" "getLoginConfiguration")) [junit4:junit4] 2> 21535 T3031 oaz.ClientCnxn$SendThread.primeConnection = Socket connection established to localhost.localdomain/127.0.0.1:44988, ini= tiating session [junit4:junit4] 2> 21536 T2939 oazs.NIOServerCnxnFactory.run Accepted soc= ket connection from /127.0.0.1:59502 [junit4:junit4] 2> 21536 T2939 oazs.ZooKeeperServer.processConnectRequest= Client attempting to establish new session at /127.0.0.1:59502 [junit4:junit4] 2> 21536 T2937 oascc.ConnectionManager.waitForConnected W= aiting for client to connect to ZooKeeper [junit4:junit4] 2> 21537 T2941 oazs.ZooKeeperServer.finishSessionInit Est= ablished session 0x13d1e7b32c3000d with negotiated timeout 10000 for client= /127.0.0.1:59502 [junit4:junit4] 2> 21537 T3031 oaz.ClientCnxn$SendThread.onConnected Sess= ion establishment complete on server localhost.localdomain/127.0.0.1:44988,= sessionid =3D 0x13d1e7b32c3000d, negotiated timeout =3D 10000 [junit4:junit4] 2> 21538 T3032 oascc.ConnectionManager.process Watcher or= g.apache.solr.common.cloud.ConnectionManager@55f8ebd4 name:ZooKeeperConnect= ion Watcher:127.0.0.1:44988/solr got event WatchedEvent state:SyncConnected= type:None path:null path:null type:None [junit4:junit4] 2> 21538 T2937 oascc.ConnectionManager.waitForConnected C= lient is connected to ZooKeeper [junit4:junit4] 2> 21539 T2937 oascc.ZkStateReader.createClusterStateWatc= hersAndUpdate Updating cluster state from ZooKeeper...=20 [junit4:junit4] 2> 21540 T2937 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 21541 T2937 oascsi.HttpClientUtil.createClient Creatin= g new http client, config:maxConnections=3D128&maxConnectionsPerHost=3D32&f= ollowRedirects=3Dfalse [junit4:junit4] 2> 21545 T2987 oasc.CoreContainer.create Creating SolrCor= e 'unloadcollection2' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-= Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadD= istributedZkTest-jetty2-1362016031046/unloadcollection2 [junit4:junit4] 2> 21545 T2987 oasc.ZkController.createCollectionZkNode C= heck for collection zkNode:unloadcollection [junit4:junit4] 2> 21546 T2987 oasc.ZkController.createCollectionZkNode C= ollection zkNode exists [junit4:junit4] 2> 21546 T2987 oasc.ZkController.readConfigName Load coll= ection config from:/collections/unloadcollection [junit4:junit4] 2> 21552 T2987 oasc.SolrResourceLoader. new SolrRes= ourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-jetty2-1362016031046/unloadcollection2/' [junit4:junit4] 2> 21589 T2987 oasc.SolrConfig. Using Lucene MatchV= ersion: LUCENE_42 [junit4:junit4] 2> 21629 T2987 oasc.SolrConfig. Loaded SolrConfig: = solrconfig.xml [junit4:junit4] 2> 21630 T2987 oass.IndexSchema.readSchema Reading Solr S= chema [junit4:junit4] 2> 21635 T2987 oass.IndexSchema.readSchema Schema name=3D= test [junit4:junit4] 2> 22068 T2987 oass.OpenExchangeRatesOrgProvider.init Ini= tialized with rates=3Dopen-exchange-rates.json, refreshInterval=3D1440. [junit4:junit4] 2> 22074 T2987 oass.IndexSchema.readSchema default search= field in schema is text [junit4:junit4] 2> 22078 T2987 oass.IndexSchema.readSchema unique key fie= ld: id [junit4:junit4] 2> 22086 T2987 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 22089 T2987 oass.FileExchangeRateProvider.reload Reloa= ding exchange rates from file currency.xml [junit4:junit4] 2> 22092 T2987 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 22095 T2987 oass.OpenExchangeRatesOrgProvider.reload R= eloading exchange rates from open-exchange-rates.json [junit4:junit4] 2> 22096 T2987 oasc.SolrCore. [unloadcollection2] O= pening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/sol= r/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-j= etty2-1362016031046/unloadcollection2/, dataDir=3D/mnt/ssd/jenkins/workspac= e/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistr= ibutedZkTest-1362016023233/1362016044773unloadcollection1_2n/ [junit4:junit4] 2> 22097 T2987 oasc.JmxMonitoredMap. JMX monitoring= is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxM= BeanServer@36314ab8 [junit4:junit4] 2> 22097 T2987 oasc.SolrCore.initDirectoryFactory org.apa= che.solr.core.MockDirectoryFactory [junit4:junit4] 2> 22098 T2987 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016= 044773unloadcollection1_2n forceNew:false [junit4:junit4] 2> 22099 T2987 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016044= 773unloadcollection1_2n [junit4:junit4] 2> 22099 T2987 oasc.SolrCore.getNewIndexDir New index dir= ectory detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-4.= x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-136= 2016023233/1362016044773unloadcollection1_2n/index/ [junit4:junit4] 2> 22099 T2987 oasc.SolrCore.initIndex WARNING [unloadcol= lection2] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-= Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-13620= 16023233/1362016044773unloadcollection1_2n/index' doesn't exist. Creating n= ew index... [junit4:junit4] 2> 22100 T2987 oasc.CachingDirectoryFactory.get return ne= w directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build= /solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016= 044773unloadcollection1_2n/index forceNew:false [junit4:junit4] 2> 22101 T2987 oasc.SolrDeletionPolicy.onCommit SolrDelet= ionPolicy.onCommit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@59e9f2de lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@17aba9e3),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 22101 T2987 oasc.SolrDeletionPolicy.updateCommits newe= st commit =3D 1[segments_1] [junit4:junit4] 2> 22102 T2987 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016044= 773unloadcollection1_2n/index [junit4:junit4] 2> 22103 T2987 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= dedupe" [junit4:junit4] 2> 22103 T2987 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= stored_sig" [junit4:junit4] 2> 22104 T2987 oasup.UpdateRequestProcessorChain.init ins= erting DistributedUpdateProcessorFactory into updateRequestProcessorChain "= distrib-dup-test-chain-implicit" [junit4:junit4] 2> 22104 T2987 oasc.RequestHandlers.initHandlersFromConfi= g created standard: solr.StandardRequestHandler [junit4:junit4] 2> 22104 T2987 oasc.RequestHandlers.initHandlersFromConfi= g created /get: solr.RealTimeGetHandler [junit4:junit4] 2> 22105 T2987 oasc.RequestHandlers.initHandlersFromConfi= g adding lazy requestHandler: solr.ReplicationHandler [junit4:junit4] 2> 22105 T2987 oasc.RequestHandlers.initHandlersFromConfi= g created /replication: solr.ReplicationHandler [junit4:junit4] 2> 22105 T2987 oasc.RequestHandlers.initHandlersFromConfi= g created /update: solr.UpdateRequestHandler [junit4:junit4] 2> 22106 T2987 oasc.RequestHandlers.initHandlersFromConfi= g created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4:junit4] 2> 22109 T2987 oashl.XMLLoader.init xsltCacheLifetimeSeco= nds=3D60 [junit4:junit4] 2> 22112 T2987 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016044= 773unloadcollection1_2n [junit4:junit4] 2> 22113 T2987 oass.SolrIndexSearcher. Opening Sear= cher@4cf53cfb main [junit4:junit4] 2> 22113 T2987 oasu.UpdateHandler.clearLog Clearing tlog = files, tlogDir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/buil= d/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/136201= 6044773unloadcollection1_2n/tlog [junit4:junit4] 2> 22114 T2987 oasu.CommitTracker. Hard AutoCommit:= disabled [junit4:junit4] 2> 22115 T2987 oasu.CommitTracker. Soft AutoCommit:= disabled [junit4:junit4] 2> 22117 T2987 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./solrtest-UnloadDistributedZkTest-1362016023233/1362016044= 773unloadcollection1_2n [junit4:junit4] 2> 22118 T3033 oasc.SolrCore.registerSearcher [unloadcoll= ection2] Registered new searcher Searcher@4cf53cfb main{StandardDirectoryRe= ader(segments_1:1)} [junit4:junit4] 2> 22119 T2987 oasc.ZkController.publish publishing core= =3Dunloadcollection2 state=3Ddown [junit4:junit4] 2> 22119 T2987 oasc.ZkController.publish numShards not fo= und on descriptor - reading it from system property [junit4:junit4] 2> C152_STATE=3Dcoll:collection1 core:collection1 props:= {base_url=3Dhttp://127.0.0.1:44018, collection=3Dcollection1, shard=3Dshard= 1, state=3Drecovering, node_name=3D127.0.0.1:44018_, core=3Dcollection1} [junit4:junit4] 2> 22793 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry Attempting to PeerSync from http://127.0.0.1:57300/collection1/ core=3Dc= ollection1 - recoveringAfterStartup=3Dtrue [junit4:junit4] 2> 22793 T3027 C152 P44018 oasu.PeerSync.sync PeerSync: c= ore=3Dcollection1 url=3Dhttp://127.0.0.1:44018 START replicas=3D[http://127= .0.0.1:57300/collection1/] nUpdates=3D100 [junit4:junit4] 2> 22794 T3027 C152 P44018 oasu.PeerSync.sync WARNING no = frame of reference to tell of we've missed updates [junit4:junit4] 2> 22794 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry PeerSync Recovery was not successful - trying replication. core=3Dcollec= tion1 [junit4:junit4] 2> 22794 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry Starting Replication Recovery. core=3Dcollection1 [junit4:junit4] 2> 22795 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry Begin buffering updates. core=3Dcollection1 [junit4:junit4] 2> 22795 T3027 C152 P44018 oasu.UpdateLog.bufferUpdates S= tarting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull} [junit4:junit4] 2> 22795 T3027 C152 P44018 oasc.RecoveryStrategy.replicat= e Attempting to replicate from http://127.0.0.1:57300/collection1/. core=3D= collection1 [junit4:junit4] 2> 22796 T3027 C152 P44018 oascsi.HttpClientUtil.createCl= ient Creating new http client, config:maxConnections=3D128&maxConnectionsPe= rHost=3D32&followRedirects=3Dfalse [junit4:junit4] 2> ASYNC NEW_CORE C154 name=3Dcollection1 org.apache.sol= r.core.SolrCore@333c137c url=3Dhttp://127.0.0.1:57300/collection1 node=3D12= 7.0.0.1:57300_ C154_STATE=3Dcoll:collection1 core:collection1 props:{base_u= rl=3Dhttp://127.0.0.1:57300, collection=3Dcollection1, shard=3Dshard1, stat= e=3Dactive, node_name=3D127.0.0.1:57300_, core=3Dcollection1, leader=3Dtrue= } [junit4:junit4] 2> 22799 T2983 C154 P57300 REQ /get {wt=3Djavabin&distrib= =3Dfalse&version=3D2&qt=3D/get&getVersions=3D100} status=3D0 QTime=3D0=20 [junit4:junit4] 2> 22801 T2989 C154 P57300 oasu.DirectUpdateHandler2.comm= it start commit{,optimize=3Dfalse,openSearcher=3Dfalse,waitSearcher=3Dtrue,= expungeDeletes=3Dfalse,softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4:junit4] 2> 22802 T2989 C154 P57300 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty2 [junit4:junit4] 2> 22803 T2989 C154 P57300 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@3c7759c5 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@5954430f),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> 22803 T2989 C154 P57300 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 1[segments_1] [junit4:junit4] 2> 22804 T2989 C154 P57300 oasc.SolrDeletionPolicy.onComm= it SolrDeletionPolicy.onCommit: commits:num=3D2 [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@3c7759c5 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@5954430f),segFN=3Dsegments_1,generation=3D1,filenames=3D[seg= ments_1] [junit4:junit4] 2> =09=09commit{dir=3DBaseDirectoryWrapper(org.apache.luc= ene.store.RAMDirectory@3c7759c5 lockFactory=3Dorg.apache.lucene.store.Nativ= eFSLockFactory@5954430f),segFN=3Dsegments_2,generation=3D2,filenames=3D[seg= ments_2] [junit4:junit4] 2> 22804 T2989 C154 P57300 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 22804 T2989 C154 P57300 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty2 [junit4:junit4] 2> 22805 T2989 C154 P57300 oass.SolrIndexSearcher. = Opening Searcher@5e528473 realtime [junit4:junit4] 2> 22805 T2989 C154 P57300 oasu.DirectUpdateHandler2.comm= it end_commit_flush [junit4:junit4] 2> 22805 T2989 C154 P57300 /update {wt=3Djavabin&commit= =3Dtrue&version=3D2&softCommit=3Dfalse&commit_end_point=3Dtrue&waitSearcher= =3Dtrue&openSearcher=3Dfalse} {commit=3D} 0 4 [junit4:junit4] 2> 22806 T3027 C152 P44018 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 22806 T3027 C152 P44018 oash.SnapPuller. No val= ue set for 'pollInterval'. Timer Task not started. [junit4:junit4] 2> 22809 T2983 C154 P57300 oash.ReplicationHandler.inform= Commits will be reserved for 10000 [junit4:junit4] 2> 22810 T2983 C154 P57300 REQ /replication {wt=3Djavabin= &version=3D2&qt=3D/replication&command=3Dindexversion} status=3D0 QTime=3D1= =20 [junit4:junit4] 2> 22810 T3027 C152 P44018 oash.SnapPuller.fetchLatestInd= ex Master's generation: 2 [junit4:junit4] 2> 22811 T3027 C152 P44018 oash.SnapPuller.fetchLatestInd= ex Slave's generation: 1 [junit4:junit4] 2> 22811 T3027 C152 P44018 oash.SnapPuller.fetchLatestInd= ex Starting replication process [junit4:junit4] 2> 22812 T2989 C154 P57300 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty2 [junit4:junit4] 2> 22813 T2989 C154 P57300 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty2/index [junit4:junit4] 2> 22813 T2989 C154 P57300 REQ /replication {wt=3Djavabin= &version=3D2&qt=3D/replication&command=3Dfilelist&generation=3D2} status=3D= 0 QTime=3D1=20 [junit4:junit4] 2> 22813 T3027 C152 P44018 oash.SnapPuller.fetchLatestInd= ex Number of files in latest index in master: 1 [junit4:junit4] 2> 22814 T3027 C152 P44018 oasc.CachingDirectoryFactory.g= et return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linu= x/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkT= est-1362016023232/jetty4/index.20130227174726042 forceNew:false [junit4:junit4] 2> 22814 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22815 T3027 C152 P44018 oash.SnapPuller.fetchLatestInd= ex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory= @71256b91 lockFactory=3Dorg.apache.lucene.store.NativeFSLockFactory@8b1299d= ) fullCopy=3Dtrue [junit4:junit4] 2> 22817 T2988 C154 P57300 REQ /replication {wt=3Dfilestr= eam&qt=3D/replication&file=3Dsegments_2&command=3Dfilecontent&checksum=3Dtr= ue&generation=3D2} status=3D0 QTime=3D1=20 [junit4:junit4] 2> 22817 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4/index [junit4:junit4] 2> 22818 T3027 C152 P44018 oash.SnapPuller.fetchLatestInd= ex Total time taken for download : 0 secs [junit4:junit4] 2> 22818 T3027 C152 P44018 oash.SnapPuller.modifyIndexPro= ps New index installed. Updating index properties... index=3Dindex.20130227= 174726042 [junit4:junit4] 2> 22818 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22819 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22819 T3027 C152 P44018 oasc.SolrCore.getNewIndexDir N= ew index directory detected: old=3D./org.apache.solr.cloud.UnloadDistribute= dZkTest-1362016023232/jetty4/index/ new=3D./org.apache.solr.cloud.UnloadDis= tributedZkTest-1362016023232/jetty4/index.20130227174726042 [junit4:junit4] 2> 22820 T3027 C152 P44018 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@71256b91 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@8b1299d),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2= ] [junit4:junit4] 2> 22820 T3027 C152 P44018 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 22820 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22821 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22821 T3027 C152 P44018 oasu.DefaultSolrCoreState.newI= ndexWriter Creating new IndexWriter... [junit4:junit4] 2> 22821 T3027 C152 P44018 oasu.DefaultSolrCoreState.newI= ndexWriter Waiting until IndexWriter is unused... core=3Dcollection1 [junit4:junit4] 2> 22821 T3027 C152 P44018 oasu.DefaultSolrCoreState.newI= ndexWriter Rollback old IndexWriter... core=3Dcollection1 [junit4:junit4] 2> 22822 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4/index.20130227174726042 [junit4:junit4] 2> 22822 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22823 T3027 C152 P44018 oasc.SolrDeletionPolicy.onInit= SolrDeletionPolicy.onInit: commits:num=3D1 [junit4:junit4] 2> =09=09commit{dir=3DMockDirWrapper(org.apache.lucene.st= ore.RAMDirectory@71256b91 lockFactory=3Dorg.apache.lucene.store.NativeFSLoc= kFactory@8b1299d),segFN=3Dsegments_2,generation=3D2,filenames=3D[segments_2= ] [junit4:junit4] 2> 22823 T3027 C152 P44018 oasc.SolrDeletionPolicy.update= Commits newest commit =3D 2[segments_2] [junit4:junit4] 2> 22823 T3027 C152 P44018 oasu.DefaultSolrCoreState.newI= ndexWriter New IndexWriter is ready to be used. [junit4:junit4] 2> 22823 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4 [junit4:junit4] 2> 22824 T3027 C152 P44018 oass.SolrIndexSearcher. = Opening Searcher@6ba812bf main [junit4:junit4] 2> 22824 T3026 oasc.SolrCore.registerSearcher [collection= 1] Registered new searcher Searcher@6ba812bf main{StandardDirectoryReader(s= egments_2:1:nrt)} [junit4:junit4] 2> 22824 T3026 oasc.CachingDirectoryFactory.close Releasi= ng directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/so= lr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-13620160232= 32/jetty4/index [junit4:junit4] 2> 22825 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4/index.20130227174726042 [junit4:junit4] 2> 22825 T3027 C152 P44018 oasc.CachingDirectoryFactory.c= lose Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/s= olr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest= -1362016023232/jetty4/index [junit4:junit4] 2> 22825 T3027 C152 P44018 oasc.RecoveryStrategy.replay N= o replay needed. core=3Dcollection1 [junit4:junit4] 2> 22825 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry Replication Recovery was successful - registering as Active. core=3Dcoll= ection1 [junit4:junit4] 2> 22825 T3027 C152 P44018 oasc.ZkController.publish publ= ishing core=3Dcollection1 state=3Dactive [junit4:junit4] 2> 22825 T3027 C152 P44018 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [junit4:junit4] 2> 22826 T3027 C152 P44018 oasc.RecoveryStrategy.doRecove= ry Finished recovery process. core=3Dcollection1 [junit4:junit4] 2> 22994 T2960 oascc.ZkStateReader.updateClusterState Upd= ating cloud state from ZooKeeper...=20 [junit4:junit4] 2> 22994 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D1 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:53427", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"unloadcollection", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:53427_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"1", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"unloadcollection1"} [junit4:junit4] 2> 22997 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:57300", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"unloadcollection", [junit4:junit4] 2> =09 "shard":null, [junit4:junit4] 2> =09 "state":"down", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:57300_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"unloadcollection2"} [junit4:junit4] 2> 22997 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Collection already exists with numShards=3D1 [junit4:junit4] 2> 22997 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Assigning new node to shard shard=3Dshard1 [junit4:junit4] 2> 22999 T2960 oasc.Overseer$ClusterStateUpdater.updateSt= ate Update state numShards=3D2 message=3D{ [junit4:junit4] 2> =09 "base_url":"http://127.0.0.1:44018", [junit4:junit4] 2> =09 "core_node_name":null, [junit4:junit4] 2> =09 "collection":"collection1", [junit4:junit4] 2> =09 "shard":"shard1", [junit4:junit4] 2> =09 "state":"active", [junit4:junit4] 2> =09 "node_name":"127.0.0.1:44018_", [junit4:junit4] 2> =09 "roles":null, [junit4:junit4] 2> =09 "numShards":"2", [junit4:junit4] 2> =09 "operation":"state", [junit4:junit4] 2> =09 "core":"collection1"} [junit4:junit4] 2> 23002 T3032 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23002 T3008 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23003 T2959 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23002 T2978 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23002 T3024 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23003 T2993 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23006 T2965 oascc.ZkStateReader$2.process A cluster st= ate change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clu= sterstate.json, has occurred - updating... (live nodes size: 5) [junit4:junit4] 2> 23122 T2987 oasc.CoreContainer.registerCore registerin= g core: unloadcollection2 [junit4:junit4] 2> 23123 T2987 oasc.ZkController.register Register replic= a - core:unloadcollection2 address:http://127.0.0.1:57300 collection:unload= collection shard:shard1 [junit4:junit4] 2> 23126 T2987 oasc.ZkController.register We are http://1= 27.0.0.1:57300/unloadcollection2/ and leader is http://127.0.0.1:53427/unlo= adcollection1/ [junit4:junit4] 2> 23127 T2987 oasc.ZkController.register No LogReplay ne= eded for core=3Dunloadcollection2 baseURL=3Dhttp://127.0.0.1:57300 [junit4:junit4] 2> 23127 T2987 oasc.ZkController.checkRecovery Core needs= to recover:unloadcollection2 [junit4:junit4] 2> 23127 T2987 oasu.DefaultSolrCoreState.doRecovery Runni= ng recovery - first canceling any ongoing recovery [junit4:junit4] 2> ASYNC NEW_CORE C155 name=3Dunloadcollection2 org.apac= he.solr.core.SolrCore@643e859e url=3Dhttp://127.0.0.1:57300/unloadcollectio= n2 node=3D127.0.0.1:57300_ C155_STATE=3Dcoll:unloadcollection core:unloadco= llection2 props:{base_url=3Dhttp://127.0.0.1:57300, collection=3Dunloadcoll= ection, state=3Ddown, node_name=3D127.0.0.1:57300_, core=3Dunloadcollection= 2} [junit4:junit4] 2> 23142 T3036 C155 P57300 oasc.RecoveryStrategy.run Star= ting recovery process. core=3Dunloadcollection2 recoveringAfterStartup=3Dt= rue [junit4:junit4] 2> 23142 T3036 C155 P57300 oasc.RecoveryStrategy.doRecove= ry ###### startupVersions=3D[] [junit4:junit4] 2> 23142 T3036 C155 P57300 oasc.ZkController.publish publ= ishing core=3Dunloadcollection2 state=3Drecovering [junit4:junit4] 2> 23143 T3036 C155 P57300 oasc.ZkController.publish numS= hards not found on descriptor - reading it from system property [ju [...truncated too long message...] t4] > =09at __randomizedtesting.SeedInfo.seed([21E8CCAD63FCE607:A00E42B5= 14A3863B]:0) [junit4:junit4] > =09at org.junit.Assert.fail(Assert.java:93) [junit4:junit4] > =09at org.apache.solr.cloud.UnloadDistributedZkTest.te= stUnloadShardAndCollection(UnloadDistributedZkTest.java:120) [junit4:junit4] > =09at org.apache.solr.cloud.UnloadDistributedZkTest.do= Test(UnloadDistributedZkTest.java:73) [junit4:junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase.te= stDistribSearch(BaseDistributedSearchTestCase.java:805) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Nat= ive Method) [junit4:junit4] > =09at sun.reflect.NativeMethodAccessorImpl.invoke(Nati= veMethodAccessorImpl.java:57) [junit4:junit4] > =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(= DelegatingMethodAccessorImpl.java:43) [junit4:junit4] > =09at java.lang.reflect.Method.invoke(Method.java:474) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.invoke(RandomizedRunner.java:1559) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.access$600(RandomizedRunner.java:79) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$6.evaluate(RandomizedRunner.java:737) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$7.evaluate(RandomizedRunner.java:773) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$8.evaluate(RandomizedRunner.java:787) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleSetupTeardownChai= ned$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleFieldCacheSanity$= 1.evaluate(TestRuleFieldCacheSanity.java:51) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:46) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleThreadAndTestName= $1.evaluate(TestRuleThreadAndTestName.java:49) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol.forkTimeoutingTask(ThreadLeakControl.java:782) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$3.evaluate(ThreadLeakControl.java:442) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner.runSingleTest(RandomizedRunner.java:746) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$3.evaluate(RandomizedRunner.java:648) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$4.evaluate(RandomizedRunner.java:682) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.RandomizedRun= ner$5.evaluate(RandomizedRunner.java:693) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4:junit4] > =09at org.apache.lucene.util.AbstractBeforeAfterRule$1= .evaluate(AbstractBeforeAfterRule.java:46) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleStoreClassName$1.= evaluate(TestRuleStoreClassName.java:42) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.SystemP= ropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.NoShado= wingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule= .java:39) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleAssertionsRequire= d$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleMarkFailure$1.eva= luate(TestRuleMarkFailure.java:48) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFai= lures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) [junit4:junit4] > =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$= 1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.rules.Stateme= ntAdapter.evaluate(StatementAdapter.java:36) [junit4:junit4] > =09at com.carrotsearch.randomizedtesting.ThreadLeakCon= trol$StatementRunner.run(ThreadLeakControl.java:358) [junit4:junit4] > =09at java.lang.Thread.run(Thread.java:722) [junit4:junit4] 2> 150225 T2937 oas.SolrTestCaseJ4.deleteCore ###deleteCo= re [junit4:junit4] 2> 150242 T2936 ccr.ThreadLeakControl.checkThreadLeaks WA= RNING Will linger awaiting termination of 3 leaked thread(s). [junit4:junit4] 2> 150771 T2940 oazs.SessionTrackerImpl.run SessionTracke= rImpl exited loop! [junit4:junit4] 2> 151325 T3024 oascc.ZkStateReader$2.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 151325 T3024 oascc.ZkStateReader$3.process WARNING Zoo= Keeper watch triggered, but Solr cannot talk to ZK [junit4:junit4] 2> 151325 T3024 oaz.ClientCnxn$EventThread.run EventThrea= d shut down [junit4:junit4] 2> NOTE: test params are: codec=3DLucene40, sim=3DRandomS= imilarityProvider(queryNorm=3Dtrue,coord=3Dyes): {}, locale=3Dis_IS, timezo= ne=3DAmerica/Metlakatla [junit4:junit4] 2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation = 1.8.0-ea (64-bit)/cpus=3D8,threads=3D2,free=3D167989472,total=3D351797248 [junit4:junit4] 2> NOTE: All tests run in this JVM: [NotRequiredUniqueKey= Test, TestDocumentBuilder, SearchHandlerTest, TestQuerySenderNoQuery, PathH= ierarchyTokenizerFactoryTest, TestSystemIdResolver, AutoCommitTest, Oversee= rCollectionProcessorTest, TestRandomFaceting, MBeansHandlerTest, JsonLoader= Test, TestLMDirichletSimilarityFactory, TestSolrQueryParser, CopyFieldTest,= TestSearchPerf, PreAnalyzedFieldTest, TestIndexSearcher, TestFaceting, Tes= tLazyCores, FastVectorHighlighterTest, IndexBasedSpellCheckerTest, TestSwee= tSpotSimilarityFactory, LoggingHandlerTest, SyncSliceTest, TestBadConfig, H= ardAutoCommitTest, UpdateRequestProcessorFactoryTest, TestFastLRUCache, Doc= ValuesMultiTest, TestQueryTypes, TestLMJelinekMercerSimilarityFactory, Solr= PluginUtilsTest, CircularListTest, WordBreakSolrSpellCheckerTest, TestAtomi= cUpdateErrorCases, TestExtendedDismaxParser, DateMathParserTest, TestStress= Reorder, TestJoin, CacheHeaderTest, DistributedSpellCheckComponentTest, Tes= tWordDelimiterFilterFactory, TestLFUCache, SystemInfoHandlerTest, ClusterSt= ateUpdateTest, CoreAdminHandlerTest, DistributedTermsComponentTest, JSONWri= terTest, TestBinaryResponseWriter, CSVRequestHandlerTest, NoCacheHeaderTest= , TestFiltering, SpatialFilterTest, BadComponentTest, TestRangeQuery, TestS= uggestSpellingConverter, ZkControllerTest, TestCollationField, DateFieldTes= t, TestSolrIndexConfig, TestPseudoReturnFields, OverseerTest, RecoveryZkTes= t, FullSolrCloudDistribCmdsTest, LeaderElectionTest, TestDistributedSearch,= BasicZkTest, DirectUpdateHandlerTest, SimpleFacetsTest, TestGroupingSearch= , TestTrie, DirectSolrConnectionTest, TestRemoteStreaming, QueryEqualityTes= t, PolyFieldTest, CurrencyFieldTest, XsltUpdateRequestHandlerTest, XmlUpdat= eRequestHandlerTest, PingRequestHandlerTest, SuggesterWFSTTest, IndexSchema= RuntimeFieldTest, TestValueSourceCache, ReturnFieldsTest, SuggesterFSTTest,= UniqFieldsUpdateProcessorFactoryTest, UpdateParamsTest, SuggesterTest, Tes= tSolrDeletionPolicy1, TestIndexingPerformance, HighlighterConfigTest, SOLR7= 49Test, TestMergePolicyConfig, SolrIndexConfigTest, MultiTermTest, TestConf= ig, TestPhraseSuggestions, LegacyHTMLStripCharFilterTest, TestCodecSupport,= TimeZoneUtilsTest, PluginInfoTest, ResourceLoaderTest, TestSolrXMLSerializ= er, UUIDFieldTest, RAMDirectoryFactoryTest, ChaosMonkeyNothingIsSafeTest, Z= kNodePropsTest, ClusterStateTest, CollectionsAPIDistributedZkTest, ShardRou= tingCustomTest, SliceStateTest, SliceStateUpdateTest, UnloadDistributedZkTe= st] [junit4:junit4] Completed on J0 in 151.51s, 1 test, 1 failure <<< FAILURES! [...truncated 10 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The followi= ng error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: The followi= ng error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The followin= g error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:183: The fo= llowing error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:447:= The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:12= 02: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:86= 5: There were test failures: 258 suites, 1078 tests, 1 failure, 12 ignored = (3 assumptions) Total time: 38 minutes 18 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Description set: Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseParallelGC Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_8_1581766628.1362016181583 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_8_1581766628.1362016181583--