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 22DAB10ACF for ; Sat, 6 Dec 2014 15:02:22 +0000 (UTC) Received: (qmail 82004 invoked by uid 500); 6 Dec 2014 15:02:20 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 81928 invoked by uid 500); 6 Dec 2014 15:02:20 -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 81914 invoked by uid 99); 6 Dec 2014 15:02:19 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 06 Dec 2014 15:02:19 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (athena.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; Sat, 06 Dec 2014 15:02:13 +0000 Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1.sd-datasolutions.de with esmtp (Exim 4.76) (envelope-from ) id 1XxGpi-0006xe-Sm; Sat, 06 Dec 2014 14:59:43 +0000 Date: Sat, 6 Dec 2014 14:59:14 +0000 (UTC) From: Policeman Jenkins Server To: mikemccand@apache.org, dev@lucene.apache.org Message-ID: <1670293331.111.1417877982859.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-5.x-Linux (64bit/jdk1.8.0_40-ea-b09) - Build # 11564 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_110_977699372.1417877954172" X-Jenkins-Job: Lucene-Solr-5.x-Linux X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_110_977699372.1417877954172 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11564/ Java: 64bit/jdk1.8.0_40-ea-b09 -XX:+UseCompressedOops -XX:+UseG1GC (asserts= : true) 2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeySafeLea= derTest Error Message: Suite timeout exceeded (>=3D 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>=3D 7200000 msec). =09at __randomizedtesting.SeedInfo.seed([2B1B1B942CA18058]:0) FAILED: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch Error Message: Test abandoned because suite timeout was reached. Stack Trace: java.lang.Exception: Test abandoned because suite timeout was reached. =09at __randomizedtesting.SeedInfo.seed([2B1B1B942CA18058]:0) Build Log: [...truncated 10602 lines...] [junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5= .x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeader= Test-2B1B1B942CA18058-001/init-core-data-001 [junit4] 2> 1600437 T7804 oas.SolrTestCaseJ4.buildSSLConfig Randomized= ssl (false) and clientAuth (true) [junit4] 2> 1600437 T7804 oas.BaseDistributedSearchTestCase.initHostCo= ntext Setting hostContext system property: /k_bt/ [junit4] 2> 1600442 T7804 oas.SolrTestCaseJ4.setUp ###Starting testDis= tribSearch [junit4] 2> 1600442 T7804 oasc.ZkTestServer.run STARTING ZK TEST SERVE= R [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1600443 T7805 oasc.ZkTestServer$ZKServerMain.runFromConfig= Starting server [junit4] 2> 1600543 T7804 oasc.ZkTestServer.run start zk server on por= t:46715 [junit4] 2> 1600543 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1600544 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1600546 T7812 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@1334bdf2 name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4] 2> 1600547 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1600547 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1600547 T7804 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 1600551 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1600565 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1600566 T7815 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@6cdd8824 name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1600566 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1600567 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1600567 T7804 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1 [junit4] 2> 1600569 T7804 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/shards [junit4] 2> 1600570 T7804 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection [junit4] 2> 1600571 T7804 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/shards [junit4] 2> 1600572 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1600572 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.xml [junit4] 2> 1600574 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1600574 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/schema.xml [junit4] 2> 1600575 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/s= olrconfig.snippet.randomindexconfig.xml [junit4] 2> 1600576 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1600577 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1600577 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/stopwords.txt [junit4] 2> 1600578 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1600579 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/protwords.txt [junit4] 2> 1600580 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1600580 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/currency.xml [junit4] 2> 1600581 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1600581 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/enumsConfig.xml [junit4] 2> 1600582 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rat= es.json [junit4] 2> 1600583 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/open-exchange-rates.json [junit4] 2> 1600584 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLat= in1Accent.txt [junit4] 2> 1600584 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1600585 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1600586 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/old_synonyms.txt [junit4] 2> 1600587 T7804 oasc.AbstractZkTestCase.putConfig put /mnt/s= sd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/co= llection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1600587 T7804 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/synonyms.txt [junit4] 2> 1600846 T7804 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1600848 T7804 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:50231 [junit4] 2> 1600850 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1600851 T7804 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1600851 T7804 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x= -Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTe= st-2B1B1B942CA18058-001/tempDir-002 [junit4] 2> 1600851 T7804 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B= 1B942CA18058-001/tempDir-002/' [junit4] 2> 1600866 T7804 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/bui= ld/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA1= 8058-001/tempDir-002/solr.xml [junit4] 2> 1600873 T7804 oasc.CoreContainer. New CoreContainer = 1177871281 [junit4] 2> 1600873 T7804 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linu= x/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B= 1B1B942CA18058-001/tempDir-002/] [junit4] 2> 1600874 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1600874 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to:=20 [junit4] 2> 1600874 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1600875 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1600875 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnections to: 10000 [junit4] 2> 1600875 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1600875 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1600875 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1600876 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1600876 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1600876 T7804 oasu.UpdateShardHandler. Creating Upda= teShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dfalse [junit4] 2> 1600877 T7804 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1600877 T7804 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1600877 T7804 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1600877 T7804 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:46715/solr [junit4] 2> 1600878 T7804 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1600878 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1600879 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1600880 T7827 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@4c5ea190 name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715 got event WatchedEvent state:SyncConnected type:N= one path:null path:null type:None [junit4] 2> 1600882 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1600882 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1600884 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1600885 T7830 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@770d0aad name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1600885 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1600886 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer/queue [junit4] 2> 1600888 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer/collection-queue-work [junit4] 2> 1600889 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer/collection-map-running [junit4] 2> 1600890 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer/collection-map-completed [junit4] 2> 1600891 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer/collection-map-failure [junit4] 2> 1600893 T7804 oascc.SolrZkClient.makePath makePath: /live_= nodes [junit4] 2> 1600893 T7804 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:50231_k_bt [junit4] 2> 1600894 T7804 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:50231_k_bt [junit4] 2> 1600895 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer_elect [junit4] 2> 1600896 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/election [junit4] 2> 1600897 T7804 oasc.Overseer.close Overseer (id=3Dnull) clo= sing [junit4] 2> 1600898 T7804 oasc.OverseerElectionContext.runLeaderProces= s I am going to be the leader 127.0.0.1:50231_k_bt [junit4] 2> 1600898 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer_elect/leader [junit4] 2> 1600900 T7804 oasc.Overseer.start Overseer (id=3D929215749= 03250947-127.0.0.1:50231_k_bt-n_0000000000) starting [junit4] 2> 1600901 T7804 oascc.SolrZkClient.makePath makePath: /overs= eer/queue-work [junit4] 2> 1600907 T7804 oasc.OverseerAutoReplicaFailoverThread. Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDel= ay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D30000 autoReplicaFailov= erBadNodeExpiration=3D60000 [junit4] 2> 1600908 T7832 oasc.OverseerCollectionProcessor.run Process= current queue of collection creations [junit4] 2> 1600908 T7804 oascc.SolrZkClient.makePath makePath: /clust= erstate.json [junit4] 2> 1600911 T7804 oascc.SolrZkClient.makePath makePath: /alias= es.json [junit4] 2> 1600913 T7804 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1600916 T7831 oasc.Overseer$ClusterStateUpdater.run Starti= ng to work on the main queue [junit4] 2> 1600917 T7834 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown collection=3Dcontrol_collection [junit4] 2> 1600918 T7834 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1600918 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1600918 T7834 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1600924 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:50231/k_bt", [junit4] 2> =09 "node_name":"127.0.0.1:50231_k_bt", [junit4] 2> =09 "numShards":"1", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "operation":"state"} [junit4] 2> 1600924 T7831 oasc.Overseer$ClusterStateUpdater.createColl= ection Create collection control_collection with shards [shard1] [junit4] 2> 1600925 T7831 oasc.Overseer$ClusterStateUpdater.createColl= ection state version control_collection 1 [junit4] 2> 1600926 T7831 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard1 [junit4] 2> 1600928 T7830 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> 1601919 T7834 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1601920 T7834 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:control_collection [junit4] 2> 1601920 T7834 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1601921 T7834 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/control_collection [junit4] 2> 1601922 T7834 oascc.ZkStateReader.readConfigName path=3D/c= ollections/control_collection configName=3Dconf1 specified config exists in= ZooKeeper [junit4] 2> 1601922 T7834 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B= 1B942CA18058-001/tempDir-002/collection1/' [junit4] 2> 1601923 T7834 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 1601924 T7834 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 1601924 T7834 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 1601944 T7834 oasc.ZkController.watchZKConfDir watch zkdir= /configs/conf1 [junit4] 2> 1601946 T7834 oasc.Config. loaded config solrconfig.= xml with version 0=20 [junit4] 2> 1601954 T7834 oasc.SolrConfig. Using Lucene MatchVer= sion: 5.0.0 [junit4] 2> 1601983 T7834 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1601984 T7834 oass.IndexSchema.readSchema Reading Solr Sch= ema from /configs/conf1/schema.xml [junit4] 2> 1601988 T7834 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1602055 T7834 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1602056 T7834 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1602057 T7834 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1602059 T7834 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1602070 T7834 oasc.CoreContainer.create Creating SolrCore = 'collection1' using configuration from collection control_collection [junit4] 2> 1602071 T7834 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1602078 T7834 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/= solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA1805= 8-001/tempDir-002/collection1/, dataDir=3D/mnt/ssd/jenkins/workspace/Lucene= -Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySaf= eLeaderTest-2B1B1B942CA18058-001/tempDir-001/control/data/ [junit4] 2> 1602079 T7834 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7ed262fd [junit4] 2> 1602079 T7834 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058= -001/tempDir-001/control/data [junit4] 2> 1602080 T7834 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-= Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTes= t-2B1B1B942CA18058-001/tempDir-001/control/data/index/ [junit4] 2> 1602080 T7834 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/= build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942= CA18058-001/tempDir-001/control/data/index' doesn't exist. Creating new ind= ex... [junit4] 2> 1602080 T7834 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058= -001/tempDir-001/control/data/index [junit4] 2> 1602081 T7834 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergeP= olicy: maxMergeAtOnce=3D47, maxMergeAtOnceExplicit=3D43, maxMergedSegmentMB= =3D70.75, floorSegmentMB=3D2.1552734375, forceMergeDeletesPctAllowed=3D1.44= 69324746518974, segmentsPerTier=3D32.0, maxCFSSegmentSizeMB=3D8.79609302220= 7999E12, noCFSRatio=3D0.5884565688349047 [junit4] 2> 1602183 T7834 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-= 5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeade= rTest-2B1B1B942CA18058-001/tempDir-001/control/data/index,segFN=3Dsegments_= 1,generation=3D1} [junit4] 2> 1602184 T7834 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1602186 T7834 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1602186 T7834 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1602186 T7834 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1602186 T7834 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1602186 T7834 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1602187 T7834 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1602187 T7834 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1602187 T7834 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1602187 T7834 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1602187 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1602188 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1602188 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1602188 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1602188 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 1602188 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 1602189 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 1602189 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 1602189 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 1602190 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 1602190 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 1602190 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 1602190 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 1602191 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestH= andler [junit4] 2> 1602191 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 1602191 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 1602191 T7834 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4] 2> 1602191 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1602192 T7834 oasc.RequestHandlers.initHandlersFromConfig = created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1602193 T7834 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1602194 T7834 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1602194 T7834 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1602195 T7834 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1602196 T7834 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4] 2> 1602196 T7834 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4] 2> 1602196 T7834 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergeP= olicy: minMergeSize=3D1000, mergeFactor=3D44, maxMergeSize=3D92233720368547= 75807, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByDel= etes=3Dfalse, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930222= 07999E12, noCFSRatio=3D0.1755904984218118] [junit4] 2> 1602197 T7834 oasc.SolrDeletionPolicy.onInit SolrDeletionP= olicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-= 5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeade= rTest-2B1B1B942CA18058-001/tempDir-001/control/data/index,segFN=3Dsegments_= 1,generation=3D1} [junit4] 2> 1602197 T7834 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1602197 T7834 oass.SolrIndexSearcher. Opening Search= er@282d78c2[collection1] main [junit4] 2> 1602197 T7834 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/control_collection [junit4] 2> 1602198 T7834 oascc.ZkStateReader.readConfigName path=3D/c= ollections/control_collection configName=3Dconf1 specified config exists in= ZooKeeper [junit4] 2> 1602199 T7834 oasr.ManagedResourceStorage.newStorageIO Set= ting up ZooKeeper-based storage for the RestManager with znodeBase: /config= s/conf1 [junit4] 2> 1602199 T7834 oasr.ManagedResourceStorage$ZooKeeperStorage= IO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1602199 T7834 oasr.RestManager.init Initializing RestManag= er with initArgs: {} [junit4] 2> 1602199 T7834 oasr.ManagedResourceStorage.load Reading _re= st_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1602200 T7834 oasr.ManagedResourceStorage$ZooKeeperStorage= IO.openInputStream No data found for znode /configs/conf1/_rest_managed.jso= n [junit4] 2> 1602200 T7834 oasr.ManagedResourceStorage.load Loaded null= at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1602201 T7834 oascc.SolrZkClient.makePath makePath: /confi= gs/conf1/_rest_managed.json [junit4] 2> 1602202 T7834 oasr.ManagedResourceStorage$ZooKeeperStorage= IO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 1602202 T7834 oasr.ManagedResourceStorage$JsonStorage.stor= e Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:pat= h=3D/configs/conf1 [junit4] 2> 1602202 T7834 oasr.RestManager.init Initializing 0 registe= red ManagedResources [junit4] 2> 1602203 T7834 oash.ReplicationHandler.inform Commits will = be reserved for 10000 [junit4] 2> 1602203 T7835 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@282d78c2[collection1] main{ExitableDirect= oryReader(UninvertingDirectoryReader())} [junit4] 2> 1602203 T7834 oasc.CoreContainer.registerCore registering = core: collection1 [junit4] 2> 1602204 T7838 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:50231/k_bt collection:control_c= ollection shard:shard1 [junit4] 2> 1602204 T7804 oass.SolrDispatchFilter.init user.dir=3D/mnt= /ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1602204 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4] 2> 1602205 T7838 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leader_elect/shard1/election [junit4] 2> 1602205 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1602206 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1602207 T7841 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@3a39acf5 name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1602207 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1602207 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1602208 T7838 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process for shard shard1 [junit4] 2> 1602208 T7804 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1602208 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1602209 T7838 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4] 2> 1602209 T7838 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1025 name=3Dcollection1 org.apache.solr.c= ore.SolrCore@75f40871 url=3Dhttp://127.0.0.1:50231/k_bt/collection1 node=3D= 127.0.0.1:50231_k_bt C1025_STATE=3Dcoll:control_collection core:collection1= props:{core=3Dcollection1, base_url=3Dhttp://127.0.0.1:50231/k_bt, node_na= me=3D127.0.0.1:50231_k_bt, state=3Ddown} [junit4] 2> 1602209 T7838 C1025 P50231 oasc.SyncStrategy.sync Sync rep= licas to http://127.0.0.1:50231/k_bt/collection1/ [junit4] 2> 1602209 T7838 C1025 P50231 oasc.SyncStrategy.syncReplicas = Sync Success - now sync replicas to me [junit4] 2> 1602209 T7838 C1025 P50231 oasc.SyncStrategy.syncToMe http= ://127.0.0.1:50231/k_bt/collection1/ has no replicas [junit4] 2> 1602210 T7838 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:50231/k_bt/collection1/ shard1 [junit4] 2> 1602210 T7804 oasc.ChaosMonkey.monkeyLog monkey: init - ex= pire sessions:false cause connection loss:false [junit4] 2> 1602210 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection"} [junit4] 2> 1602210 T7838 oascc.SolrZkClient.makePath makePath: /colle= ctions/control_collection/leaders/shard1 [junit4] 2> 1602210 T7804 oasc.AbstractFullDistribZkTestBase.createJet= tys Creating collection1 with stateFormat=3D2 [junit4] 2> 1602211 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1602211 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1602212 T7844 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@617a3416 name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1602212 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1602212 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1602213 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1602213 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "base_url":"http://127.0.0.1:50231/k_bt", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "state":"active"} [junit4] 2> 1602215 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"create", [junit4] 2> =09 "name":"collection1", [junit4] 2> =09 "numShards":"2", [junit4] 2> =09 "stateFormat":2} [junit4] 2> 1602215 T7831 oasc.Overseer$ClusterStateUpdater.buildColle= ction Building a new collection: collection1 [junit4] 2> 1602216 T7831 oasc.Overseer$ClusterStateUpdater.createColl= ection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 1602216 T7831 oasc.Overseer$ClusterStateUpdater.createColl= ection state version collection1 2 [junit4] 2> 1602217 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1602217 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to create_collection /collections/collection1/state.json [junit4] 2> 1602218 T7841 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> 1602218 T7830 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> 1602263 T7838 oasc.ZkController.register We are http://127= .0.0.1:50231/k_bt/collection1/ and leader is http://127.0.0.1:50231/k_bt/co= llection1/ [junit4] 2> 1602263 T7838 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:50231/k_bt [junit4] 2> 1602263 T7838 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4] 2> 1602263 T7838 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive collection=3Dcontrol_collection [junit4] 2> 1602263 T7838 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1602265 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1602267 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:50231/k_bt", [junit4] 2> =09 "node_name":"127.0.0.1:50231_k_bt", [junit4] 2> =09 "numShards":"2", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"control_collection", [junit4] 2> =09 "operation":"state"} [junit4] 2> 1602368 T7830 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> 1602368 T7841 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> 1602461 T7804 oasc.AbstractFullDistribZkTestBase.createJet= tys create jetty 1 [junit4] 2> 1602462 T7804 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1602463 T7804 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:42942 [junit4] 2> 1602464 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1602465 T7804 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1602465 T7804 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x= -Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTe= st-2B1B1B942CA18058-001/tempDir-003 [junit4] 2> 1602465 T7804 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B= 1B942CA18058-001/tempDir-003/' [junit4] 2> 1602476 T7804 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/bui= ld/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA1= 8058-001/tempDir-003/solr.xml [junit4] 2> 1602482 T7804 oasc.CoreContainer. New CoreContainer = 1771495487 [junit4] 2> 1602483 T7804 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linu= x/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B= 1B1B942CA18058-001/tempDir-003/] [junit4] 2> 1602483 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1602483 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to:=20 [junit4] 2> 1602483 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1602484 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1602484 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnections to: 10000 [junit4] 2> 1602484 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1602484 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1602484 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1602485 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1602485 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1602485 T7804 oasu.UpdateShardHandler. Creating Upda= teShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dfalse [junit4] 2> 1602486 T7804 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1602486 T7804 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1602486 T7804 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1602486 T7804 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:46715/solr [junit4] 2> 1602487 T7804 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1602487 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1602487 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1602489 T7856 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@2590fec name:ZooKeeperConnection= Watcher:127.0.0.1:46715 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4] 2> 1602490 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1602490 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1602492 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1602493 T7859 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@20a32acd name:ZooKeeperConnectio= n Watcher:127.0.0.1:46715/solr got event WatchedEvent state:SyncConnected t= ype:None path:null path:null type:None [junit4] 2> 1602493 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1602497 T7804 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1603500 T7804 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:42942_k_bt [junit4] 2> 1603501 T7804 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:42942_k_bt [junit4] 2> 1603505 T7804 oasc.Overseer.close Overseer (id=3Dnull) clo= sing [junit4] 2> 1603512 T7860 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown collection=3Dcollection1 [junit4] 2> 1603512 T7860 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1603513 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1603514 T7860 oasc.ZkController.preRegister Registering wa= tch for external collection collection1 [junit4] 2> 1603514 T7860 oascc.ZkStateReader.addZkWatch addZkWatch co= llection1 [junit4] 2> 1603515 T7860 oascc.ZkStateReader.addZkWatch Updating coll= ection state at /collections/collection1/state.json from ZooKeeper...=20 [junit4] 2> 1603517 T7860 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 0=20 [junit4] 2> 1603517 T7860 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1603517 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:42942/k_bt", [junit4] 2> =09 "node_name":"127.0.0.1:42942_k_bt", [junit4] 2> =09 "numShards":"2", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 1603519 T7831 oasc.Overseer$ClusterStateUpdater.updateStat= e Collection already exists with numShards=3D2 [junit4] 2> 1603519 T7831 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard2 [junit4] 2> 1603522 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1603522 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1603522 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1603523 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 1=20 [junit4] 2> 1604517 T7860 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1604517 T7860 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4] 2> 1604518 T7860 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1604518 T7860 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1604519 T7860 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1604519 T7860 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B= 1B942CA18058-001/tempDir-003/collection1/' [junit4] 2> 1604519 T7860 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 1604519 T7860 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 1604519 T7860 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 1604531 T7860 oasc.ZkController.watchZKConfDir watch zkdir= /configs/conf1 [junit4] 2> 1604532 T7860 oasc.Config. loaded config solrconfig.= xml with version 0=20 [junit4] 2> 1604539 T7860 oasc.SolrConfig. Using Lucene MatchVer= sion: 5.0.0 [junit4] 2> 1604547 T7860 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1604547 T7860 oass.IndexSchema.readSchema Reading Solr Sch= ema from /configs/conf1/schema.xml [junit4] 2> 1604551 T7860 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1604652 T7860 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1604653 T7860 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1604654 T7860 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1604656 T7860 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1604666 T7860 oasc.CoreContainer.create Creating SolrCore = 'collection1' using configuration from collection collection1 [junit4] 2> 1604667 T7860 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1604667 T7860 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/= solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA1805= 8-001/tempDir-003/collection1/, dataDir=3D/mnt/ssd/jenkins/workspace/Lucene= -Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySaf= eLeaderTest-2B1B1B942CA18058-001/tempDir-001/jetty1/ [junit4] 2> 1604667 T7860 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7ed262fd [junit4] 2> 1604667 T7860 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058= -001/tempDir-001/jetty1 [junit4] 2> 1604668 T7860 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-= Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTes= t-2B1B1B942CA18058-001/tempDir-001/jetty1/index/ [junit4] 2> 1604668 T7860 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/= build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942= CA18058-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 1604669 T7860 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058= -001/tempDir-001/jetty1/index [junit4] 2> 1604669 T7860 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergeP= olicy: maxMergeAtOnce=3D47, maxMergeAtOnceExplicit=3D43, maxMergedSegmentMB= =3D70.75, floorSegmentMB=3D2.1552734375, forceMergeDeletesPctAllowed=3D1.44= 69324746518974, segmentsPerTier=3D32.0, maxCFSSegmentSizeMB=3D8.79609302220= 7999E12, noCFSRatio=3D0.5884565688349047 [junit4] 2> 1604691 T7860 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-= 5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeade= rTest-2B1B1B942CA18058-001/tempDir-001/jetty1/index,segFN=3Dsegments_1,gene= ration=3D1} [junit4] 2> 1604691 T7860 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1604695 T7860 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1604695 T7860 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1604695 T7860 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1604696 T7860 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1604696 T7860 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1604696 T7860 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1604696 T7860 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1604697 T7860 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1604697 T7860 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1604698 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1604698 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1604698 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1604699 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1604699 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 1604699 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 1604701 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 1604702 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 1604702 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 1604703 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 1604703 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 1604704 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 1604704 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 1604704 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestH= andler [junit4] 2> 1604705 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 1604705 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 1604706 T7860 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4] 2> 1604706 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1604707 T7860 oasc.RequestHandlers.initHandlersFromConfig = created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1604709 T7860 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1604710 T7860 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1604711 T7860 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1604712 T7860 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1604713 T7860 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4] 2> 1604713 T7860 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4] 2> 1604715 T7860 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergeP= olicy: minMergeSize=3D1000, mergeFactor=3D44, maxMergeSize=3D92233720368547= 75807, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByDel= etes=3Dfalse, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930222= 07999E12, noCFSRatio=3D0.1755904984218118] [junit4] 2> 1604716 T7860 oasc.SolrDeletionPolicy.onInit SolrDeletionP= olicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-= 5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeade= rTest-2B1B1B942CA18058-001/tempDir-001/jetty1/index,segFN=3Dsegments_1,gene= ration=3D1} [junit4] 2> 1604716 T7860 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1604717 T7860 oass.SolrIndexSearcher. Opening Search= er@436ebd74[collection1] main [junit4] 2> 1604717 T7860 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1604718 T7860 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1604718 T7860 oasr.ManagedResourceStorage.newStorageIO Set= ting up ZooKeeper-based storage for the RestManager with znodeBase: /config= s/conf1 [junit4] 2> 1604718 T7860 oasr.ManagedResourceStorage$ZooKeeperStorage= IO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1604718 T7860 oasr.RestManager.init Initializing RestManag= er with initArgs: {} [junit4] 2> 1604719 T7860 oasr.ManagedResourceStorage.load Reading _re= st_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1604719 T7860 oasr.ManagedResourceStorage$ZooKeeperStorage= IO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.js= on [junit4] 2> 1604720 T7860 oasr.ManagedResourceStorage.load Loaded Link= edHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=3D/confi= gs/conf1 [junit4] 2> 1604720 T7860 oasr.ManagedResource.reloadFromStorage Loade= d initArgs {} for /rest/managed [junit4] 2> 1604720 T7860 oasr.RestManager.init Initializing 0 registe= red ManagedResources [junit4] 2> 1604720 T7860 oash.ReplicationHandler.inform Commits will = be reserved for 10000 [junit4] 2> 1604721 T7861 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@436ebd74[collection1] main{ExitableDirect= oryReader(UninvertingDirectoryReader())} [junit4] 2> 1604721 T7860 oasc.CoreContainer.registerCore registering = core: collection1 [junit4] 2> 1604722 T7864 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:42942/k_bt collection:collectio= n1 shard:shard2 [junit4] 2> 1604722 T7804 oass.SolrDispatchFilter.init user.dir=3D/mnt= /ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1604722 T7864 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leader_elect/shard2/election [junit4] 2> 1604722 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4] 2> 1604725 T7864 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process for shard shard2 [junit4] 2> 1604726 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1604726 T7864 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4] 2> 1604726 T7864 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1026 name=3Dcollection1 org.apache.solr.c= ore.SolrCore@164aa738 url=3Dhttp://127.0.0.1:42942/k_bt/collection1 node=3D= 127.0.0.1:42942_k_bt C1026_STATE=3Dcoll:collection1 core:collection1 props:= {core=3Dcollection1, base_url=3Dhttp://127.0.0.1:42942/k_bt, node_name=3D12= 7.0.0.1:42942_k_bt, state=3Ddown} [junit4] 2> 1604727 T7864 C1026 P42942 oasc.SyncStrategy.sync Sync rep= licas to http://127.0.0.1:42942/k_bt/collection1/ [junit4] 2> 1604727 T7864 C1026 P42942 oasc.SyncStrategy.syncReplicas = Sync Success - now sync replicas to me [junit4] 2> 1604727 T7864 C1026 P42942 oasc.SyncStrategy.syncToMe http= ://127.0.0.1:42942/k_bt/collection1/ has no replicas [junit4] 2> 1604727 T7864 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:42942/k_bt/collection1/ shard2 [junit4] 2> 1604727 T7864 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leaders/shard2 [junit4] 2> 1604728 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1"} [junit4] 2> 1604730 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1604730 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1604731 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 2=20 [junit4] 2> 1604733 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "base_url":"http://127.0.0.1:42942/k_bt", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "state":"active"} [junit4] 2> 1604734 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1604734 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1604735 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1604735 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 3=20 [junit4] 2> 1604782 T7864 oasc.ZkController.register We are http://127= .0.0.1:42942/k_bt/collection1/ and leader is http://127.0.0.1:42942/k_bt/co= llection1/ [junit4] 2> 1604782 T7864 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:42942/k_bt [junit4] 2> 1604782 T7864 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4] 2> 1604782 T7864 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive collection=3Dcollection1 [junit4] 2> 1604782 T7864 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1604783 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1604785 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:42942/k_bt", [junit4] 2> =09 "node_name":"127.0.0.1:42942_k_bt", [junit4] 2> =09 "numShards":"2", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard2", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 1604785 T7864 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 3=20 [junit4] 2> 1604788 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1604789 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1604789 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 4=20 [junit4] 2> 1605091 T7804 oasc.AbstractFullDistribZkTestBase.createJet= tys create jetty 2 [junit4] 2> 1605092 T7804 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1605093 T7804 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:56909 [junit4] 2> 1605095 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1605095 T7804 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1605095 T7804 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x= -Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTe= st-2B1B1B942CA18058-001/tempDir-004 [junit4] 2> 1605096 T7804 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B= 1B942CA18058-001/tempDir-004/' [junit4] 2> 1605110 T7804 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/bui= ld/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA1= 8058-001/tempDir-004/solr.xml [junit4] 2> 1605119 T7804 oasc.CoreContainer. New CoreContainer = 1673343389 [junit4] 2> 1605119 T7804 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linu= x/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B= 1B1B942CA18058-001/tempDir-004/] [junit4] 2> 1605120 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1605120 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to:=20 [junit4] 2> 1605120 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting connTimeout to: 15000 [junit4] 2> 1605120 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnectionsPerHost to: 20 [junit4] 2> 1605121 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxConnections to: 10000 [junit4] 2> 1605121 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting corePoolSize to: 0 [junit4] 2> 1605121 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maximumPoolSize to: 2147483647 [junit4] 2> 1605121 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting maxThreadIdleTime to: 5 [junit4] 2> 1605121 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting sizeOfQueue to: -1 [junit4] 2> 1605122 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting fairnessPolicy to: false [junit4] 2> 1605122 T7804 oasu.UpdateShardHandler. Creating Upda= teShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout= =3D45000&retry=3Dfalse [junit4] 2> 1605122 T7804 oasl.LogWatcher.createWatcher SLF4J impl is = org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1605122 T7804 oasl.LogWatcher.newRegisteredLogWatcher Regi= stering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1605123 T7804 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1605123 T7804 oasc.ZkContainer.initZooKeeper Zookeeper cli= ent=3D127.0.0.1:46715/solr [junit4] 2> 1605123 T7804 oasc.ZkController.checkChrootPath zkHost inc= ludes chroot [junit4] 2> 1605123 T7804 oascc.SolrZkClient.createZkCredentialsToAddA= utomatically Using default ZkCredentialsProvider [junit4] 2> 1605124 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1605125 T7876 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@ca7625d name:ZooKeeperConnection= Watcher:127.0.0.1:46715 got event WatchedEvent state:SyncConnected type:No= ne path:null path:null type:None [junit4] 2> 1605126 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1605127 T7804 oascc.SolrZkClient.createZkACLProvider Using= default ZkACLProvider [junit4] 2> 1605129 T7804 oascc.ConnectionManager.waitForConnected Wai= ting for client to connect to ZooKeeper [junit4] 2> 1605130 T7879 oascc.ConnectionManager.process Watcher org.= apache.solr.common.cloud.ConnectionManager@97ee2d3 name:ZooKeeperConnection= Watcher:127.0.0.1:46715/solr got event WatchedEvent state:SyncConnected ty= pe:None path:null path:null type:None [junit4] 2> 1605130 T7804 oascc.ConnectionManager.waitForConnected Cli= ent is connected to ZooKeeper [junit4] 2> 1605134 T7804 oascc.ZkStateReader.createClusterStateWatche= rsAndUpdate Updating cluster state from ZooKeeper...=20 [junit4] 2> 1606137 T7804 oasc.ZkController.createEphemeralLiveNode Re= gister node as live in ZooKeeper:/live_nodes/127.0.0.1:56909_k_bt [junit4] 2> 1606138 T7804 oascc.SolrZkClient.makePath makePath: /live_= nodes/127.0.0.1:56909_k_bt [junit4] 2> 1606142 T7804 oasc.Overseer.close Overseer (id=3Dnull) clo= sing [junit4] 2> 1606146 T7880 oasc.ZkController.publish publishing core=3D= collection1 state=3Ddown collection=3Dcollection1 [junit4] 2> 1606146 T7880 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1606147 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1606148 T7880 oasc.ZkController.preRegister Registering wa= tch for external collection collection1 [junit4] 2> 1606148 T7880 oascc.ZkStateReader.addZkWatch addZkWatch co= llection1 [junit4] 2> 1606149 T7880 oascc.ZkStateReader.addZkWatch Updating coll= ection state at /collections/collection1/state.json from ZooKeeper...=20 [junit4] 2> 1606150 T7880 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 4=20 [junit4] 2> 1606151 T7880 oasc.ZkController.waitForCoreNodeName look f= or our core node name [junit4] 2> 1606151 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:56909/k_bt", [junit4] 2> =09 "node_name":"127.0.0.1:56909_k_bt", [junit4] 2> =09 "numShards":"2", [junit4] 2> =09 "state":"down", [junit4] 2> =09 "shard":null, [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 1606153 T7831 oasc.Overseer$ClusterStateUpdater.updateStat= e Collection already exists with numShards=3D2 [junit4] 2> 1606154 T7831 oasc.Overseer$ClusterStateUpdater.updateStat= e Assigning new node to shard shard=3Dshard1 [junit4] 2> 1606155 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1606156 T7879 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1606156 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1606156 T7879 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 5=20 [junit4] 2> 1606156 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 5=20 [junit4] 2> 1607151 T7880 oasc.ZkController.waitForShardId waiting to = find shard id in clusterstate for collection1 [junit4] 2> 1607151 T7880 oasc.ZkController.createCollectionZkNode Che= ck for collection zkNode:collection1 [junit4] 2> 1607152 T7880 oasc.ZkController.createCollectionZkNode Col= lection zkNode exists [junit4] 2> 1607152 T7880 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1607153 T7880 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1607153 T7880 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/= solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B= 1B942CA18058-001/tempDir-004/collection1/' [junit4] 2> 1607153 T7880 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 1607154 T7880 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 1607154 T7880 oasc.SolrResourceLoader.replaceClassLoader A= dding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-0= 01/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 1607165 T7880 oasc.ZkController.watchZKConfDir watch zkdir= /configs/conf1 [junit4] 2> 1607167 T7880 oasc.Config. loaded config solrconfig.= xml with version 0=20 [junit4] 2> 1607174 T7880 oasc.SolrConfig. Using Lucene MatchVer= sion: 5.0.0 [junit4] 2> 1607181 T7880 oasc.SolrConfig. Loaded SolrConfig: so= lrconfig.xml [junit4] 2> 1607182 T7880 oass.IndexSchema.readSchema Reading Solr Sch= ema from /configs/conf1/schema.xml [junit4] 2> 1607186 T7880 oass.IndexSchema.readSchema [collection1] Sc= hema name=3Dtest [junit4] 2> 1607265 T7880 oass.IndexSchema.readSchema default search f= ield in schema is text [junit4] 2> 1607266 T7880 oass.IndexSchema.readSchema unique key field= : id [junit4] 2> 1607267 T7880 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1607269 T7880 oass.FileExchangeRateProvider.reload Reloadi= ng exchange rates from file currency.xml [junit4] 2> 1607278 T7880 oasc.CoreContainer.create Creating SolrCore = 'collection1' using configuration from collection collection1 [junit4] 2> 1607278 T7880 oasc.SolrCore.initDirectoryFactory solr.Stan= dardDirectoryFactory [junit4] 2> 1607279 T7880 oasc.SolrCore. [collection1] Opening n= ew SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/= solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA1805= 8-001/tempDir-004/collection1/, dataDir=3D/mnt/ssd/jenkins/workspace/Lucene= -Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySaf= eLeaderTest-2B1B1B942CA18058-001/tempDir-001/jetty2/ [junit4] 2> 1607279 T7880 oasc.JmxMonitoredMap. JMX monitoring i= s enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBe= anServer@7ed262fd [junit4] 2> 1607279 T7880 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058= -001/tempDir-001/jetty2 [junit4] 2> 1607280 T7880 oasc.SolrCore.getNewIndexDir New index direc= tory detected: old=3Dnull new=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-= Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTes= t-2B1B1B942CA18058-001/tempDir-001/jetty2/index/ [junit4] 2> 1607280 T7880 oasc.SolrCore.initIndex WARN [collection1] S= olr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/= build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942= CA18058-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 1607281 T7880 oasc.CachingDirectoryFactory.get return new = directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058= -001/tempDir-001/jetty2/index [junit4] 2> 1607282 T7880 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergeP= olicy: maxMergeAtOnce=3D47, maxMergeAtOnceExplicit=3D43, maxMergedSegmentMB= =3D70.75, floorSegmentMB=3D2.1552734375, forceMergeDeletesPctAllowed=3D1.44= 69324746518974, segmentsPerTier=3D32.0, maxCFSSegmentSizeMB=3D8.79609302220= 7999E12, noCFSRatio=3D0.5884565688349047 [junit4] 2> 1607287 T7880 oasc.SolrDeletionPolicy.onCommit SolrDeletio= nPolicy.onCommit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-= 5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeade= rTest-2B1B1B942CA18058-001/tempDir-001/jetty2/index,segFN=3Dsegments_1,gene= ration=3D1} [junit4] 2> 1607287 T7880 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1607289 T7880 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "nodistrib" [junit4] 2> 1607290 T7880 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "dedupe" [junit4] 2> 1607290 T7880 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "de= dupe" [junit4] 2> 1607290 T7880 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "stored_sig" [junit4] 2> 1607290 T7880 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "st= ored_sig" [junit4] 2> 1607290 T7880 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1607291 T7880 oasup.UpdateRequestProcessorChain.init creat= ing updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1607291 T7880 oasup.UpdateRequestProcessorChain.init inser= ting DistributedUpdateProcessorFactory into updateRequestProcessorChain "di= strib-dup-test-chain-implicit" [junit4] 2> 1607291 T7880 oasc.SolrCore.loadUpdateProcessorChains no u= pdateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1607291 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1607291 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1607292 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1607292 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1607292 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 1607293 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 1607294 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 1607294 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 1607295 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 1607295 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 1607295 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 1607296 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 1607296 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 1607296 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestH= andler [junit4] 2> 1607297 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 1607297 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 1607297 T7880 oasc.RequestHandlers.initHandlersFromConfig = created standard: solr.StandardRequestHandler [junit4] 2> 1607297 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1607298 T7880 oasc.RequestHandlers.initHandlersFromConfig = created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1607299 T7880 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1607300 T7880 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1607300 T7880 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1607301 T7880 oashl.XMLLoader.init xsltCacheLifetimeSecond= s=3D60 [junit4] 2> 1607302 T7880 oasu.CommitTracker. Hard AutoCommit: d= isabled [junit4] 2> 1607302 T7880 oasu.CommitTracker. Soft AutoCommit: d= isabled [junit4] 2> 1607304 T7880 oasu.RandomMergePolicy. RandomMergePol= icy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergeP= olicy: minMergeSize=3D1000, mergeFactor=3D44, maxMergeSize=3D92233720368547= 75807, maxMergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByDel= etes=3Dfalse, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.7960930222= 07999E12, noCFSRatio=3D0.1755904984218118] [junit4] 2> 1607304 T7880 oasc.SolrDeletionPolicy.onInit SolrDeletionP= olicy.onInit: commits: num=3D1 [junit4] 2> =09=09commit{dir=3D/mnt/ssd/jenkins/workspace/Lucene-Solr-= 5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeySafeLeade= rTest-2B1B1B942CA18058-001/tempDir-001/jetty2/index,segFN=3Dsegments_1,gene= ration=3D1} [junit4] 2> 1607304 T7880 oasc.SolrDeletionPolicy.updateCommits newest= commit generation =3D 1 [junit4] 2> 1607305 T7880 oass.SolrIndexSearcher. Opening Search= er@1c5b076b[collection1] main [junit4] 2> 1607305 T7880 oascc.ZkStateReader.readConfigName Load coll= ection config from:/collections/collection1 [junit4] 2> 1607306 T7880 oascc.ZkStateReader.readConfigName path=3D/c= ollections/collection1 configName=3Dconf1 specified config exists in ZooKee= per [junit4] 2> 1607306 T7880 oasr.ManagedResourceStorage.newStorageIO Set= ting up ZooKeeper-based storage for the RestManager with znodeBase: /config= s/conf1 [junit4] 2> 1607306 T7880 oasr.ManagedResourceStorage$ZooKeeperStorage= IO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1607307 T7880 oasr.RestManager.init Initializing RestManag= er with initArgs: {} [junit4] 2> 1607307 T7880 oasr.ManagedResourceStorage.load Reading _re= st_managed.json using ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1607307 T7880 oasr.ManagedResourceStorage$ZooKeeperStorage= IO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.js= on [junit4] 2> 1607307 T7880 oasr.ManagedResourceStorage.load Loaded Link= edHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=3D/confi= gs/conf1 [junit4] 2> 1607308 T7880 oasr.ManagedResource.reloadFromStorage Loade= d initArgs {} for /rest/managed [junit4] 2> 1607308 T7880 oasr.RestManager.init Initializing 0 registe= red ManagedResources [junit4] 2> 1607308 T7880 oash.ReplicationHandler.inform Commits will = be reserved for 10000 [junit4] 2> 1607308 T7881 oasc.SolrCore.registerSearcher [collection1]= Registered new searcher Searcher@1c5b076b[collection1] main{ExitableDirect= oryReader(UninvertingDirectoryReader())} [junit4] 2> 1607309 T7880 oasc.CoreContainer.registerCore registering = core: collection1 [junit4] 2> 1607309 T7884 oasc.ZkController.register Register replica = - core:collection1 address:http://127.0.0.1:56909/k_bt collection:collectio= n1 shard:shard1 [junit4] 2> 1607310 T7804 oass.SolrDispatchFilter.init user.dir=3D/mnt= /ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1 [junit4] 2> 1607310 T7884 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leader_elect/shard1/election [junit4] 2> 1607310 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() done [junit4] 2> 1607312 T7884 oasc.ShardLeaderElectionContext.runLeaderPro= cess Running the leader process for shard shard1 [junit4] 2> 1607313 T7884 oasc.ShardLeaderElectionContext.waitForRepli= casToComeUp Enough replicas found to continue. [junit4] 2> 1607313 T7884 oasc.ShardLeaderElectionContext.runLeaderPro= cess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1027 name=3Dcollection1 org.apache.solr.c= ore.SolrCore@4b524117 url=3Dhttp://127.0.0.1:56909/k_bt/collection1 node=3D= 127.0.0.1:56909_k_bt C1027_STATE=3Dcoll:collection1 core:collection1 props:= {core=3Dcollection1, base_url=3Dhttp://127.0.0.1:56909/k_bt, node_name=3D12= 7.0.0.1:56909_k_bt, state=3Ddown} [junit4] 2> 1607313 T7884 C1027 P56909 oasc.SyncStrategy.sync Sync rep= licas to http://127.0.0.1:56909/k_bt/collection1/ [junit4] 2> 1607313 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1607314 T7884 C1027 P56909 oasc.SyncStrategy.syncReplicas = Sync Success - now sync replicas to me [junit4] 2> 1607314 T7884 C1027 P56909 oasc.SyncStrategy.syncToMe http= ://127.0.0.1:56909/k_bt/collection1/ has no replicas [junit4] 2> 1607314 T7884 oasc.ShardLeaderElectionContext.runLeaderPro= cess I am the new leader: http://127.0.0.1:56909/k_bt/collection1/ shard1 [junit4] 2> 1607314 T7884 oascc.SolrZkClient.makePath makePath: /colle= ctions/collection1/leaders/shard1 [junit4] 2> 1607315 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"collection1"} [junit4] 2> 1607317 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1607317 T7879 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1607317 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1607318 T7879 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 6=20 [junit4] 2> 1607318 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 6=20 [junit4] 2> 1607320 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "operation":"leader", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "base_url":"http://127.0.0.1:56909/k_bt", [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "state":"active"} [junit4] 2> 1607321 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1607321 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1607322 T7879 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1607322 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1607322 T7879 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 7=20 [junit4] 2> 1607322 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 7=20 [junit4] 2> 1607367 T7884 oasc.ZkController.register We are http://127= .0.0.1:56909/k_bt/collection1/ and leader is http://127.0.0.1:56909/k_bt/co= llection1/ [junit4] 2> 1607367 T7884 oasc.ZkController.register No LogReplay need= ed for core=3Dcollection1 baseURL=3Dhttp://127.0.0.1:56909/k_bt [junit4] 2> 1607367 T7884 oasc.ZkController.checkRecovery I am the lea= der, no recovery necessary [junit4] 2> 1607367 T7884 oasc.ZkController.publish publishing core=3D= collection1 state=3Dactive collection=3Dcollection1 [junit4] 2> 1607368 T7884 oasc.ZkController.publish numShards not foun= d on descriptor - reading it from system property [junit4] 2> 1607369 T7830 oasc.DistributedQueue$LatchWatcher.process N= odeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1607370 T7884 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 7=20 [junit4] 2> 1607371 T7831 oasc.Overseer$ClusterStateUpdater.processMes= sage processMessage: queueSize: 1, message =3D { [junit4] 2> =09 "core":"collection1", [junit4] 2> =09 "core_node_name":"core_node2", [junit4] 2> =09 "roles":null, [junit4] 2> =09 "base_url":"http://127.0.0.1:56909/k_bt", [junit4] 2> =09 "node_name":"127.0.0.1:56909_k_bt", [junit4] 2> =09 "numShards":"2", [junit4] 2> =09 "state":"active", [junit4] 2> =09 "shard":"shard1", [junit4] 2> =09 "collection":"collection1", [junit4] 2> =09 "operation":"state"} [junit4] 2> 1607373 T7831 oasc.Overseer$ClusterStateUpdater.updateZkSt= ates going to update_collection /collections/collection1/state.json [junit4] 2> 1607373 T7859 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1607374 T7879 oascc.ZkStateReader$7.process A cluster stat= e change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/colle= ctions/collection1/state.json, has occurred - updating...=20 [junit4] 2> 1607374 T7859 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 8=20 [junit4] 2> 1607374 T7879 oascc.ZkStateReader.updateWatchedCollection = Updating data for collection1 to ver 8=20 [junit4] 2> 1607555 T7804 oasc.AbstractFullDistribZkTestBase.createJet= tys create jetty 3 [junit4] 2> 1607556 T7804 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1607557 T7804 oejs.AbstractConnector.doStart Started Selec= tChannelConnector@127.0.0.1:60466 [junit4] 2> 1607559 T7804 oass.SolrDispatchFilter.init SolrDispatchFil= ter.init() [junit4] 2> 1607559 T7804 oasc.SolrResourceLoader.locateSolrHome JNDI = not configured for solr (NoInitialContextEx) [junit4] 2> 1607559 T7804 oasc.SolrResourceLoader.locateSolrHome using= system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenk= ins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.= cloud.ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-001/tempDir-005 [junit4] 2> 1607560 T7804 oasc.SolrResourceLoader. new SolrResou= rceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/wor= kspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.C= haosMonkeySafeLeaderTest-2B1B1B942CA18058-001/tempDir-005/' [junit4] 2> 1607571 T7804 oasc.ConfigSolr.fromFile Loading container c= onfiguration from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/bui= ld/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspac= e/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosM= onkeySafeLeaderTest-2B1B1B942CA18058-001/tempDir-005/solr.xml [junit4] 2> 1607579 T7804 oasc.CoreContainer. New CoreContainer = 809394655 [junit4] 2> 1607579 T7804 oasc.CoreContainer.load Loading cores into C= oreContainer [instanceDir=3D../../../../../../../../../../mnt/ssd/jenkins/w= orkspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud= .ChaosMonkeySafeLeaderTest-2B1B1B942CA18058-001/tempDir-005/] [junit4] 2> 1607580 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting socketTimeout to: 90000 [junit4] 2> 1607580 T7804 oashc.HttpShardHandlerFactory.getParameter S= etting urlScheme to:=20 [junit4] 2> 1607580 T7804 oashc.HttpShardHandlerFactory.g [...truncated too long message...] 4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.runWorker(Thr= eadPoolExecutor.java:1127) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor$Work= er.run(ThreadPoolExecutor.java:617) [junit4] 2> =09 at java.lang.Thread.run(Thread.java:745) [junit4] 2> =09 29) Thread[id=3D7862, name=3DThread-4716, state=3DWAI= TING, group=3DTGRP-ChaosMonkeySafeLeaderTest] [junit4] 2> =09 at java.lang.Object.wait(Native Method) [junit4] 2> =09 at java.lang.Object.wait(Object.java:502) [junit4] 2> =09 at org.apache.solr.core.CloserThread.run(CoreCo= ntainer.java:924) [junit4] 2> =09 30) Thread[id=3D7909, name=3DcommitScheduler-2791-thr= ead-1, state=3DWAITING, group=3DTGRP-ChaosMonkeySafeLeaderTest] [junit4] 2> =09 at sun.misc.Unsafe.park(Native Method) [junit4] 2> =09 at java.util.concurrent.locks.LockSupport.park(= LockSupport.java:175) [junit4] 2> =09 at java.util.concurrent.locks.AbstractQueuedSyn= chronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] 2> =09 at java.util.concurrent.ScheduledThreadPoolExec= utor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081) [junit4] 2> =09 at java.util.concurrent.ScheduledThreadPoolExec= utor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.getT= ask(ThreadPoolExecutor.java:1067) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.runW= orker(ThreadPoolExecutor.java:1127) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor$Work= er.run(ThreadPoolExecutor.java:617) [junit4] 2> =09 at java.lang.Thread.run(Thread.java:745) [junit4] 2> =09 31) Thread[id=3D7881, name=3DsearcherExecutor-2797-th= read-1, state=3DWAITING, group=3DTGRP-ChaosMonkeySafeLeaderTest] [junit4] 2> =09 at sun.misc.Unsafe.park(Native Method) [junit4] 2> =09 at java.util.concurrent.locks.LockSupport.park(= LockSupport.java:175) [junit4] 2> =09 at java.util.concurrent.locks.AbstractQueuedSyn= chronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] 2> =09 at java.util.concurrent.LinkedBlockingQueue.tak= e(LinkedBlockingQueue.java:442) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.getT= ask(ThreadPoolExecutor.java:1067) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.runW= orker(ThreadPoolExecutor.java:1127) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor$Work= er.run(ThreadPoolExecutor.java:617) [junit4] 2> =09 at java.lang.Thread.run(Thread.java:745) [junit4] 2> =09 32) Thread[id=3D7884, name=3DcoreZkRegister-2792-thre= ad-1, state=3DWAITING, group=3DTGRP-ChaosMonkeySafeLeaderTest] [junit4] 2> =09 at sun.misc.Unsafe.park(Native Method) [junit4] 2> =09 at java.util.concurrent.locks.LockSupport.park(= LockSupport.java:175) [junit4] 2> =09 at java.util.concurrent.locks.AbstractQueuedSyn= chronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] 2> =09 at java.util.concurrent.LinkedBlockingQueue.tak= e(LinkedBlockingQueue.java:442) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.getT= ask(ThreadPoolExecutor.java:1067) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor.runW= orker(ThreadPoolExecutor.java:1127) [junit4] 2> =09 at java.util.concurrent.ThreadPoolExecutor$Work= er.run(ThreadPoolExecutor.java:617) [junit4] 2> =09 at java.lang.Thread.run(Thread.java:745) [junit4] 2> =09 33) Thread[id=3D7847, name=3Dqtp232667233-7847 Select= or1, state=3DRUNNABLE, group=3DTGRP-ChaosMonkeySafeLeaderTest] [junit4] 2> =09 at sun.nio.ch.IOUtil.drain(Native Method) [junit4] 2> =09 at sun.nio.ch.EPollSelectorImpl.doSelect(EPollS= electorImpl.java:90) [junit4] 2> =09 at sun.nio.ch.SelectorImpl.lockAndDoSelect(Sele= ctorImpl.java:86) [junit4] 2> =09 at sun.nio.ch.SelectorImpl.select(SelectorImpl.= java:97) [junit4] 2> =09 at org.eclipse.jetty.io.nio.SelectorManager$Sel= ectSet.doSelect(SelectorManager.java:569) [junit4] 2> =09 at org.eclipse.jetty.io.nio.SelectorManager$1.r= un(SelectorManager.java:290) [junit4] 2> =09 at org.eclipse.jetty.util.thread.QueuedThreadPo= ol.runJob(QueuedThreadPool.java:608) [junit4] 2> =09 at org.eclipse.jetty.util.thread.QueuedThreadPo= ol$3.run(QueuedThreadPool.java:543) [junit4] 2> =09 at java.lang.Thread.run(Thread.java:745) [junit4] 2> =09 34) Thread[id=3D7897, name=3DTEST-ChaosMonkeySafeLead= erTest.testDistribSearch-seed#[2B1B1B942CA18058]-SendThread(127.0.0.1:46715= ), state=3DTIMED_WAITING, group=3DTGRP-ChaosMonkeySafeLeaderTest] [junit4] 2> =09 at java.lang.Thread.sleep(Native Method) [junit4] 2> =09 at org.apache.zookeeper.client.StaticHostProvid= er.next(StaticHostProvider.java:101) [junit4] 2> =09 at org.apache.zookeeper.ClientCnxn$SendThread.s= tartConnect(ClientCnxn.java:940) [junit4] 2> =09 at org.apache.zookeeper.ClientCnxn$SendThread.r= un(ClientCnxn.java:1003) [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene50), sim=3D= DefaultSimilarity, locale=3Den, timezone=3DEurope/Rome [junit4] 2> NOTE: Linux 3.13.0-40-generic amd64/Oracle Corporation 1.8= .0_40-ea (64-bit)/cpus=3D8,threads=3D41,free=3D142170480,total=3D433061888 [junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryComponen= tOptimizationTest, TestChildDocTransformer, AnalyticsQueryTest, TestToleran= tSearch, TestStressLucene, TestJmxMonitoredMap, DirectUpdateHandlerOptimize= Test, TestCSVResponseWriter, TestBlendedInfixSuggestions, TestReplicaProper= ties, TestZkChroot, TestRecoveryHdfs, DirectSolrConnectionTest, SolrIndexCo= nfigTest, UpdateRequestProcessorFactoryTest, AtomicUpdatesTest, TestMergePo= licyConfig, TestSolrQueryParser, EnumFieldTest, TestJoin, TestSolrXml, Test= ReversedWildcardFilterFactory, LoggingHandlerTest, TestXIncludeConfig, SSLM= igrationTest, TestFieldCollectionResource, TestSchemaManager, CircularListT= est, TestShortCircuitedRequests, TestQuerySenderNoQuery, RAMDirectoryFactor= yTest, TestCloudManagedSchema, TestValueSourceCache, TestCoreContainer, Tes= tOmitPositions, TestManagedSchemaDynamicFieldResource, OpenCloseCoreStressT= est, TestIBSimilarityFactory, TestQueryUtils, SearchHandlerTest, SimpleMLTQ= ParserTest, LeaderFailoverAfterPartitionTest, DistributedExpandComponentTes= t, TestDistribDocBasedVersion, NumericFieldsTest, URLClassifyProcessorTest,= SynonymTokenizerTest, SharedFSAutoReplicaFailoverTest, TestPHPSerializedRe= sponseWriter, TestBulkSchemaAPI, FieldAnalysisRequestHandlerTest, MergeStra= tegyTest, RequestHandlersTest, TestPseudoReturnFields, TestBulkSchemaConcur= rent, TestLFUCache, SortByFunctionTest, QueryElevationComponentTest, TestMu= ltiCoreConfBootstrap, TestModifyConfFiles, TestPivotHelperCode, ShardSplitT= est, TestBM25SimilarityFactory, SpatialFilterTest, TestManagedSynonymFilter= Factory, TestOrdValues, FullSolrCloudDistribCmdsTest, DistribCursorPagingTe= st, BasicZkTest, DeleteInactiveReplicaTest, SOLR749Test, CoreContainerCoreI= nitFailuresTest, MultiThreadedOCPTest, TestConfigOverlay, TestWriterPerf, T= estFiltering, ClusterStateTest, TestSchemaResource, BJQParserTest, DebugCom= ponentTest, HdfsBasicDistributedZkTest, PreAnalyzedUpdateProcessorTest, Hig= hlighterMaxOffsetTest, HdfsUnloadDistributedZkTest, TestMaxScoreQueryParser= , TestQueryTypes, TestExpandComponent, PrimUtilsTest, TestDynamicFieldResou= rce, CoreAdminRequestStatusTest, LeaderElectionIntegrationTest, TestFieldRe= source, ResponseHeaderTest, DistribDocExpirationUpdateProcessorTest, AddSch= emaFieldsUpdateProcessorFactoryTest, PreAnalyzedFieldTest, CopyFieldTest, D= eleteShardTest, UUIDFieldTest, SimplePostToolTest, TermVectorComponentDistr= ibutedTest, AssignTest, HardAutoCommitTest, ParsingFieldUpdateProcessorsTes= t, TestCharFilters, TestSweetSpotSimilarityFactory, TestRecovery, SuggestCo= mponentTest, TermVectorComponentTest, ChaosMonkeySafeLeaderTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DChaosMonkeySa= feLeaderTest -Dtests.seed=3D2B1B1B942CA18058 -Dtests.multiplier=3D3 -Dtests= .slow=3Dtrue -Dtests.locale=3Den -Dtests.timezone=3DEurope/Rome -Dtests.ass= erts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 0.00s J1 | ChaosMonkeySafeLeaderTest (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded = (>=3D 7200000 msec). [junit4] > =09at __randomizedtesting.SeedInfo.seed([2B1B1B942CA18058]= :0) [junit4] Completed on J1 in 7223.97s, 1 test, 2 errors <<< FAILURES! [...truncated 11 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The followi= ng error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The followi= ng error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The followin= g error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The = following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The fo= llowing error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508:= The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:13= 63: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:97= 0: There were test failures: 450 suites, 1844 tests, 1 suite-level error, 1= error, 31 ignored (22 assumptions) Total time: 178 minutes 51 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0_40-ea-b09 -XX:+U= seCompressedOops -XX:+UseG1GC (asserts: true) Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_110_977699372.1417877954172 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_110_977699372.1417877954172--