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 3101210279 for ; Mon, 10 Feb 2014 06:57:08 +0000 (UTC) Received: (qmail 92634 invoked by uid 500); 10 Feb 2014 06:57:05 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 91235 invoked by uid 500); 10 Feb 2014 06:56:55 -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 91071 invoked by uid 99); 10 Feb 2014 06:56:49 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 10 Feb 2014 06:56:49 +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 serv1.sd-datasolutions.de) (188.138.97.18) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 10 Feb 2014 06:56:36 +0000 Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1.sd-datasolutions.de with esmtp (Exim 4.76) (envelope-from ) id 1WCkmt-0000GP-MU; Mon, 10 Feb 2014 06:56:16 +0000 Date: Mon, 10 Feb 2014 06:55:50 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org, markrmiller@apache.org Message-ID: <1439091561.211.1392015375636.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.7.0) - Build # 1283 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_210_965445297.1392015350662" X-Jenkins-Job: Lucene-Solr-4.x-MacOSX X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_210_965445297.1392015350662 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1283/ Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC 1 tests failed. REGRESSION: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSea= rch Error Message: Captured an uncaught exception in thread: Thread[id=3D3944, name=3DRecovery= Thread, state=3DRUNNABLE, group=3DTGRP-ChaosMonkeySafeLeaderTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an unca= ught exception in thread: Thread[id=3D3944, name=3DRecoveryThread, state=3D= RUNNABLE, group=3DTGRP-ChaosMonkeySafeLeaderTest] =09at __randomizedtesting.SeedInfo.seed([B274480C43036233:3392C614345C020F]= :0) Caused by: java.lang.AssertionError: file _1.si does not exist =09at __randomizedtesting.SeedInfo.seed([B274480C43036233]:0) =09at org.apache.lucene.index.IndexWriter.filesExist(IndexWriter.java:4363) =09at org.apache.lucene.index.IndexWriter.startCommit(IndexWriter.java:4429= ) =09at org.apache.lucene.index.IndexWriter.prepareCommitInternal(IndexWriter= .java:2926) =09at org.apache.lucene.index.IndexWriter.commitInternal(IndexWriter.java:3= 022) =09at org.apache.lucene.index.IndexWriter.commit(IndexWriter.java:2989) =09at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandle= r2.java:578) =09at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.ja= va:396) =09at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:247) Build Log: [...truncated 10516 lines...] [junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest [junit4] 2> 1374167 T3614 oas.BaseDistributedSearchTestCase.initHostCo= ntext Setting hostContext system property: /tr/ef [junit4] 2> 1374174 T3614 oas.SolrTestCaseJ4.setUp ###Starting testDis= tribSearch [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x= -MacOSX/solr/build/solr-core/test/J0/./solrtest-ChaosMonkeySafeLeaderTest-1= 392013353247 [junit4] 2> 1374176 T3614 oasc.ZkTestServer.run STARTING ZK TEST SERVE= R [junit4] 2> 1374177 T3615 oasc.ZkTestServer$ZKServerMain.runFromConfig= Starting server [junit4] 2> 1374280 T3614 oasc.ZkTestServer.run start zk server on por= t:53671 [junit4] 2> 1374282 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1374289 T3621 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@6d00dc53 name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4] 2> 1374289 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1374290 T3614 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 1374302 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1374305 T3623 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@6f8bac3d name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1374306 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1374306 T3614 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4] 2> 1374315 T3614 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4] 2> 1374323 T3614 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4] 2> 1374330 T3614 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4] 2> 1374336 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1374336 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4] 2> 1374348 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1374349 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4] 2> 1374356 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/c= onf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1374357 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1374365 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1374366 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4] 2> 1374374 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1374375 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4] 2> 1374383 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1374383 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4] 2> 1374392 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1374392 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/enumsConfig.xml [junit4] 2> 1374401 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchan= ge-rates.json [junit4] 2> 1374401 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4] 2> 1374410 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-= ISOLatin1Accent.txt [junit4] 2> 1374411 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1374420 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1374421 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4] 2> 1374429 T3614 oasc.AbstractZkTestCase.putConfig put /Users= /jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test-files/s= olr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1374430 T3614 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4] 2> 1374807 T3614 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1374814 T3614 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:53674 [junit4] 2> 1374815 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1374816 T3614 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1374816 T3614 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeySafeLea= derTest-controljetty-1392013353512 [junit4] 2> 1374817 T3614 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest= -controljetty-1392013353512/' [junit4] 2> 1374842 T3614 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-contr= oljetty-1392013353512/solr.xml [junit4] 2> 1374905 T3614 oasc.CoreContainer. New CoreContainer = 1074454794 [junit4] 2> 1374905 T3614 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D./org.apache.solr.cloud.ChaosMonkeySafeLeaderTe= st-controljetty-1392013353512/] [junit4] 2> 1374907 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1374907 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4] 2> 1374907 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1374907 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1374908 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1374908 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1374908 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1374908 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1374909 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1374918 T3614 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1374919 T3614 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1374919 T3614 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1374919 T3614 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:53671/solr [junit4] 2> 1374920 T3614 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1374921 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1374926 T3634 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@a3c785f name:ZooKeeperConnection= Watcher:127.0.0.1:53671 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4] 2> 1374927 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1374935 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1374938 T3636 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@676d515 name:ZooKeeperConnection= Watcher:127.0.0.1:53671/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4] 2> 1374939 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1374943 T3614 oascc.SolrZkClient.makePath makePath: /overs= eer/queue [junit4] 2> 1374953 T3614 oascc.SolrZkClient.makePath makePath: /overs= eer/collection-queue-work [junit4] 2> 1374961 T3614 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4] 2> 1374965 T3614 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:53674_tr%2Fef [junit4] 2> 1374968 T3614 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:53674_tr%2Fef [junit4] 2> 1374978 T3614 oascc.SolrZkClient.makePath makePath: /overs= eer_elect [junit4] 2> 1374986 T3614 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4] 2> 1375001 T3614 oasc.OverseerElectionContext.runLeaderProces= s I am going to be the leader 127.0.0.1:53674_tr%2Fef [junit4] 2> 1375002 T3614 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4] 2> 1375012 T3614 oasc.Overseer.start Overseer (id=3D912269871= 18788611-127.0.0.1:53674_tr%2Fef-n_0000000000) starting [junit4] 2> 1375026 T3614 oascc.SolrZkClient.makePath makePath: /overs= eer/queue-work [junit4] 2> 1375044 T3638 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4] 2> 1375048 T3638 oasc.OverseerCollectionProcessor.prioritizeO= verseerNodes prioritizing overseer nodes [junit4] 2> 1375048 T3614 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4] 2> 1375060 T3614 oascc.SolrZkClient.makePath makePath: /alias= es.json [junit4] 2> 1375068 T3614 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1375080 T3637 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4] 2> 1375088 T3639 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4] 2> 1375089 T3639 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1375092 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1375092 T3639 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1375097 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1375100 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D1 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "base_url":"http://127.0.0.1:53674/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53674_tr%2Fef", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "numShards":"1", [junit4] 2> =09 "core_node_name":null} [junit4] 2> 1375100 T3637 oasc.Overseer$ClusterStateUpdater.createColl= ection Create collection control_collection with shards [shard1] [junit4] 2> 1375100 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard1 [junit4] 2> 1375107 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1375109 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1376094 T3639 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1376095 T3639 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeySafeLea= derTest-controljetty-1392013353512/collection1 [junit4] 2> 1376095 T3639 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4] 2> 1376099 T3639 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1376099 T3639 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/control_collection [junit4] 2> 1376106 T3639 oascc.ZkStateReader.readConfigName path=3D/c= ollections/control_collection configName=3Dconf1 specified config exists in= ZooKeeper [junit4] 2> 1376106 T3639 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest= -controljetty-1392013353512/collection1/' [junit4] 2> 1376108 T3639 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-= 1392013353512/collection1/lib/classes/' to classloader [junit4] 2> 1376109 T3639 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-= 1392013353512/collection1/lib/README' to classloader [junit4] 2> 1376187 T3639 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_47 [junit4] 2> 1376256 T3639 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1376260 T3639 oass.IndexSchema.readSchema Reading Solr Sch= ema from schema.xml [junit4] 2> 1376267 T3639 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1376742 T3639 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1376745 T3639 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1376746 T3639 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1376753 T3639 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1376818 T3639 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1376849 T3639 oasc.SolrCore. [collection1] Opening n= ew SolrCore at ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljet= ty-1392013353512/collection1/, dataDir=3D./org.apache.solr.cloud.ChaosMonke= ySafeLeaderTest-1392013353246/control/data/ [junit4] 2> 1376849 T3639 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7f2dcd23 [junit4] 2> 1376850 T3639 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/control/data [junit4] 2> 1376851 T3639 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.ChaosMonkeySafeLead= erTest-1392013353246/control/data/index/ [junit4] 2> 1376852 T3639 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1392= 013353246/control/data/index' doesn't exist. Creating new index... [junit4] 2> 1376852 T3639 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/control/data/index [junit4] 2> 1376853 T3639 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByte= SizeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D34, maxMergeSize=3D2= 147483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeB= yDeletes=3Dtrue, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930= 22207999E12, noCFSRatio=3D0.8026233123093857] [junit4] 2> 1376857 T3639 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/control/data/index,segFN=3Dsegments_1,generation= =3D1} [junit4] 2> 1376857 T3639 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1376860 T3639 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1376860 T3639 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1376861 T3639 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1376861 T3639 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1376861 T3639 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1376861 T3639 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1376862 T3639 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1376862 T3639 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1376862 T3639 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1376863 T3639 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4] 2> 1376864 T3639 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4] 2> 1376864 T3639 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1376864 T3639 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4] 2> 1376865 T3639 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4] 2> 1376866 T3639 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1376866 T3639 oasc.RequestHandlers.initHandlersFromConfig = created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1376875 T3639 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1376880 T3639 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4] 2> 1376880 T3639 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4] 2> 1376880 T3639 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergeP= olicy: maxMergeAtOnce=3D28, maxMergeAtOnceExplicit=3D45, maxMergedSegmentMB= =3D92.2216796875, floorSegmentMB=3D0.556640625, forceMergeDeletesPctAllowed= =3D3.1001938445087895, segmentsPerTier=3D27.0, maxCFSSegmentSizeMB=3D8.7960= 93022207999E12, noCFSRatio=3D0.0 [junit4] 2> 1376882 T3639 oasc.SolrDeletionPolicy.onInit SolrDeletionP= olicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/control/data/index,segFN=3Dsegments_1,generation= =3D1} [junit4] 2> 1376882 T3639 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1376882 T3639 oass.SolrIndexSearcher. Opening Search= er@6e171550[collection1] main [junit4] 2> 1376886 T3640 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@6e171550[collection1] main{StandardDirect= oryReader(segments_1:1:nrt)} [junit4] 2> 1376887 T3639 oasc.CoreContainer.registerCore registering = core: collection1 [junit4] 2> 1376889 T3614 oass.SolrDispatchFilter.init user.dir=3D/Use= rs/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1376889 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4] 2> 1376889 T3643 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:53674/tr/ef collection:control_= collection shard:shard1 [junit4] 2> 1376893 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1376894 T3643 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leader_elect/shard1/election [junit4] 2> 1376898 T3645 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@61746cde name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1376899 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1376903 T3614 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1376912 T3614 oasc.ChaosMonkey.monkeyLog monkey: init - ex= pire sessions:false cause connection loss:false [junit4] 2> 1376915 T3643 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process for shard shard1 [junit4] 2> 1376920 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1376922 T3643 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4] 2> 1376922 T3643 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1638 name=3Dcollection1 org.apache.solr.c= ore.SolrCore@351fa941 url=3Dhttp://127.0.0.1:53674/tr/ef/collection1 node= =3D127.0.0.1:53674_tr%2Fef C1638_STATE=3Dcoll:control_collection core:colle= ction1 props:{state=3Ddown, base_url=3Dhttp://127.0.0.1:53674/tr/ef, core= =3Dcollection1, node_name=3D127.0.0.1:53674_tr%2Fef} [junit4] 2> 1376923 T3643 C1638 P53674 oasc.SyncStrategy.sync Sync rep= licas to http://127.0.0.1:53674/tr/ef/collection1/ [junit4] 2> 1376923 T3643 C1638 P53674 oasc.SyncStrategy.syncReplicas = Sync Success - now sync replicas to me [junit4] 2> 1376924 T3643 C1638 P53674 oasc.SyncStrategy.syncToMe http= ://127.0.0.1:53674/tr/ef/collection1/ has no replicas [junit4] 2> 1376924 T3643 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:53674/tr/ef/collection1/ shard1 [junit4] 2> 1376924 T3643 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leaders/shard1 [junit4] 2> 1376927 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1376943 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1376946 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1376947 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1376960 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1376965 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1377071 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1377071 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1377107 T3643 oasc.ZkController.register We are http://127= .0.0.1:53674/tr/ef/collection1/ and leader is http://127.0.0.1:53674/tr/ef/= collection1/ [junit4] 2> 1377107 T3643 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:53674/tr/ef [junit4] 2> 1377107 T3643 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4] 2> 1377108 T3643 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive [junit4] 2> 1377108 T3643 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1377110 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1377110 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1377110 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1377112 T3643 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1377114 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1377116 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D3 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "base_url":"http://127.0.0.1:53674/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53674_tr%2Fef", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "core_node_name":"core_node1"} [junit4] 2> 1377124 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1377229 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1377230 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1377285 T3614 oasc.AbstractFullDistribZkTestBase.createJet= tys create jetty 1 [junit4] 2> 1377287 T3614 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1377292 T3614 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:53678 [junit4] 2> 1377294 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1377294 T3614 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1377294 T3614 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: ../../../../../../../../../Users/jenkins/w= orkspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.s= olr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1392013355985 [junit4] 2> 1377295 T3614 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '../../../../../../../../../Users/jenkins/workspac= e/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.clo= ud.ChaosMonkeySafeLeaderTest-jetty1-1392013355985/' [junit4] 2> 1377329 T3614 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/buil= d/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Luce= ne-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.Cha= osMonkeySafeLeaderTest-jetty1-1392013355985/solr.xml [junit4] 2> 1377399 T3614 oasc.CoreContainer. New CoreContainer = 1515545952 [junit4] 2> 1377399 T3614 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D../../../../../../../../../Users/jenkins/worksp= ace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.c= loud.ChaosMonkeySafeLeaderTest-jetty1-1392013355985/] [junit4] 2> 1377401 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1377401 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4] 2> 1377402 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1377402 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1377402 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1377402 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1377403 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1377403 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1377403 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1377412 T3614 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1377413 T3614 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1377413 T3614 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1377414 T3614 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:53671/solr [junit4] 2> 1377414 T3614 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1377416 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1377420 T3656 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1c72aff5 name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4] 2> 1377421 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1377428 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1377432 T3658 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@b88de74 name:ZooKeeperConnection= Watcher:127.0.0.1:53671/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4] 2> 1377432 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1377447 T3614 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1378459 T3614 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:53678_tr%2Fef [junit4] 2> 1378464 T3614 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:53678_tr%2Fef [junit4] 2> 1378482 T3658 oascc.ZkStateReader$3.process Updating live = nodes... (2) [junit4] 2> 1378482 T3636 oascc.ZkStateReader$3.process Updating live = nodes... (2) [junit4] 2> 1378483 T3645 oascc.ZkStateReader$3.process Updating live = nodes... (2) [junit4] 2> 1378517 T3659 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4] 2> 1378517 T3659 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1378522 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1378522 T3659 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1378522 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1378523 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1378535 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1378538 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D3 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "base_url":"http://127.0.0.1:53678/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53678_tr%2Fef", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "core_node_name":null} [junit4] 2> 1378538 T3637 oasc.Overseer$ClusterStateUpdater.createColl= ection Create collection collection1 with shards [shard1, shard2, shard3] [junit4] 2> 1378539 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard2 [junit4] 2> 1378554 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1378666 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1378666 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1378666 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1379523 T3659 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1379524 T3659 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/w= orkspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.s= olr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1392013355985/collection1 [junit4] 2> 1379524 T3659 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4] 2> 1379526 T3659 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1379526 T3659 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1379530 T3659 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1379530 T3659 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '../../../../../../../../../Users/jenkins/workspac= e/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.clo= ud.ChaosMonkeySafeLeaderTest-jetty1-1392013355985/collection1/' [junit4] 2> 1379532 T3659 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-139201= 3355985/collection1/lib/classes/' to classloader [junit4] 2> 1379533 T3659 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-139201= 3355985/collection1/lib/README' to classloader [junit4] 2> 1379619 T3659 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_47 [junit4] 2> 1379714 T3659 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1379726 T3659 oass.IndexSchema.readSchema Reading Solr Sch= ema from schema.xml [junit4] 2> 1379738 T3659 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1380399 T3659 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1380402 T3659 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1380404 T3659 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1380413 T3659 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1380487 T3659 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1380488 T3659 oasc.SolrCore. [collection1] Opening n= ew SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-So= lr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMon= keySafeLeaderTest-jetty1-1392013355985/collection1/, dataDir=3D./org.apache= .solr.cloud.ChaosMonkeySafeLeaderTest-1392013353246/jetty1/ [junit4] 2> 1380488 T3659 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7f2dcd23 [junit4] 2> 1380489 T3659 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/jetty1 [junit4] 2> 1380490 T3659 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.ChaosMonkeySafeLead= erTest-1392013353246/jetty1/index/ [junit4] 2> 1380491 T3659 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1392= 013353246/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 1380492 T3659 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/jetty1/index [junit4] 2> 1380493 T3659 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByte= SizeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D34, maxMergeSize=3D2= 147483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeB= yDeletes=3Dtrue, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930= 22207999E12, noCFSRatio=3D0.8026233123093857] [junit4] 2> 1380497 T3659 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/jetty1/index,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1380498 T3659 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1380504 T3659 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1380505 T3659 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1380506 T3659 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1380506 T3659 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1380506 T3659 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1380506 T3659 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1380507 T3659 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1380508 T3659 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1380508 T3659 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1380509 T3659 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4] 2> 1380510 T3659 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4] 2> 1380510 T3659 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1380510 T3659 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4] 2> 1380511 T3659 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4] 2> 1380512 T3659 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1380513 T3659 oasc.RequestHandlers.initHandlersFromConfig = created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1380526 T3659 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1380530 T3659 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4] 2> 1380530 T3659 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4] 2> 1380531 T3659 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergeP= olicy: maxMergeAtOnce=3D28, maxMergeAtOnceExplicit=3D45, maxMergedSegmentMB= =3D92.2216796875, floorSegmentMB=3D0.556640625, forceMergeDeletesPctAllowed= =3D3.1001938445087895, segmentsPerTier=3D27.0, maxCFSSegmentSizeMB=3D8.7960= 93022207999E12, noCFSRatio=3D0.0 [junit4] 2> 1380533 T3659 oasc.SolrDeletionPolicy.onInit SolrDeletionP= olicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/jetty1/index,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1380533 T3659 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1380534 T3659 oass.SolrIndexSearcher. Opening Search= er@76c8c89d[collection1] main [junit4] 2> 1380538 T3660 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@76c8c89d[collection1] main{StandardDirect= oryReader(segments_1:1:nrt)} [junit4] 2> 1380542 T3659 oasc.CoreContainer.registerCore registering = core: collection1 [junit4] 2> 1380544 T3614 oass.SolrDispatchFilter.init user.dir=3D/Use= rs/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1380544 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4] 2> 1380544 T3663 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:53678/tr/ef collection:collecti= on1 shard:shard2 [junit4] 2> 1380549 T3663 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leader_elect/shard2/election [junit4] 2> 1380567 T3663 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process for shard shard2 [junit4] 2> 1380573 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380574 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380574 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380577 T3663 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4] 2> 1380577 T3663 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1639 name=3Dcollection1 org.apache.solr.c= ore.SolrCore@7c66afe1 url=3Dhttp://127.0.0.1:53678/tr/ef/collection1 node= =3D127.0.0.1:53678_tr%2Fef C1639_STATE=3Dcoll:collection1 core:collection1 = props:{state=3Ddown, base_url=3Dhttp://127.0.0.1:53678/tr/ef, core=3Dcollec= tion1, node_name=3D127.0.0.1:53678_tr%2Fef} [junit4] 2> 1380578 T3663 C1639 P53678 oasc.SyncStrategy.sync Sync rep= licas to http://127.0.0.1:53678/tr/ef/collection1/ [junit4] 2> 1380579 T3663 C1639 P53678 oasc.SyncStrategy.syncReplicas = Sync Success - now sync replicas to me [junit4] 2> 1380579 T3663 C1639 P53678 oasc.SyncStrategy.syncToMe http= ://127.0.0.1:53678/tr/ef/collection1/ has no replicas [junit4] 2> 1380579 T3663 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:53678/tr/ef/collection1/ shard2 [junit4] 2> 1380579 T3663 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leaders/shard2 [junit4] 2> 1380585 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1380607 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380614 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380620 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380620 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380636 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1380645 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380756 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380758 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380758 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380781 T3663 oasc.ZkController.register We are http://127= .0.0.1:53678/tr/ef/collection1/ and leader is http://127.0.0.1:53678/tr/ef/= collection1/ [junit4] 2> 1380781 T3663 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:53678/tr/ef [junit4] 2> 1380782 T3663 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4] 2> 1380782 T3663 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive [junit4] 2> 1380782 T3663 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1380792 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380792 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380793 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380793 T3663 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1380797 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1380799 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D3 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "base_url":"http://127.0.0.1:53678/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53678_tr%2Fef", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "core_node_name":"core_node1"} [junit4] 2> 1380810 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1380918 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380919 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380919 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1380996 T3614 oasc.AbstractFullDistribZkTestBase.createJet= tys create jetty 2 [junit4] 2> 1380997 T3614 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1381002 T3614 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:53681 [junit4] 2> 1381002 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1381002 T3614 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1381003 T3614 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: ../../../../../../../../../Users/jenkins/w= orkspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.s= olr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1392013359618 [junit4] 2> 1381004 T3614 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '../../../../../../../../../Users/jenkins/workspac= e/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.clo= ud.ChaosMonkeySafeLeaderTest-jetty2-1392013359618/' [junit4] 2> 1381041 T3614 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/buil= d/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Luce= ne-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.Cha= osMonkeySafeLeaderTest-jetty2-1392013359618/solr.xml [junit4] 2> 1381130 T3614 oasc.CoreContainer. New CoreContainer = 2114898533 [junit4] 2> 1381131 T3614 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D../../../../../../../../../Users/jenkins/worksp= ace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.c= loud.ChaosMonkeySafeLeaderTest-jetty2-1392013359618/] [junit4] 2> 1381133 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1381133 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4] 2> 1381134 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1381134 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1381134 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1381135 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1381135 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1381135 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1381136 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1381146 T3614 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1381146 T3614 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1381147 T3614 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1381147 T3614 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:53671/solr [junit4] 2> 1381148 T3614 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1381151 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1381156 T3674 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@7d09ed8a name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4] 2> 1381157 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1381167 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1381170 T3676 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@3723bbbb name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1381171 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1381186 T3614 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1382198 T3614 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:53681_tr%2Fef [junit4] 2> 1382202 T3614 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:53681_tr%2Fef [junit4] 2> 1382220 T3636 oascc.ZkStateReader$3.process Updating live = nodes... (3) [junit4] 2> 1382220 T3676 oascc.ZkStateReader$3.process Updating live = nodes... (3) [junit4] 2> 1382220 T3645 oascc.ZkStateReader$3.process Updating live = nodes... (3) [junit4] 2> 1382221 T3658 oascc.ZkStateReader$3.process Updating live = nodes... (3) [junit4] 2> 1382244 T3677 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4] 2> 1382245 T3677 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1382247 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1382248 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1382248 T3677 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1382248 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1382253 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1382254 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D3 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "base_url":"http://127.0.0.1:53681/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53681_tr%2Fef", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "core_node_name":null} [junit4] 2> 1382254 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Collection already exists with numShards=3D3 [junit4] 2> 1382255 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard1 [junit4] 2> 1382260 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1382370 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1382371 T3676 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1382371 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1382371 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1383250 T3677 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1383251 T3677 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/w= orkspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.s= olr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1392013359618/collection1 [junit4] 2> 1383251 T3677 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4] 2> 1383253 T3677 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1383254 T3677 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1383258 T3677 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1383258 T3677 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '../../../../../../../../../Users/jenkins/workspac= e/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.clo= ud.ChaosMonkeySafeLeaderTest-jetty2-1392013359618/collection1/' [junit4] 2> 1383260 T3677 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-139201= 3359618/collection1/lib/classes/' to classloader [junit4] 2> 1383261 T3677 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-139201= 3359618/collection1/lib/README' to classloader [junit4] 2> 1383334 T3677 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_47 [junit4] 2> 1383394 T3677 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1383397 T3677 oass.IndexSchema.readSchema Reading Solr Sch= ema from schema.xml [junit4] 2> 1383410 T3677 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1383931 T3677 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1383935 T3677 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1383936 T3677 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1383942 T3677 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1384009 T3677 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1384009 T3677 oasc.SolrCore. [collection1] Opening n= ew SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-So= lr-4.x-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMon= keySafeLeaderTest-jetty2-1392013359618/collection1/, dataDir=3D./org.apache= .solr.cloud.ChaosMonkeySafeLeaderTest-1392013353246/jetty2/ [junit4] 2> 1384010 T3677 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7f2dcd23 [junit4] 2> 1384011 T3677 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/jetty2 [junit4] 2> 1384012 T3677 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.ChaosMonkeySafeLead= erTest-1392013353246/jetty2/index/ [junit4] 2> 1384012 T3677 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1392= 013353246/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 1384013 T3677 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/jetty2/index [junit4] 2> 1384014 T3677 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByte= SizeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D34, maxMergeSize=3D2= 147483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeB= yDeletes=3Dtrue, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930= 22207999E12, noCFSRatio=3D0.8026233123093857] [junit4] 2> 1384018 T3677 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/jetty2/index,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1384018 T3677 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1384024 T3677 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1384024 T3677 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1384025 T3677 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1384025 T3677 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1384025 T3677 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1384026 T3677 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1384027 T3677 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1384028 T3677 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1384028 T3677 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1384029 T3677 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4] 2> 1384030 T3677 oasc.RequestHandlers.initHandlersFromConfig = created /get: solr.RealTimeGetHandler [junit4] 2> 1384030 T3677 oasc.RequestHandlers.initHandlersFromConfig = adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1384031 T3677 oasc.RequestHandlers.initHandlersFromConfig = created /replication: solr.ReplicationHandler [junit4] 2> 1384032 T3677 oasc.RequestHandlers.initHandlersFromConfig = created /update: solr.UpdateRequestHandler [junit4] 2> 1384033 T3677 oasc.RequestHandlers.initHandlersFromConfig = created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1384034 T3677 oasc.RequestHandlers.initHandlersFromConfig = created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1384046 T3677 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1384050 T3677 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4] 2> 1384050 T3677 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4] 2> 1384050 T3677 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergeP= olicy: maxMergeAtOnce=3D28, maxMergeAtOnceExplicit=3D45, maxMergedSegmentMB= =3D92.2216796875, floorSegmentMB=3D0.556640625, forceMergeDeletesPctAllowed= =3D3.1001938445087895, segmentsPerTier=3D27.0, maxCFSSegmentSizeMB=3D8.7960= 93022207999E12, noCFSRatio=3D0.0 [junit4] 2> 1384053 T3677 oasc.SolrDeletionPolicy.onInit SolrDeletionP= olicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/jetty2/index,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1384053 T3677 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1384053 T3677 oass.SolrIndexSearcher. Opening Search= er@64835d7e[collection1] main [junit4] 2> 1384059 T3678 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@64835d7e[collection1] main{StandardDirect= oryReader(segments_1:1:nrt)} [junit4] 2> 1384062 T3677 oasc.CoreContainer.registerCore registering = core: collection1 [junit4] 2> 1384064 T3614 oass.SolrDispatchFilter.init user.dir=3D/Use= rs/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1384064 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4] 2> 1384064 T3681 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:53681/tr/ef collection:collecti= on1 shard:shard1 [junit4] 2> 1384067 T3681 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leader_elect/shard1/election [junit4] 2> 1384078 T3681 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process for shard shard1 [junit4] 2> 1384083 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384083 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384083 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384084 T3681 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4] 2> 1384085 T3681 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1640 name=3Dcollection1 org.apache.solr.c= ore.SolrCore@48f0a0ff url=3Dhttp://127.0.0.1:53681/tr/ef/collection1 node= =3D127.0.0.1:53681_tr%2Fef C1640_STATE=3Dcoll:collection1 core:collection1 = props:{state=3Ddown, base_url=3Dhttp://127.0.0.1:53681/tr/ef, core=3Dcollec= tion1, node_name=3D127.0.0.1:53681_tr%2Fef} [junit4] 2> 1384085 T3681 C1640 P53681 oasc.SyncStrategy.sync Sync rep= licas to http://127.0.0.1:53681/tr/ef/collection1/ [junit4] 2> 1384085 T3681 C1640 P53681 oasc.SyncStrategy.syncReplicas = Sync Success - now sync replicas to me [junit4] 2> 1384085 T3681 C1640 P53681 oasc.SyncStrategy.syncToMe http= ://127.0.0.1:53681/tr/ef/collection1/ has no replicas [junit4] 2> 1384085 T3681 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:53681/tr/ef/collection1/ shard1 [junit4] 2> 1384086 T3681 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leaders/shard1 [junit4] 2> 1384094 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1384098 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384106 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384109 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384113 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384113 T3676 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384117 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1384123 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384227 T3676 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384228 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384228 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384228 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384255 T3681 oasc.ZkController.register We are http://127= .0.0.1:53681/tr/ef/collection1/ and leader is http://127.0.0.1:53681/tr/ef/= collection1/ [junit4] 2> 1384255 T3681 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:53681/tr/ef [junit4] 2> 1384255 T3681 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4] 2> 1384256 T3681 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive [junit4] 2> 1384256 T3681 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1384258 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384258 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384258 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384261 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1384262 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D3 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "base_url":"http://127.0.0.1:53681/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53681_tr%2Fef", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "core_node_name":"core_node2"} [junit4] 2> 1384268 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1384269 T3681 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1384375 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384376 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384376 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384377 T3676 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 1384425 T3614 oasc.AbstractFullDistribZkTestBase.createJet= tys create jetty 3 [junit4] 2> 1384427 T3614 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1384433 T3614 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:53684 [junit4] 2> 1384436 T3614 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1384436 T3614 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1384437 T3614 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeySafeLea= derTest-jetty3-1392013363137 [junit4] 2> 1384438 T3614 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest= -jetty3-1392013363137/' [junit4] 2> 1384466 T3614 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/buil= d/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty= 3-1392013363137/solr.xml [junit4] 2> 1384553 T3614 oasc.CoreContainer. New CoreContainer = 983510294 [junit4] 2> 1384555 T3614 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D./org.apache.solr.cloud.ChaosMonkeySafeLeaderTe= st-jetty3-1392013363137/] [junit4] 2> 1384557 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1384557 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to: http:// [junit4] 2> 1384557 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1384558 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1384558 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1384558 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1384558 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1384559 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1384559 T3614 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1384570 T3614 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1384570 T3614 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1384571 T3614 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1384571 T3614 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:53671/solr [junit4] 2> 1384572 T3614 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1384573 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1384578 T3692 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@28491d6b name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4] 2> 1384579 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1384589 T3614 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1384594 T3694 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@6deca583 name:ZooKeeperConnectio= n Watcher:127.0.0.1:53671/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1384594 T3614 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1384604 T3614 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1385614 T3614 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:53684_tr%2Fef [junit4] 2> 1385619 T3614 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:53684_tr%2Fef [junit4] 2> 1385637 T3645 oascc.ZkStateReader$3.process Updating live = nodes... (4) [junit4] 2> 1385641 T3676 oascc.ZkStateReader$3.process Updating live = nodes... (4) [junit4] 2> 1385642 T3658 oascc.ZkStateReader$3.process Updating live = nodes... (4) [junit4] 2> 1385642 T3694 oascc.ZkStateReader$3.process Updating live = nodes... (4) [junit4] 2> 1385643 T3636 oascc.ZkStateReader$3.process Updating live = nodes... (4) [junit4] 2> 1385673 T3695 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown [junit4] 2> 1385673 T3695 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1385678 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1385679 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1385680 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1385678 T3695 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1385689 T3637 oascc.ZkStateReader.updateClusterState Updat= ing cloud state from ZooKeeper...=20 [junit4] 2> 1385692 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Update state numShards=3D3 message=3D{ [junit4] 2> =09 "operation":"state", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "base_url":"http://127.0.0.1:53684/tr/ef", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "node_name":"127.0.0.1:53684_tr%2Fef", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "shard_range":null, [junit4] 2> =09 "shard_state":"active", [junit4] 2> =09 "shard_parent":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "numShards":"3", [junit4] 2> =09 "core_node_name":null} [junit4] 2> 1385693 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Collection already exists with numShards=3D3 [junit4] 2> 1385693 T3637 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard3 [junit4] 2> 1385709 T3636 oasc.DistributedQueue$LatchChildWatcher.proc= ess LatchChildWatcher fired on path: /overseer/queue state: SyncConnected t= ype NodeChildrenChanged [junit4] 2> 1385818 T3676 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 1385819 T3694 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 1385819 T3636 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 1385820 T3658 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 1385819 T3645 oascc.ZkStateReader$2.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clust= erstate.json, has occurred - updating... (live nodes size: 4) [junit4] 2> 1386682 T3695 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1386683 T3695 oasc.CoreContainer.create Creating SolrCore = 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeySafeLea= derTest-jetty3-1392013363137/collection1 [junit4] 2> 1386683 T3695 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4] 2> 1386685 T3695 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1386685 T3695 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1386688 T3695 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1386689 T3695 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest= -jetty3-1392013363137/collection1/' [junit4] 2> 1386690 T3695 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-139201= 3363137/collection1/lib/classes/' to classloader [junit4] 2> 1386690 T3695 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr= -core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-139201= 3363137/collection1/lib/README' to classloader [junit4] 2> 1386761 T3695 oasc.SolrConfig. Using Lucene MatchVer= sion: LUCENE_47 [junit4] 2> 1386828 T3695 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1386832 T3695 oass.IndexSchema.readSchema Reading Solr Sch= ema from schema.xml [junit4] 2> 1386840 T3695 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1387379 T3695 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1387382 T3695 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1387383 T3695 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1387393 T3695 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1387469 T3695 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1387469 T3695 oasc.SolrCore. [collection1] Opening n= ew SolrCore at ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-139= 2013363137/collection1/, dataDir=3D./org.apache.solr.cloud.ChaosMonkeySafeL= eaderTest-1392013353246/jetty3/ [junit4] 2> 1387469 T3695 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7f2dcd23 [junit4] 2> 1387470 T3695 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/jetty3 [junit4] 2> 1387470 T3695 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D./org.apache.solr.cloud.ChaosMonkeySafeLead= erTest-1392013353246/jetty3/index/ [junit4] 2> 1387471 T3695 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1392= 013353246/jetty3/index' doesn't exist. Creating new index... [junit4] 2> 1387472 T3695 oasc.CachingDirectoryFactory.get return new = directory for /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/so= lr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-13920133532= 46/jetty3/index [junit4] 2> 1387473 T3695 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByte= SizeMergePolicy: minMergeSize=3D1677721, mergeFactor=3D34, maxMergeSize=3D2= 147483648, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeB= yDeletes=3Dtrue, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930= 22207999E12, noCFSRatio=3D0.8026233123093857] [junit4] 2> 1387476 T3695 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/Users/jenkins/workspace/Lucene-Solr-4.= x-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafe= LeaderTest-1392013353246/jetty3/index,segFN=3Dsegments_1,generation=3D1} [junit4] 2> 1387476 T3695 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1387480 T3695 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1387481 T3695 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1387481 T3695 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1387481 T3695 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1387482 T3695 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1387482 T3695 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1387482 T3695 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1387483 T3695 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1387483 T3695 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined a [...truncated too long message...] ard3 [junit4] 2> 1482123 T3871 oasc.SolrException.log ERROR There was a pro= blem trying to register as the leader:org.apache.zookeeper.KeeperException$= SessionExpiredException: KeeperErrorCode =3D Session expired for /collectio= ns [junit4] 2> =09=09at org.apache.zookeeper.KeeperException.create(Keepe= rException.java:127) [junit4] 2> =09=09at org.apache.zookeeper.KeeperException.create(Keepe= rException.java:51) [junit4] 2> =09=09at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.j= ava:1041) [junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient$3.execu= te(SolrZkClient.java:206) [junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient$3.execu= te(SolrZkClient.java:203) [junit4] 2> =09=09at org.apache.solr.common.cloud.ZkCmdExecutor.retryO= peration(ZkCmdExecutor.java:73) [junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient.exists(= SolrZkClient.java:203) [junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient.makePat= h(SolrZkClient.java:414) [junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient.makePat= h(SolrZkClient.java:383) [junit4] 2> =09=09at org.apache.solr.common.cloud.SolrZkClient.makePat= h(SolrZkClient.java:370) [junit4] 2> =09=09at org.apache.solr.cloud.ShardLeaderElectionContextB= ase.runLeaderProcess(ElectionContext.java:112) [junit4] 2> =09=09at org.apache.solr.cloud.ShardLeaderElectionContext.= runLeaderProcess(ElectionContext.java:273) [junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.runIamLeaderP= rocess(LeaderElector.java:164) [junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.checkIfIamLea= der(LeaderElector.java:108) [junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector.access$000(Le= aderElector.java:55) [junit4] 2> =09=09at org.apache.solr.cloud.LeaderElector$1.process(Lea= derElector.java:137) [junit4] 2> =09=09at org.apache.zookeeper.ClientCnxn$EventThread.proce= ssEvent(ClientCnxn.java:519) [junit4] 2> =09=09at org.apache.zookeeper.ClientCnxn$EventThread.run(C= lientCnxn.java:495) [junit4] 2> =09 [junit4] 2> 1482124 T3871 oasc.LeaderElector$1.process WARN org.apach= e.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =3D Se= ssion expired for /collections/collection1/leader_elect/shard3/election/912= 26987118788636-core_node12-n_0000000003 [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java= :873) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$2.execute(= SolrZkClient.java:174) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$2.execute(= SolrZkClient.java:171) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:73) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.delete(Sol= rZkClient.java:171) [junit4] 2> =09at org.apache.solr.cloud.ElectionContext.cancelElection= (ElectionContext.java:67) [junit4] 2> =09at org.apache.solr.cloud.ShardLeaderElectionContext.run= LeaderProcess(ElectionContext.java:293) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.runIamLeaderProc= ess(LeaderElector.java:164) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLeader= (LeaderElector.java:108) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(Leade= rElector.java:55) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Leader= Elector.java:137) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:519) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:495) [junit4] 2>=20 [junit4] 2> 1482124 T3871 oasc.LeaderElector$1.process WARN org.apach= e.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =3D Se= ssion expired for /overseer_elect/election [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:127) [junit4] 2> =09at org.apache.zookeeper.KeeperException.create(KeeperEx= ception.java:51) [junit4] 2> =09at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper= .java:1468) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:257) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient$6.execute(= SolrZkClient.java:254) [junit4] 2> =09at org.apache.solr.common.cloud.ZkCmdExecutor.retryOper= ation(ZkCmdExecutor.java:73) [junit4] 2> =09at org.apache.solr.common.cloud.SolrZkClient.getChildre= n(SolrZkClient.java:254) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.checkIfIamLeader= (LeaderElector.java:92) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector.access$000(Leade= rElector.java:55) [junit4] 2> =09at org.apache.solr.cloud.LeaderElector$1.process(Leader= Elector.java:137) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.processE= vent(ClientCnxn.java:519) [junit4] 2> =09at org.apache.zookeeper.ClientCnxn$EventThread.run(Clie= ntCnxn.java:495) [junit4] 2>=20 [junit4] 2> 1482124 T3871 oascc.ZkStateReader$3.process WARN ZooKeeper= watch triggered, but Solr cannot talk to ZK [junit4] 2> NOTE: test params are: codec=3DAsserting, sim=3DDefaultSim= ilarity, locale=3Dit, timezone=3DAmerica/Coral_Harbour [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_51 (= 64-bit)/cpus=3D2,threads=3D1,free=3D398869944,total=3D519438336 [junit4] 2> NOTE: All tests run in this JVM: [TestFiltering, SpellChec= kCollatorTest, TestExtendedDismaxParser, RemoteQueryErrorTest, SolrCoreTest= , TestFieldResource, BasicDistributedZk2Test, TestGroupingSearch, TestClass= NameShortening, TestPostingsSolrHighlighter, FieldAnalysisRequestHandlerTes= t, AssignTest, TestSolr4Spatial, TestLuceneMatchVersion, SolrIndexConfigTes= t, HdfsUnloadDistributedZkTest, XsltUpdateRequestHandlerTest, QueryEquality= Test, TestUniqueKeyFieldResource, SuggesterTest, HighlighterTest, SoftAutoC= ommitTest, TestStressUserVersions, SpellingQueryConverterTest, CursorPaging= Test, BadCopyFieldTest, PathHierarchyTokenizerFactoryTest, TestFuzzyAnalyze= dSuggestions, TestLFUCache, PrimUtilsTest, TestWordDelimiterFilterFactory, = TestSerializedLuceneMatchVersion, DateFieldTest, TestCollationKeyRangeQueri= es, TestSolrDeletionPolicy1, TestAddFieldRealTimeGet, ZkControllerTest, Tes= tMaxScoreQueryParser, BlockDirectoryTest, ResourceLoaderTest, TestJoin, Num= ericFieldsTest, TestDynamicFieldCollectionResource, BasicDistributedZkTest,= SolrPluginUtilsTest, RecoveryZkTest, TestFieldCollectionResource, OpenClos= eCoreStressTest, TestJmxIntegration, DisMaxRequestHandlerTest, BadComponent= Test, DocumentBuilderTest, MultiTermTest, InfoHandlerTest, LeaderElectionIn= tegrationTest, SuggesterWFSTTest, ResponseLogComponentTest, TestJmxMonitore= dMap, TestPartialUpdateDeduplication, TestStressReorder, TestSchemaSimilari= tyResource, TestRandomDVFaceting, SolrIndexSplitterTest, TestDistributedSea= rch, TestFieldTypeResource, SliceStateUpdateTest, TestSystemIdResolver, Ove= rseerCollectionProcessorTest, DeleteShardTest, TestReversedWildcardFilterFa= ctory, TestDefaultSearchFieldResource, TestMultiCoreConfBootstrap, DateMath= ParserTest, SOLR749Test, TestQueryUtils, TestRandomFaceting, SearchHandlerT= est, IndexSchemaTest, HighlighterConfigTest, CurrencyFieldXmlFileTest, Hdfs= DirectoryTest, ChangedSchemaMergeTest, CursorMarkTest, DistributedSpellChec= kComponentTest, TestQueryTypes, TestPhraseSuggestions, MBeansHandlerTest, S= yncSliceTest, TestImplicitCoreProperties, SimpleFacetsTest, DistributedTerm= sComponentTest, UnloadDistributedZkTest, TestOverriddenPrefixQueryForCustom= FieldType, StatelessScriptUpdateProcessorFactoryTest, DistributedQueryCompo= nentCustomSortTest, DirectSolrSpellCheckerTest, TestHighFrequencyDictionary= Factory, DistributedSuggestComponentTest, TestFastWriter, LegacyHTMLStripCh= arFilterTest, TestStandardQParsers, DocValuesMissingTest, SystemInfoHandler= Test, SampleTest, TestCustomSort, ClusterStateUpdateTest, URLClassifyProces= sorTest, BlockCacheTest, TestSolrJ, SolrXmlInZkTest, AddSchemaFieldsUpdateP= rocessorFactoryTest, TestRangeQuery, TestUpdate, SolrTestCaseJ4Test, Cluste= rStateTest, TestBM25SimilarityFactory, TestStressLucene, DistribCursorPagin= gTest, ParsingFieldUpdateProcessorsTest, ShardRoutingTest, ExternalFileFiel= dSortTest, TestZkChroot, BasicFunctionalityTest, TestSolrDeletionPolicy2, R= egexBoostProcessorTest, ConvertedLegacyTest, TestSweetSpotSimilarityFactory= , TestComponentsName, DebugComponentTest, QueryParsingTest, IndexSchemaRunt= imeFieldTest, TestHashPartitioner, AlternateDirectoryTest, ChaosMonkeySafeL= eaderTest] [junit4] Completed in 108.86s, 1 test, 1 error <<< FAILURES! [...truncated 687 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:459: The followin= g error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:439: The followin= g error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:39: The following= error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The f= ollowing error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The fol= lowing error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:491: = The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:130= 8: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:941= : There were test failures: 360 suites, 1558 tests, 1 error, 40 ignored (6 = assumptions) Total time: 88 minutes 15 seconds Build step 'Invoke Ant' marked build as failure Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseConcMa= rkSweepGC Archiving artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure ------=_Part_210_965445297.1392015350662 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_210_965445297.1392015350662--