Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/12624/ Java: 64bit/jdk1.8.0_60-ea-b12 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=5654, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5654, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47495: Could not find collection : awholynewstresscollection_collection0_0 at __randomizedtesting.SeedInfo.seed([39019D00E5FF66E7]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:884) Build Log: [...truncated 10830 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/init-core-data-001 [junit4] 2> 755998 T5258 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 755998 T5258 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 755999 T5258 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 2> 755999 T5259 oasc.ZkTestServer$2$1.setClientPort client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 755999 T5259 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 756099 T5258 oasc.ZkTestServer.run start zk server on port:60493 [junit4] 2> 756100 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 756100 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 756101 T5266 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72bb0b89 name:ZooKeeperConnection Watcher:127.0.0.1:60493 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 756101 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 756102 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 756102 T5258 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 756103 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 756103 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 756104 T5269 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4161f170 name:ZooKeeperConnection Watcher:127.0.0.1:60493/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 756104 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 756104 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 756104 T5258 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 756105 T5258 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 756105 T5258 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 756106 T5258 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 756106 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 756106 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 756107 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 756107 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 756108 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 756108 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 756109 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 756109 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 756109 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 756110 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 756110 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 756110 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 756111 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 756111 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 756111 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 756112 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 756112 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 756112 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 756113 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 756113 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 756114 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 756114 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 756114 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 756115 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 756115 T5272 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@314d0a65 name:ZooKeeperConnection Watcher:127.0.0.1:60493 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 756115 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 756115 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 756116 T5258 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 756116 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 756116 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 756117 T5275 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@840653d name:ZooKeeperConnection Watcher:127.0.0.1:60493/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 756117 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 756117 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 756117 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 756118 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml [junit4] 2> 756118 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 756119 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml [junit4] 2> 756119 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 756120 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 756120 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 756120 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 756121 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 756121 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt [junit4] 2> 756122 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 756122 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt [junit4] 2> 756122 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 756122 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml [junit4] 2> 756123 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 756123 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 756124 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 756124 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 756124 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 756124 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 756125 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 756125 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt [junit4] 2> 756126 T5258 oasc.AbstractZkTestCase.putConfig put /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 756126 T5258 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml [junit4] 2> 756169 T5258 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1 [junit4] 2> 756170 T5258 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 756171 T5258 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1fb6fa46{/,null,AVAILABLE} [junit4] 2> 756171 T5258 oejs.AbstractConnector.doStart Started ServerConnector@1c606208{HTTP/1.1}{127.0.0.1:33923} [junit4] 2> 756172 T5258 oejs.Server.doStart Started @757123ms [junit4] 2> 756172 T5258 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=33923, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores} [junit4] 2> 756172 T5258 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 756172 T5258 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/' [junit4] 2> 756194 T5258 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/solr.xml [junit4] 2> 756198 T5258 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores [junit4] 2> 756198 T5258 oasc.CoreContainer.<init> New CoreContainer 629602151 [junit4] 2> 756198 T5258 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/] [junit4] 2> 756198 T5258 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/lib [junit4] 2> 756198 T5258 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/lib). [junit4] 2> 756202 T5258 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 756203 T5258 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 756203 T5258 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 756203 T5258 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 756204 T5258 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 756204 T5258 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60493/solr [junit4] 2> 756204 T5258 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 756204 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 756205 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 756205 T5289 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33e30be1 name:ZooKeeperConnection Watcher:127.0.0.1:60493 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 756206 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 756206 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 756207 T5258 n:127.0.0.1:33923_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 756207 T5292 n:127.0.0.1:33923_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@79c90661 name:ZooKeeperConnection Watcher:127.0.0.1:60493/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 756207 T5258 n:127.0.0.1:33923_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 756208 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 756209 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 756209 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 756210 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 756211 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 756211 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 756212 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 756212 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 756212 T5258 n:127.0.0.1:33923_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33923_ [junit4] 2> 756213 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33923_ [junit4] 2> 756213 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 756214 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 756214 T5258 n:127.0.0.1:33923_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 756215 T5258 n:127.0.0.1:33923_ oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:33923_ [junit4] 2> 756215 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 756216 T5258 n:127.0.0.1:33923_ oasc.Overseer.start Overseer (id=93876587146051589-127.0.0.1:33923_-n_0000000000) starting [junit4] 2> 756216 T5258 n:127.0.0.1:33923_ oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 756218 T5258 n:127.0.0.1:33923_ oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 756218 T5294 n:127.0.0.1:33923_ oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 756218 T5258 n:127.0.0.1:33923_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 756218 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 756219 T5258 n:127.0.0.1:33923_ oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 756220 T5258 n:127.0.0.1:33923_ oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 756220 T5258 n:127.0.0.1:33923_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores [junit4] 2> 756221 T5258 n:127.0.0.1:33923_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 756221 T5258 n:127.0.0.1:33923_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/ [junit4] 2> 756221 T5258 n:127.0.0.1:33923_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 756222 T5296 n:127.0.0.1:33923_ c:control_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 756222 T5296 n:127.0.0.1:33923_ c:control_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 756222 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 756222 T5296 n:127.0.0.1:33923_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 756222 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33923";, [junit4] 2> "node_name":"127.0.0.1:33923_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 756223 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33923";, [junit4] 2> "node_name":"127.0.0.1:33923_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 756223 T5293 n:127.0.0.1:33923_ oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 756223 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 756223 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 757222 T5296 n:127.0.0.1:33923_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 757222 T5296 n:127.0.0.1:33923_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 757223 T5296 n:127.0.0.1:33923_ oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 757223 T5296 n:127.0.0.1:33923_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 757223 T5296 n:127.0.0.1:33923_ oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 757223 T5296 n:127.0.0.1:33923_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/' [junit4] 2> 757229 T5296 n:127.0.0.1:33923_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 757233 T5296 n:127.0.0.1:33923_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 757237 T5296 n:127.0.0.1:33923_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.3.0 [junit4] 2> 757243 T5296 n:127.0.0.1:33923_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 757243 T5296 n:127.0.0.1:33923_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 757246 T5296 n:127.0.0.1:33923_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 757310 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 757313 T5296 n:127.0.0.1:33923_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 757314 T5296 n:127.0.0.1:33923_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 757317 T5296 n:127.0.0.1:33923_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 757318 T5296 n:127.0.0.1:33923_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 757319 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 757319 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 757319 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 757319 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 757320 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 757320 T5296 n:127.0.0.1:33923_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 757320 T5296 n:127.0.0.1:33923_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 757320 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 757320 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 757320 T5296 n:127.0.0.1:33923_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@310040fe [junit4] 2> 757321 T5296 n:127.0.0.1:33923_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/data [junit4] 2> 757321 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/data/index/ [junit4] 2> 757321 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 757321 T5296 n:127.0.0.1:33923_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/control-001/cores/collection1/data/index [junit4] 2> 757322 T5296 n:127.0.0.1:33923_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1557480983, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 757322 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@37c4287d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a00ca7a),segFN=segments_1,generation=1} [junit4] 2> 757323 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 757325 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 757325 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 757325 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 757325 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 757325 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 757326 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 757326 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 757326 T5296 n:127.0.0.1:33923_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 757326 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 757327 T5296 n:127.0.0.1:33923_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 757328 T5296 n:127.0.0.1:33923_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 757337 T5296 n:127.0.0.1:33923_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 757338 T5296 n:127.0.0.1:33923_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 757339 T5296 n:127.0.0.1:33923_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 757340 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 757340 T5296 n:127.0.0.1:33923_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 757340 T5296 n:127.0.0.1:33923_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 757341 T5296 n:127.0.0.1:33923_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 757341 T5296 n:127.0.0.1:33923_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 757341 T5296 n:127.0.0.1:33923_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=4.1630859375, floorSegmentMB=0.5263671875, forceMergeDeletesPctAllowed=24.331235562528317, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@37c4287d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a00ca7a),segFN=segments_1,generation=1} [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@52afd860[collection1] main [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex WARN No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 757342 T5296 n:127.0.0.1:33923_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion WARN Could not find max version in index or recent updates, using new clock 1502025395744014336 [junit4] 2> 757344 T5296 n:127.0.0.1:33923_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 2 ms to seed version buckets with highest version 1502025395744014336 [junit4] 2> 757344 T5296 n:127.0.0.1:33923_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 757345 T5296 n:127.0.0.1:33923_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 757345 T5296 n:127.0.0.1:33923_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 757345 T5296 n:127.0.0.1:33923_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 757345 T5296 n:127.0.0.1:33923_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 757345 T5296 n:127.0.0.1:33923_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 757345 T5296 n:127.0.0.1:33923_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 757346 T5296 n:127.0.0.1:33923_ x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 757346 T5296 n:127.0.0.1:33923_ x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 757346 T5296 n:127.0.0.1:33923_ x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 757346 T5296 n:127.0.0.1:33923_ x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 757346 T5297 n:127.0.0.1:33923_ x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52afd860[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 757346 T5296 n:127.0.0.1:33923_ x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 757347 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:33923 collection:control_collection shard:shard1 [junit4] 2> 757347 T5258 n:127.0.0.1:33923_ oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2 [junit4] 2> 757347 T5258 n:127.0.0.1:33923_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 757347 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 757348 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 757348 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 757349 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 757349 T5303 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@748a9a00 name:ZooKeeperConnection Watcher:127.0.0.1:60493/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 757349 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 757349 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 757349 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 757350 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 757349 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> ASYNC NEW_CORE C1369 name=collection1 org.apache.solr.core.SolrCore@74a2eb6a url=http://127.0.0.1:33923/collection1 node=127.0.0.1:33923_ C1369_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:33923, node_name=127.0.0.1:33923_, state=down} [junit4] 2> 757350 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 C1369 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33923/collection1/ [junit4] 2> 757349 T5258 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 757350 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 757350 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 C1369 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 757350 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 C1369 oasc.SyncStrategy.syncToMe http://127.0.0.1:33923/collection1/ has no replicas [junit4] 2> 757350 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33923/collection1/ shard1 [junit4] 2> 757350 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 757351 T5258 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 757351 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 757352 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:33923";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 757395 T5258 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1 [junit4] 2> 757396 T5258 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001 [junit4] 2> 757396 T5258 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 757397 T5258 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6d053aa4{/,null,AVAILABLE} [junit4] 2> 757397 T5258 oejs.AbstractConnector.doStart Started ServerConnector@467a2dd7{HTTP/1.1}{127.0.0.1:43320} [junit4] 2> 757398 T5258 oejs.Server.doStart Started @758349ms [junit4] 2> 757398 T5258 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solrconfig=solrconfig.xml, hostContext=/, hostPort=43320, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores} [junit4] 2> 757398 T5258 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 757398 T5258 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/' [junit4] 2> 757406 T5258 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/solr.xml [junit4] 2> 757408 T5258 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores [junit4] 2> 757409 T5258 oasc.CoreContainer.<init> New CoreContainer 1418462842 [junit4] 2> 757409 T5258 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/] [junit4] 2> 757409 T5258 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/lib [junit4] 2> 757409 T5258 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/lib). [junit4] 2> 757413 T5258 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 757414 T5258 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 757414 T5258 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 757414 T5258 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 757415 T5258 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 757415 T5258 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60493/solr [junit4] 2> 757415 T5258 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 757415 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 757415 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 757416 T5317 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7f441ee0 name:ZooKeeperConnection Watcher:127.0.0.1:60493 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 757417 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 757417 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 757418 T5258 n:127.0.0.1:43320_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 757418 T5320 n:127.0.0.1:43320_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@358a9d21 name:ZooKeeperConnection Watcher:127.0.0.1:60493/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 757419 T5258 n:127.0.0.1:43320_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 757420 T5258 n:127.0.0.1:43320_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 757453 T5303 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 757453 T5320 n:127.0.0.1:43320_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 757453 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 757502 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ZkController.register We are http://127.0.0.1:33923/collection1/ and leader is http://127.0.0.1:33923/collection1/ [junit4] 2> 757502 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33923 [junit4] 2> 757502 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 757502 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 757502 T5300 n:127.0.0.1:33923_ c:control_collection s:shard1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 757503 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 757503 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33923";, [junit4] 2> "node_name":"127.0.0.1:33923_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 757503 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33923";, [junit4] 2> "node_name":"127.0.0.1:33923_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 757604 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 757604 T5303 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 757604 T5320 n:127.0.0.1:43320_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 758421 T5258 n:127.0.0.1:43320_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43320_ [junit4] 2> 758422 T5258 n:127.0.0.1:43320_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43320_ [junit4] 2> 758423 T5258 n:127.0.0.1:43320_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 758424 T5258 n:127.0.0.1:43320_ oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 758424 T5258 n:127.0.0.1:43320_ oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 758424 T5258 n:127.0.0.1:43320_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores [junit4] 2> 758425 T5258 n:127.0.0.1:43320_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 758425 T5258 n:127.0.0.1:43320_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/ [junit4] 2> 758425 T5258 n:127.0.0.1:43320_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 758426 T5321 n:127.0.0.1:43320_ c:collection1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 758426 T5321 n:127.0.0.1:43320_ c:collection1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 758426 T5321 n:127.0.0.1:43320_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 758426 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 758427 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43320";, [junit4] 2> "node_name":"127.0.0.1:43320_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 758427 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43320";, [junit4] 2> "node_name":"127.0.0.1:43320_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 758427 T5293 n:127.0.0.1:33923_ oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 758427 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 758528 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 758528 T5303 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 758528 T5320 n:127.0.0.1:43320_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759426 T5321 n:127.0.0.1:43320_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 759427 T5321 n:127.0.0.1:43320_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 759427 T5321 n:127.0.0.1:43320_ oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 759427 T5321 n:127.0.0.1:43320_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 759427 T5321 n:127.0.0.1:43320_ oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 759427 T5321 n:127.0.0.1:43320_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/' [junit4] 2> 759434 T5321 n:127.0.0.1:43320_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 759436 T5321 n:127.0.0.1:43320_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 759439 T5321 n:127.0.0.1:43320_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.3.0 [junit4] 2> 759445 T5321 n:127.0.0.1:43320_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 759445 T5321 n:127.0.0.1:43320_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 759448 T5321 n:127.0.0.1:43320_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 759529 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 759534 T5321 n:127.0.0.1:43320_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 759535 T5321 n:127.0.0.1:43320_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 759539 T5321 n:127.0.0.1:43320_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 759541 T5321 n:127.0.0.1:43320_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 759542 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 759542 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 759543 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 759543 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 759543 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 759543 T5321 n:127.0.0.1:43320_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 759544 T5321 n:127.0.0.1:43320_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 759544 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 759544 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/], dataDir=[null] [junit4] 2> 759544 T5321 n:127.0.0.1:43320_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@310040fe [junit4] 2> 759545 T5321 n:127.0.0.1:43320_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/data [junit4] 2> 759545 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 759545 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 759545 T5321 n:127.0.0.1:43320_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-1-001/cores/collection1/data/index [junit4] 2> 759546 T5321 n:127.0.0.1:43320_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1557480983, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 759547 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@5fdfdeab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@500206b5),segFN=segments_1,generation=1} [junit4] 2> 759547 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 759550 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 759550 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 759551 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 759551 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 759551 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 759551 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 759551 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 759551 T5321 n:127.0.0.1:43320_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 759552 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 759553 T5321 n:127.0.0.1:43320_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 759554 T5321 n:127.0.0.1:43320_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 759555 T5321 n:127.0.0.1:43320_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 759556 T5321 n:127.0.0.1:43320_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 759559 T5321 n:127.0.0.1:43320_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 759560 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 759560 T5321 n:127.0.0.1:43320_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 759561 T5321 n:127.0.0.1:43320_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 759562 T5321 n:127.0.0.1:43320_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 759562 T5321 n:127.0.0.1:43320_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 759563 T5321 n:127.0.0.1:43320_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=4.1630859375, floorSegmentMB=0.5263671875, forceMergeDeletesPctAllowed=24.331235562528317, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 759563 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@5fdfdeab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@500206b5),segFN=segments_1,generation=1} [junit4] 2> 759563 T5321 n:127.0.0.1:43320_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 759563 T5321 n:127.0.0.1:43320_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@1122b6b6[collection1] main [junit4] 2> 759563 T5321 n:127.0.0.1:43320_ x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 759564 T5321 n:127.0.0.1:43320_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 759564 T5321 n:127.0.0.1:43320_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex WARN No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 759564 T5321 n:127.0.0.1:43320_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion WARN Could not find max version in index or recent updates, using new clock 1502025398073950208 [junit4] 2> 759566 T5321 n:127.0.0.1:43320_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 2 ms to seed version buckets with highest version 1502025398073950208 [junit4] 2> 759566 T5321 n:127.0.0.1:43320_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 759566 T5321 n:127.0.0.1:43320_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 759567 T5321 n:127.0.0.1:43320_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 759567 T5321 n:127.0.0.1:43320_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 759567 T5321 n:127.0.0.1:43320_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 759567 T5321 n:127.0.0.1:43320_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 759568 T5321 n:127.0.0.1:43320_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 759568 T5321 n:127.0.0.1:43320_ x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 759568 T5321 n:127.0.0.1:43320_ x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 759568 T5321 n:127.0.0.1:43320_ x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 759569 T5322 n:127.0.0.1:43320_ x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1122b6b6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 759569 T5321 n:127.0.0.1:43320_ x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 759569 T5321 n:127.0.0.1:43320_ x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 759570 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:43320 collection:collection1 shard:shard2 [junit4] 2> 759570 T5258 n:127.0.0.1:43320_ oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2 [junit4] 2> 759570 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 759570 T5258 n:127.0.0.1:43320_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 759571 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 759572 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 759572 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 759572 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 759572 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> ASYNC NEW_CORE C1370 name=collection1 org.apache.solr.core.SolrCore@198ae82c url=http://127.0.0.1:43320/collection1 node=127.0.0.1:43320_ C1370_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:43320, node_name=127.0.0.1:43320_, state=down} [junit4] 2> 759572 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 C1370 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:43320/collection1/ [junit4] 2> 759572 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 C1370 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 759572 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 C1370 oasc.SyncStrategy.syncToMe http://127.0.0.1:43320/collection1/ has no replicas [junit4] 2> 759572 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43320/collection1/ shard2 [junit4] 2> 759573 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 759574 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 759574 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:43320";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 759624 T5258 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores/collection1 [junit4] 2> 759625 T5258 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001 [junit4] 2> 759625 T5258 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 759626 T5258 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@519a3abc{/,null,AVAILABLE} [junit4] 2> 759626 T5258 oejs.AbstractConnector.doStart Started ServerConnector@760f5c42{HTTP/1.1}{127.0.0.1:49151} [junit4] 2> 759627 T5258 oejs.Server.doStart Started @760578ms [junit4] 2> 759627 T5258 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solrconfig=solrconfig.xml, hostContext=/, hostPort=49151, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores} [junit4] 2> 759627 T5258 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 759627 T5258 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/' [junit4] 2> 759635 T5258 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/solr.xml [junit4] 2> 759638 T5258 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores [junit4] 2> 759638 T5258 oasc.CoreContainer.<init> New CoreContainer 2045219737 [junit4] 2> 759638 T5258 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/] [junit4] 2> 759639 T5258 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/lib [junit4] 2> 759639 T5258 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/lib). [junit4] 2> 759643 T5258 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 759644 T5258 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 759645 T5258 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 759645 T5258 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 759645 T5258 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 759645 T5258 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60493/solr [junit4] 2> 759645 T5258 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 759645 T5258 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 759646 T5258 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 759647 T5339 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4cd0fc7a name:ZooKeeperConnection Watcher:127.0.0.1:60493 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 759647 T5258 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 759648 T5258 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 759648 T5258 n:127.0.0.1:49151_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 759649 T5342 n:127.0.0.1:49151_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@60718bd3 name:ZooKeeperConnection Watcher:127.0.0.1:60493/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 759649 T5258 n:127.0.0.1:49151_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 759651 T5258 n:127.0.0.1:49151_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 759675 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759675 T5320 n:127.0.0.1:43320_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759675 T5303 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759675 T5342 n:127.0.0.1:49151_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759724 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ZkController.register We are http://127.0.0.1:43320/collection1/ and leader is http://127.0.0.1:43320/collection1/ [junit4] 2> 759725 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43320 [junit4] 2> 759725 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 759725 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 759725 T5325 n:127.0.0.1:43320_ c:collection1 s:shard2 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 759726 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 759726 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43320";, [junit4] 2> "node_name":"127.0.0.1:43320_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 759726 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43320";, [junit4] 2> "node_name":"127.0.0.1:43320_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 759827 T5303 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759828 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759827 T5342 n:127.0.0.1:49151_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 759828 T5320 n:127.0.0.1:43320_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 760652 T5258 n:127.0.0.1:49151_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49151_ [junit4] 2> 760653 T5258 n:127.0.0.1:49151_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49151_ [junit4] 2> 760654 T5258 n:127.0.0.1:49151_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 760654 T5258 n:127.0.0.1:49151_ oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used. [junit4] 2> 760655 T5258 n:127.0.0.1:49151_ oasc.CoreContainer.intializeAuthorizationPlugin Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 760655 T5258 n:127.0.0.1:49151_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores [junit4] 2> 760656 T5258 n:127.0.0.1:49151_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores/collection1, collection=collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 760656 T5258 n:127.0.0.1:49151_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest 39019D00E5FF66E7-001/shard-2-001/cores/collection1/ [junit4] 2> 760656 T5258 n:127.0.0.1:49151_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 760656 T5343 n:127.0.0.1:49151_ c:collection1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 760657 T5343 n:127.0.0.1:49151_ c:collection1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 760657 T5292 n:127.0.0.1:33923_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 760657 T5343 n:127.0.0.1:49151_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 760657 T5293 n:127.0.0.1:33923_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49151";, [junit4] 2> "node_name":"127.0.0.1:49151_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 760658 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49151";, [junit4] 2> "node_name":"127.0.0.1:49151_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 760658 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 760658 T5293 n:127.0.0.1:33923_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 760759 T5303 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 760759 T5292 n:127.0.0.1:33923_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 760759 T5342 n:127.0.0.1:49151_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 760759 T5320 n:127.0.0.1:43320_ oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 761657 T5343 n:127.0.0.1:49151_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 761658 T5343 n:127.0.0.1:49151_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> [...truncated too long message...] o 127.0.0.1:60493 60493 [junit4] 2> 972999 T5529 c:collection1 s:shard1 x:collection1 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf2 [junit4] 2> 2 /solr/collections/acollectionafterbaddelete/state.json [junit4] 2> 2 /solr/collections/halfdeletedcollection/state.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection1_0/leader_elect/shard6/election/93876587146051602-core_node36-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection1_0/leader_elect/shard1/election/93876587146051589-core_node5-n_0000000000 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard2/election/93876587146051596-core_node35-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard4/election/93876587146051596-core_node18-n_0000000001 [junit4] 2> 2 /solr/collections/halfdeletedcollection2/leader_elect/shard2/election/93876587146051598-core_node1-n_0000000000 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard1/election/93876587146051598-core_node1-n_0000000000 [junit4] 2> 2 /solr/collections/addReplicaColl/leader_elect/shard1/election/93876587146051596-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard6/election/93876587146051592-core_node10-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard5/election/93876587146051602-core_node34-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 5 /solr/live_nodes [junit4] 2> 2 /solr/overseer/queue [junit4] 2> 2 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=test -Dtests.seed=39019D00E5FF66E7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_AR -Dtests.timezone=Africa/Sao_Tome -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 217s J2 | CollectionsAPIDistributedZkTest.test <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5654, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47495: Could not find collection : awholynewstresscollection_collection0_0 [junit4] > at __randomizedtesting.SeedInfo.seed([39019D00E5FF66E7]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:884)Throwable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5655, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47495: Could not find collection : awholynewstresscollection_collection1_0 [junit4] > at __randomizedtesting.SeedInfo.seed([39019D00E5FF66E7]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:884) [junit4] 2> 973010 T5258 c:collection1 s:shard1 x:collection1 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> 217235 T5257 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=Lucene50(blocksize=128), range_facet_l_dv=Lucene50(blocksize=128), _version_=Lucene50(blocksize=128), multiDefault=FST50, a_t=FST50, intDefault=Lucene50(blocksize=128), a_i1=FST50, id=Lucene50(blocksize=128), range_facet_i_dv=FST50, text=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=FST50, timestamp=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=es_AR, timezone=Africa/Sao_Tome [junit4] 2> NOTE: Linux 3.13.0-53-generic amd64/Oracle Corporation 1.8.0_60-ea (64-bit)/cpus=12,threads=1,free=255577072,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIAsyncDistributedZkTest, TestCloudManagedSchema, TestLuceneMatchVersion, SOLR749Test, TestQueryTypes, TestDefaultSimilarityFactory, TestCSVResponseWriter, AnalyticsQueryTest, HdfsDirectoryTest, DirectUpdateHandlerOptimizeTest, DistribCursorPagingTest, TestFastOutputStream, PluginInfoTest, QueryResultKeyTest, TestConfigOverlay, RequestLoggingTest, TestAnalyzeInfixSuggestions, TestManagedStopFilterFactory, SortByFunctionTest, HdfsLockFactoryTest, TestSolrConfigHandlerConcurrent, HighlighterMaxOffsetTest, TestHashQParserPlugin, TestSort, CloudMLTQParserTest, SampleTest, NumericFieldsTest, TestAddFieldRealTimeGet, TestStressRecovery, TestSolrJ, TestBulkSchemaAPI, TestAuthenticationFramework, TestSystemIdResolver, XsltUpdateRequestHandlerTest, TestBlobHandler, TestZkChroot, TestReload, TermVectorComponentDistributedTest, SSLMigrationTest, TestManagedSchemaDynamicFieldResource, TestRecoveryHdfs, CloudExitableDirectoryReaderTest, TestSolrQueryParser, TestBinaryResponseWriter, TestBulkSchemaConcurrent, PolyFieldTest, HardAutoCommitTest, HdfsCollectionsAPIDistributedZkTest, SuggestComponentTest, AutoCommitTest, LoggingHandlerTest, DocValuesTest, SearchHandlerTest, DirectUpdateHandlerTest, SpellCheckCollatorTest, TestChildDocTransformer, BadIndexSchemaTest, SimpleFacetsTest, AliasIntegrationTest, DistributedFacetPivotSmallAdvancedTest, HdfsSyncSliceTest, PrimUtilsTest, SuggesterTest, MoreLikeThisHandlerTest, TestBM25SimilarityFactory, StatsComponentTest, DeleteReplicaTest, TestUtils, TestCollationFieldDocValues, TestPseudoReturnFields, TestStressReorder, OverseerTest, TestUniqueKeyFieldResource, TestGroupingSearch, TestCollationField, OverseerRolesTest, MigrateRouteKeyTest, OverseerCollectionProcessorTest, BasicDistributedZkTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest] [junit4] Completed [372/496] on J2 in 218.00s, 1 test, 1 error <<< FAILURES! [...truncated 402 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:536: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:484: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:229: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:511: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1433: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:991: There were test failures: 496 suites, 1972 tests, 1 error, 57 ignored (25 assumptions) Total time: 42 minutes 53 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
