Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/620/
1 tests failed. REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDistribSearch Error Message: Captured an uncaught exception in thread: Thread[id=6732, name=Thread-5010, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=6732, name=Thread-5010, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:58232/collection1 at __randomizedtesting.SeedInfo.seed([46BCC646B336D449]:0) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:380) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:58232/collection1 at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:378) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:152) at java.net.SocketInputStream.read(SocketInputStream.java:122) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448) ... 4 more Build Log: [...truncated 12458 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/init-core-data-001 [junit4] 2> 1420316 T6324 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 1420321 T6324 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 1420322 T6324 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1420323 T6325 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 1420423 T6324 oasc.ZkTestServer.run start zk server on port:58226 [junit4] 2> 1420424 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1420425 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1420429 T6331 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f4a47d3 name:ZooKeeperConnection Watcher:127.0.0.1:58226 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1420429 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1420430 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1420430 T6324 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 1420433 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1420434 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1420435 T6333 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f9f8e61 name:ZooKeeperConnection Watcher:127.0.0.1:58226/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1420435 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1420435 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1420436 T6324 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 1420438 T6324 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 1420439 T6324 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 1420440 T6324 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 1420442 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1420443 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1420445 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1420446 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 1420548 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1420549 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1420551 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1420551 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 1420553 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1420553 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 1420555 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1420556 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 1420557 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1420558 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1420560 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1420560 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1420562 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1420563 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1420564 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1420565 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1420567 T6324 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1420567 T6324 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 1421884 T6324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1421887 T6324 oejs.AbstractConnector.doStart Started [email protected]:58232 [junit4] 2> 1421887 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1421887 T6324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1421888 T6324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002 [junit4] 2> 1421888 T6324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/' [junit4] 2> 1421917 T6324 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/solr.xml [junit4] 2> 1421971 T6324 oasc.CoreContainer.<init> New CoreContainer 990554596 [junit4] 2> 1421971 T6324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/] [junit4] 2> 1421972 T6324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1421972 T6324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1421973 T6324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1421973 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1421973 T6324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1421973 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1421974 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1421974 T6324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1421974 T6324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1421974 T6324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1421975 T6324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1421975 T6324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1421975 T6324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1421975 T6324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58226/solr [junit4] 2> 1421976 T6324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1421976 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1421977 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1421978 T6344 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3917d604 name:ZooKeeperConnection Watcher:127.0.0.1:58226 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1421979 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1421980 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1421982 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1421983 T6346 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b76a676 name:ZooKeeperConnection Watcher:127.0.0.1:58226/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1421983 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1421984 T6324 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 1421986 T6324 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 1421988 T6324 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 1421990 T6324 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 1421992 T6324 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 1421993 T6324 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 1421994 T6324 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58232_ [junit4] 2> 1421995 T6324 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58232_ [junit4] 2> 1421997 T6324 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 1421998 T6324 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 1421999 T6324 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1422000 T6324 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:58232_ [junit4] 2> 1422001 T6324 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 1422002 T6324 oasc.Overseer.start Overseer (id=92412204217466883-127.0.0.1:58232_-n_0000000000) starting [junit4] 2> 1422004 T6324 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 1422009 T6324 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1422009 T6348 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 1422010 T6324 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 1422011 T6324 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 1422012 T6324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1422014 T6347 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 1422019 T6350 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 1422020 T6350 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1422020 T6350 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1422021 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1422022 T6347 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:58232";, [junit4] 2> "operation":"state", [junit4] 2> "numShards":"1", [junit4] 2> "node_name":"127.0.0.1:58232_"} [junit4] 2> 1422022 T6347 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1] [junit4] 2> 1422022 T6347 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1422024 T6351 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> 1423021 T6350 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1423022 T6350 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 1423022 T6350 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1423023 T6350 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1423023 T6350 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1423023 T6350 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/collection1/' [junit4] 2> 1423026 T6350 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 1423026 T6350 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 1423027 T6350 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 1423091 T6350 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 1423139 T6350 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1423240 T6350 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 1423251 T6350 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1423601 T6350 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1423604 T6350 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1423606 T6350 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1423610 T6350 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1423629 T6350 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1423629 T6350 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1423629 T6350 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-002/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/control/data/ [junit4] 2> 1423630 T6350 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1582b0 [junit4] 2> 1423639 T6350 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/control/data [junit4] 2> 1423650 T6350 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/control/data/index/ [junit4] 2> 1423651 T6350 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 1423655 T6350 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/control/data/index [junit4] 2> 1423656 T6350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1423659 T6350 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(SimpleFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3fc8cf02)),segFN=segments_1,generation=1} [junit4] 2> 1423660 T6350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1423666 T6350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1423666 T6350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1423667 T6350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1423667 T6350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1423667 T6350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1423668 T6350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1423668 T6350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1423668 T6350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1423669 T6350 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1423669 T6350 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1423670 T6350 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1423670 T6350 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1423671 T6350 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1423671 T6350 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1423672 T6350 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 1423672 T6350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1423672 T6350 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 1423673 T6350 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1423673 T6350 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 1423673 T6350 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1423674 T6350 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1423674 T6350 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1423694 T6350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1423697 T6350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1423699 T6350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1423701 T6350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1423703 T6350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1423709 T6350 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1423709 T6350 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1423711 T6350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@3dfaf494 [junit4] 2> 1423713 T6350 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(SimpleFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3fc8cf02)),segFN=segments_1,generation=1} [junit4] 2> 1423713 T6350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1423714 T6350 oass.SolrIndexSearcher.<init> Opening Searcher@1f9f6404[collection1] main [junit4] 2> 1423714 T6350 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1423715 T6350 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1423715 T6350 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1423716 T6350 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1423716 T6350 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1423716 T6350 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1423717 T6350 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1423717 T6350 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1423718 T6350 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 1423719 T6350 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 1423720 T6350 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1423720 T6350 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1423723 T6352 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f9f6404[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 1423728 T6350 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1423729 T6355 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58232 collection:control_collection shard:shard1 [junit4] 2> 1423729 T6324 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1423729 T6355 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 1423729 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1423731 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1423731 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1423733 T6357 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17edf767 name:ZooKeeperConnection Watcher:127.0.0.1:58226/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1423733 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1423733 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1423734 T6355 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 1423734 T6324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1423736 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1423736 T6355 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1423736 T6355 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> 1423736 T6324 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> ASYNC NEW_CORE C630 name=collection1 org.apache.solr.core.SolrCore@6eb5acd url=http://127.0.0.1:58232/collection1 node=127.0.0.1:58232_ C630_STATE=coll:control_collection core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:58232, node_name=127.0.0.1:58232_} [junit4] 2> 1423736 T6355 C630 P58232 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58232/collection1/ [junit4] 2> 1423737 T6355 C630 P58232 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1423737 T6355 C630 P58232 oasc.SyncStrategy.syncToMe http://127.0.0.1:58232/collection1/ has no replicas [junit4] 2> 1423738 T6355 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58232/collection1/ shard1 [junit4] 2> 1423738 T6355 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1423739 T6351 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> 1423739 T6358 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> 1423742 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1423846 T6351 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> 1423846 T6358 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> 1423895 T6355 oasc.ZkController.register We are http://127.0.0.1:58232/collection1/ and leader is http://127.0.0.1:58232/collection1/ [junit4] 2> 1423896 T6355 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58232 [junit4] 2> 1423896 T6355 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1423896 T6355 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 1423896 T6355 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1423898 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1423899 T6347 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:58232";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"3", [junit4] 2> "node_name":"127.0.0.1:58232_"} [junit4] 2> 1424002 T6351 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> 1424002 T6358 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> 1424954 T6324 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 1424955 T6324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1424957 T6324 oejs.AbstractConnector.doStart Started [email protected]:58236 [junit4] 2> 1424958 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1424958 T6324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1424958 T6324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003 [junit4] 2> 1424958 T6324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/' [junit4] 2> 1424987 T6324 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/solr.xml [junit4] 2> 1425040 T6324 oasc.CoreContainer.<init> New CoreContainer 570938925 [junit4] 2> 1425040 T6324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/] [junit4] 2> 1425041 T6324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1425041 T6324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1425042 T6324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1425042 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1425042 T6324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1425042 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1425043 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1425043 T6324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1425043 T6324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1425043 T6324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1425044 T6324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1425044 T6324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1425044 T6324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1425044 T6324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58226/solr [junit4] 2> 1425045 T6324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1425045 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1425046 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1425047 T6369 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@307e35cf name:ZooKeeperConnection Watcher:127.0.0.1:58226 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1425048 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1425048 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1425050 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1425051 T6371 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4f4cc27d name:ZooKeeperConnection Watcher:127.0.0.1:58226/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1425051 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1425055 T6324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1426057 T6324 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58236_ [junit4] 2> 1426058 T6324 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58236_ [junit4] 2> 1426061 T6324 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1426077 T6373 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1426077 T6373 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1426078 T6373 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1426078 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1426080 T6347 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:58236";, [junit4] 2> "operation":"state", [junit4] 2> "numShards":"3", [junit4] 2> "node_name":"127.0.0.1:58236_"} [junit4] 2> 1426080 T6347 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3] [junit4] 2> 1426080 T6347 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3 [junit4] 2> 1426082 T6351 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> 1426082 T6372 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> 1426082 T6358 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> 1427078 T6373 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1427079 T6373 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1427079 T6373 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1427080 T6373 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1427080 T6373 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1427080 T6373 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/collection1/' [junit4] 2> 1427082 T6373 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 1427083 T6373 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 1427083 T6373 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 1427147 T6373 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 1427194 T6373 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1427295 T6373 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 1427305 T6373 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1427651 T6373 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1427654 T6373 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1427656 T6373 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1427661 T6373 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1427679 T6373 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1427679 T6373 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1427679 T6373 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-003/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty1/ [junit4] 2> 1427680 T6373 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1582b0 [junit4] 2> 1427682 T6373 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty1 [junit4] 2> 1427683 T6373 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty1/index/ [junit4] 2> 1427683 T6373 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 1427685 T6373 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty1/index [junit4] 2> 1427686 T6373 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1427689 T6373 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(SimpleFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/index-SimpleFSDirectory-002 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8a05bbf)),segFN=segments_1,generation=1} [junit4] 2> 1427690 T6373 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1427697 T6373 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1427697 T6373 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1427698 T6373 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1427698 T6373 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1427698 T6373 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1427698 T6373 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1427699 T6373 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1427699 T6373 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1427699 T6373 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1427700 T6373 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1427701 T6373 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1427701 T6373 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1427702 T6373 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1427703 T6373 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1427703 T6373 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 1427703 T6373 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1427704 T6373 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 1427704 T6373 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1427705 T6373 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 1427705 T6373 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1427706 T6373 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1427706 T6373 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1427724 T6373 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1427726 T6373 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1427728 T6373 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1427730 T6373 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1427732 T6373 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1427737 T6373 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1427737 T6373 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1427738 T6373 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@9c2c1b3 [junit4] 2> 1427740 T6373 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(SimpleFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/index-SimpleFSDirectory-002 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8a05bbf)),segFN=segments_1,generation=1} [junit4] 2> 1427740 T6373 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1427741 T6373 oass.SolrIndexSearcher.<init> Opening Searcher@51d39359[collection1] main [junit4] 2> 1427741 T6373 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1427742 T6373 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1427742 T6373 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1427742 T6373 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1427742 T6373 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1427743 T6373 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1427743 T6373 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 1427744 T6373 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1427744 T6373 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 1427744 T6373 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1427749 T6374 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51d39359[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 1427754 T6373 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1427754 T6377 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58236 collection:collection1 shard:shard3 [junit4] 2> 1427755 T6324 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1427755 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1427755 T6377 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 1427759 T6377 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 1427761 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1427761 T6377 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1427761 T6377 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C631 name=collection1 org.apache.solr.core.SolrCore@183fb6dc url=http://127.0.0.1:58236/collection1 node=127.0.0.1:58236_ C631_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:58236, node_name=127.0.0.1:58236_} [junit4] 2> 1427761 T6377 C631 P58236 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58236/collection1/ [junit4] 2> 1427762 T6377 C631 P58236 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1427762 T6377 C631 P58236 oasc.SyncStrategy.syncToMe http://127.0.0.1:58236/collection1/ has no replicas [junit4] 2> 1427762 T6377 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58236/collection1/ shard3 [junit4] 2> 1427762 T6377 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 1427764 T6351 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> 1427764 T6372 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> 1427764 T6358 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> 1427765 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1427869 T6351 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> 1427869 T6372 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> 1427869 T6358 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> 1427918 T6377 oasc.ZkController.register We are http://127.0.0.1:58236/collection1/ and leader is http://127.0.0.1:58236/collection1/ [junit4] 2> 1427919 T6377 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58236 [junit4] 2> 1427919 T6377 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1427919 T6377 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 1427919 T6377 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1427921 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1427922 T6347 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "shard":"shard3", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:58236";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"3", [junit4] 2> "node_name":"127.0.0.1:58236_"} [junit4] 2> 1428025 T6372 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> 1428025 T6358 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> 1428025 T6351 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> 1429067 T6324 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 1429068 T6324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1429071 T6324 oejs.AbstractConnector.doStart Started [email protected]:58240 [junit4] 2> 1429071 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1429071 T6324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1429072 T6324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004 [junit4] 2> 1429072 T6324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/' [junit4] 2> 1429103 T6324 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/solr.xml [junit4] 2> 1429162 T6324 oasc.CoreContainer.<init> New CoreContainer 1713914374 [junit4] 2> 1429162 T6324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/] [junit4] 2> 1429163 T6324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1429163 T6324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1429164 T6324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1429164 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1429164 T6324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1429164 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1429164 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1429165 T6324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1429165 T6324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1429165 T6324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1429166 T6324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1429166 T6324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1429166 T6324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1429166 T6324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58226/solr [junit4] 2> 1429167 T6324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1429167 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1429168 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1429169 T6388 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d06cc4f name:ZooKeeperConnection Watcher:127.0.0.1:58226 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1429170 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1429171 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1429173 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1429174 T6390 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16cf3d30 name:ZooKeeperConnection Watcher:127.0.0.1:58226/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1429174 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1429178 T6324 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1430180 T6324 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58240_ [junit4] 2> 1430181 T6324 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58240_ [junit4] 2> 1430184 T6324 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1430191 T6392 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1430191 T6392 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1430192 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1430192 T6392 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1430194 T6347 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:58240";, [junit4] 2> "operation":"state", [junit4] 2> "numShards":"3", [junit4] 2> "node_name":"127.0.0.1:58240_"} [junit4] 2> 1430194 T6347 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3 [junit4] 2> 1430194 T6347 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1430196 T6391 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> 1430196 T6372 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> 1430196 T6358 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> 1430196 T6351 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> 1431193 T6392 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1431194 T6392 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1431194 T6392 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1431195 T6392 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1431195 T6392 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1431196 T6392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/collection1/' [junit4] 2> 1431198 T6392 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 1431199 T6392 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 1431200 T6392 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 1431272 T6392 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 1431324 T6392 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1431425 T6392 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 1431437 T6392 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1431818 T6392 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1431821 T6392 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1431822 T6392 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1431827 T6392 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1431845 T6392 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1431846 T6392 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1431846 T6392 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-004/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty2/ [junit4] 2> 1431846 T6392 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1582b0 [junit4] 2> 1431849 T6392 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty2 [junit4] 2> 1431850 T6392 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty2/index/ [junit4] 2> 1431850 T6392 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 1431853 T6392 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-001/jetty2/index [junit4] 2> 1431853 T6392 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1431856 T6392 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(SimpleFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64ca7bbc)),segFN=segments_1,generation=1} [junit4] 2> 1431857 T6392 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1431864 T6392 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1431864 T6392 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1431865 T6392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1431865 T6392 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1431865 T6392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1431866 T6392 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1431866 T6392 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1431866 T6392 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1431867 T6392 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1431867 T6392 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1431868 T6392 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1431868 T6392 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1431869 T6392 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1431870 T6392 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1431870 T6392 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 1431870 T6392 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 1431871 T6392 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 1431871 T6392 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1431872 T6392 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 1431872 T6392 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 1431873 T6392 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1431873 T6392 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1431894 T6392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1431896 T6392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1431898 T6392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1431900 T6392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1431903 T6392 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1431908 T6392 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1431908 T6392 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1431909 T6392 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@29c5ed1a [junit4] 2> 1431913 T6392 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(SimpleFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/index-SimpleFSDirectory-003 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64ca7bbc)),segFN=segments_1,generation=1} [junit4] 2> 1431913 T6392 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1431914 T6392 oass.SolrIndexSearcher.<init> Opening Searcher@1e4ae8db[collection1] main [junit4] 2> 1431914 T6392 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1431915 T6392 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1431915 T6392 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1431915 T6392 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1431915 T6392 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1431916 T6392 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1431916 T6392 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 1431917 T6392 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1431917 T6392 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 1431917 T6392 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1431921 T6393 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e4ae8db[collection1] main{UninvertingDirectoryReader()} [junit4] 2> 1431926 T6392 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1431927 T6396 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58240 collection:collection1 shard:shard1 [junit4] 2> 1431927 T6324 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1431928 T6396 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 1431928 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1431931 T6396 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 1431933 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1431933 T6396 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1431933 T6396 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C632 name=collection1 org.apache.solr.core.SolrCore@71828fe5 url=http://127.0.0.1:58240/collection1 node=127.0.0.1:58240_ C632_STATE=coll:collection1 core:collection1 props:{core=collection1, state=down, base_url=http://127.0.0.1:58240, node_name=127.0.0.1:58240_} [junit4] 2> 1431933 T6396 C632 P58240 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58240/collection1/ [junit4] 2> 1431934 T6396 C632 P58240 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1431934 T6396 C632 P58240 oasc.SyncStrategy.syncToMe http://127.0.0.1:58240/collection1/ has no replicas [junit4] 2> 1431934 T6396 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58240/collection1/ shard1 [junit4] 2> 1431934 T6396 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 1431936 T6351 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> 1431936 T6372 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> 1431936 T6391 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> 1431936 T6358 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> 1431940 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1431941 T6358 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> 1431941 T6351 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> 1431941 T6391 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> 1431941 T6372 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> 1431988 T6396 oasc.ZkController.register We are http://127.0.0.1:58240/collection1/ and leader is http://127.0.0.1:58240/collection1/ [junit4] 2> 1431989 T6396 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58240 [junit4] 2> 1431989 T6396 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1431989 T6396 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 1431989 T6396 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1431991 T6351 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 1431993 T6347 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:58240";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node2", [junit4] 2> "numShards":"3", [junit4] 2> "node_name":"127.0.0.1:58240_"} [junit4] 2> 1432096 T6372 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> 1432096 T6358 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> 1432096 T6351 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> 1432097 T6391 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> 1433130 T6324 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 1433131 T6324 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1433134 T6324 oejs.AbstractConnector.doStart Started [email protected]:55594 [junit4] 2> 1433134 T6324 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1433135 T6324 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1433135 T6324 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-005 [junit4] 2> 1433135 T6324 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-005/' [junit4] 2> 1433164 T6324 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-005/solr.xml [junit4] 2> 1433231 T6324 oasc.CoreContainer.<init> New CoreContainer 2027714076 [junit4] 2> 1433232 T6324 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/./temp/solr.cloud.FullSolrCloudDistribCmdsTest-46BCC646B336D449-001/tempDir-005/] [junit4] 2> 1433232 T6324 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1433233 T6324 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1433233 T6324 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1433233 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1433233 T6324 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1433234 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1433234 T6324 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1433234 T6324 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1433234 T6324 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1433235 T6324 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1433235 T6324 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1433235 T6324 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1433235 T6324 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1433236 T6324 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58226/solr [junit4] 2> 1433236 T6324 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1433236 T6324 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1433237 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1433238 T6407 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@51ce6641 name:ZooKeeperConnection Watcher:127.0.0.1:58226 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1433240 T6324 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1433240 T6324 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1433241 T6324 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1433243 T6409 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d7902d0 name:ZooKeeperConnection Watcher:127.0.0.1:58226/solr got event WatchedEvent stat [...truncated too long message...] ent_f2_s=PostingsFormat(name=Lucene41WithOrds), 1parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 7grand3_s=PostingsFormat(name=MockRandom), 15parent_f1_s=PostingsFormat(name=MockRandom), 42parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 2parent_f1_s=PostingsFormat(name=MockRandom), 37parent_f2_s=PostingsFormat(name=Asserting), 4parent_f2_s=PostingsFormat(name=Asserting), id=PostingsFormat(name=Lucene41WithOrds), 2parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 41parent_f2_s=PostingsFormat(name=MockRandom), text_t=PostingsFormat(name=MockRandom), 7parent_f2_s=PostingsFormat(name=MockRandom), 4child1_s=PostingsFormat(name=Asserting), 6grand1_s=PostingsFormat(name=Lucene41WithOrds), 8parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), type_s=PostingsFormat(name=MockRandom), 10parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 7grand0_s=PostingsFormat(name=Lucene41WithOrds), 20parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 26parent_f1_s=PostingsFormat(name=MockRandom), 2grand0_s=PostingsFormat(name=MockRandom), 41parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 34parent_f2_s=PostingsFormat(name=MockRandom), a_t=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), _root_=PostingsFormat(name=MockRandom), 10parent_f1_s=PostingsFormat(name=Asserting), 34parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 23parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 4parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 6grand2_s=PostingsFormat(name=MockRandom), 35parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 32parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 13parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 25parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 6grand5_s=PostingsFormat(name=Lucene41WithOrds), 37parent_f1_s=PostingsFormat(name=MockRandom), 28parent_f2_s=PostingsFormat(name=Lucene41WithOrds), rnd_b=PostingsFormat(name=Lucene41WithOrds), 33parent_f2_s=PostingsFormat(name=Asserting), 4child0_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 1parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 4grand0_s=PostingsFormat(name=MockRandom), 7grand5_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 12parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 9parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 30parent_f2_s=PostingsFormat(name=MockRandom), 4grand3_s=PostingsFormat(name=Lucene41WithOrds), 14parent_f1_s=PostingsFormat(name=Asserting), 14parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 31parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 4child2_s=PostingsFormat(name=MockRandom), 6grand4_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 6grand3_s=PostingsFormat(name=Asserting), 6parent_f1_s=PostingsFormat(name=MockRandom), 7grand2_s=PostingsFormat(name=Asserting), 16parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 11parent_f1_s=PostingsFormat(name=MockRandom), 5grand2_s=PostingsFormat(name=Lucene41WithOrds), 2grand1_s=PostingsFormat(name=Lucene41WithOrds), 36parent_f1_s=PostingsFormat(name=Asserting), 3child2_s=PostingsFormat(name=Asserting), 29parent_f2_s=PostingsFormat(name=MockRandom), 22parent_f2_s=PostingsFormat(name=Asserting), 39parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 7parent_f1_s=PostingsFormat(name=Asserting), 29parent_f1_s=PostingsFormat(name=Asserting), 43parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 3grand0_s=PostingsFormat(name=Lucene41WithOrds), 40parent_f1_s=PostingsFormat(name=MockRandom), 30parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 1grand0_s=PostingsFormat(name=Asserting), 32parent_f1_s=PostingsFormat(name=Asserting), 9parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 27parent_f1_s=PostingsFormat(name=Lucene41WithOrds), 25parent_f1_s=PostingsFormat(name=Asserting), 3grand1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 7grand6_s=PostingsFormat(name=Asserting), 21parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 0parent_f2_s=PostingsFormat(name=Asserting), 16parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 4grand2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 13parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 35parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), _version_=PostingsFormat(name=MockRandom), 27parent_f2_s=PostingsFormat(name=MockRandom), 21parent_f1_s=PostingsFormat(name=Asserting), 19parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 31parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 3grand2_s=PostingsFormat(name=Asserting), 3parent_f2_s=PostingsFormat(name=MockRandom), 5grand1_s=PostingsFormat(name=MockRandom), 3parent_f1_s=PostingsFormat(name=Asserting), 23parent_f2_s=PostingsFormat(name=MockRandom), 17parent_f2_s=PostingsFormat(name=Lucene41WithOrds), 18parent_f2_s=PostingsFormat(name=MockRandom), 20parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 0parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 24parent_f1_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 12parent_f2_s=PostingsFormat(name=MockRandom), 22parent_f1_s=PostingsFormat(name=MockRandom), 4child3_s=PostingsFormat(name=Lucene41WithOrds), 18parent_f1_s=PostingsFormat(name=Asserting), 17parent_f1_s=PostingsFormat(name=MockRandom), 28parent_f1_s=PostingsFormat(name=MockRandom), 5grand0_s=PostingsFormat(name=Asserting), 26parent_f2_s=PostingsFormat(name=Asserting), 7grand4_s=PostingsFormat(name=Lucene41WithOrds), 36parent_f2_s=TestBloomFilteredLucene41Postings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))), 5grand4_s=PostingsFormat(name=Asserting), 33parent_f1_s=PostingsFormat(name=MockRandom)}, docValues:{}, sim=DefaultSimilarity, locale=et_EE, timezone=America/Matamoros [junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=85466168,total=459276288 [junit4] 2> NOTE: All tests run in this JVM: [TestSolrQueryParserDefaultOperatorResource, TestDefaultSearchFieldResource, TestRandomDVFaceting, CircularListTest, SSLMigrationTest, LegacyHTMLStripCharFilterTest, CursorMarkTest, TestSchemaNameResource, CurrencyFieldXmlFileTest, TestMissingGroups, RemoteQueryErrorTest, TestDynamicFieldCollectionResource, OverseerRolesTest, SpellCheckCollatorTest, HardAutoCommitTest, BinaryUpdateRequestHandlerTest, InfoHandlerTest, TestHashPartitioner, AssignTest, CopyFieldTest, TestSolrCoreProperties, HighlighterTest, UpdateRequestProcessorFactoryTest, TestCloudManagedSchema, BasicFunctionalityTest, DistributedFacetPivotLargeTest, TestMultiCoreConfBootstrap, DateMathParserTest, LeaderElectionIntegrationTest, TestStressLucene, ModifyConfFileTest, TestDistributedMissingSort, OverseerStatusTest, StatsComponentTest, DistributedDebugComponentTest, TestPerFieldSimilarity, TestOmitPositions, MigrateRouteKeyTest, SolrXmlInZkTest, TestUniqueKeyFieldResource, RAMDirectoryFactoryTest, TestJmxMonitoredMap, ZkNodePropsTest, HdfsCollectionsAPIDistributedZkTest, TestFieldResource, SyncSliceTest, AnalysisAfterCoreReloadTest, IndexSchemaTest, TestLuceneMatchVersion, TestSolr4Spatial, TestQuerySenderListener, AnalysisErrorHandlingTest, DistributedIntervalFacetingTest, FullSolrCloudDistribCmdsTest] [junit4] Completed on J2 in 994.96s, 1 test, 1 error <<< FAILURES! [...truncated 286 lines...] BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:498: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:471: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:60: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:189: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:496: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1360: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:963: There were test failures: 437 suites, 1827 tests, 1 error, 54 ignored (11 assumptions) Total time: 152 minutes 35 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-NightlyTests-trunk #619 Archived 5 artifacts Archive block size is 32768 Received 0 blocks and 432339559 bytes Compression is 0.0% Took 1 min 15 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
