X-Git-Url: https://git.whamcloud.com/?p=fs%2Flustre-release.git;a=blobdiff_plain;f=lustre%2Fptlrpc%2Fclient.c;h=39d704929b293062caff27cf36c8f86f2dc3b157;hp=39a77aff776849a0c8f7c7d03b567f4790dd6746;hb=0cb008aa46d283c03aeae79fd3ab36c828da2174;hpb=0df86a8af6e98d885da3516691e7ebf27804a9c5 diff --git a/lustre/ptlrpc/client.c b/lustre/ptlrpc/client.c index 39a77af..39d7049 100644 --- a/lustre/ptlrpc/client.c +++ b/lustre/ptlrpc/client.c @@ -1,26 +1,37 @@ /* -*- mode: c; c-basic-offset: 8; indent-tabs-mode: nil; -*- * vim:expandtab:shiftwidth=8:tabstop=8: * - * Copyright (c) 2002, 2003 Cluster File Systems, Inc. + * GPL HEADER START * - * This file is part of the Lustre file system, http://www.lustre.org - * Lustre is a trademark of Cluster File Systems, Inc. + * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. * - * You may have signed or agreed to another license before downloading - * this software. If so, you are bound by the terms and conditions - * of that agreement, and the following does not apply to you. See the - * LICENSE file included with this distribution for more information. + * This program is free software; you can redistribute it and/or modify + * it under the terms of the GNU General Public License version 2 only, + * as published by the Free Software Foundation. * - * If you did not agree to a different license, then this copy of Lustre - * is open source software; you can redistribute it and/or modify it - * under the terms of version 2 of the GNU General Public License as - * published by the Free Software Foundation. + * This program is distributed in the hope that it will be useful, but + * WITHOUT ANY WARRANTY; without even the implied warranty of + * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU + * General Public License version 2 for more details (a copy is included + * in the LICENSE file that accompanied this code). * - * In either case, Lustre is distributed in the hope that it will be - * useful, but WITHOUT ANY WARRANTY; without even the implied warranty - * of MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the - * license text for more details. + * You should have received a copy of the GNU General Public License + * version 2 along with this program; If not, see + * http://www.sun.com/software/products/lustre/docs/GPLv2.pdf * + * Please contact Sun Microsystems, Inc., 4150 Network Circle, Santa Clara, + * CA 95054 USA or visit www.sun.com if you need additional information or + * have any questions. + * + * GPL HEADER END + */ +/* + * Copyright 2008 Sun Microsystems, Inc. All rights reserved + * Use is subject to license terms. + */ +/* + * This file is part of Lustre, http://www.lustre.org/ + * Lustre is a trademark of Sun Microsystems, Inc. */ #define DEBUG_SUBSYSTEM S_RPC @@ -35,6 +46,7 @@ #include #include #include +#include #include "ptlrpc_internal.h" @@ -59,7 +71,7 @@ struct ptlrpc_connection *ptlrpc_uuid_to_connection(struct obd_uuid *uuid) return NULL; } - c = ptlrpc_get_connection(peer, self, uuid); + c = ptlrpc_connection_get(peer, self, uuid); if (c) { memcpy(c->c_remote_uuid.uuid, uuid->uuid, sizeof(c->c_remote_uuid.uuid)); @@ -70,24 +82,6 @@ struct ptlrpc_connection *ptlrpc_uuid_to_connection(struct obd_uuid *uuid) return c; } -void ptlrpc_readdress_connection(struct ptlrpc_connection *conn, - struct obd_uuid *uuid) -{ - lnet_nid_t self; - lnet_process_id_t peer; - int err; - - err = ptlrpc_uuid_to_peer(uuid, &peer, &self); - if (err != 0) { - CERROR("cannot find peer %s!\n", uuid->uuid); - return; - } - - conn->c_peer = peer; - conn->c_self = self; - return; -} - static inline struct ptlrpc_bulk_desc *new_bulk(int npages, int type, int portal) { struct ptlrpc_bulk_desc *desc; @@ -100,7 +94,7 @@ static inline struct ptlrpc_bulk_desc *new_bulk(int npages, int type, int portal cfs_waitq_init(&desc->bd_waitq); desc->bd_max_iov = npages; desc->bd_iov_count = 0; - desc->bd_md_h = LNET_INVALID_HANDLE; + LNetInvalidateHandle(&desc->bd_md_h); desc->bd_portal = portal; desc->bd_type = type; @@ -132,8 +126,8 @@ struct ptlrpc_bulk_desc *ptlrpc_prep_bulk_imp (struct ptlrpc_request *req, return desc; } -struct ptlrpc_bulk_desc *ptlrpc_prep_bulk_exp (struct ptlrpc_request *req, - int npages, int type, int portal) +struct ptlrpc_bulk_desc *ptlrpc_prep_bulk_exp(struct ptlrpc_request *req, + int npages, int type, int portal) { struct obd_export *exp = req->rq_export; struct ptlrpc_bulk_desc *desc; @@ -179,6 +173,9 @@ void ptlrpc_free_bulk(struct ptlrpc_bulk_desc *desc) LASSERT(desc->bd_iov_count != LI_POISON); /* not freed already */ LASSERT(!desc->bd_network_rw); /* network hands off or */ LASSERT((desc->bd_export != NULL) ^ (desc->bd_import != NULL)); + + sptlrpc_enc_pool_put_pages(desc); + if (desc->bd_export) class_export_put(desc->bd_export); else @@ -189,6 +186,168 @@ void ptlrpc_free_bulk(struct ptlrpc_bulk_desc *desc) EXIT; } +/* Set server timelimit for this req */ +void ptlrpc_at_set_req_timeout(struct ptlrpc_request *req) +{ + __u32 serv_est; + int idx; + struct imp_at *at; + + LASSERT(req->rq_import); + + if (AT_OFF) { + /* non-AT settings */ + req->rq_timeout = req->rq_import->imp_server_timeout ? + obd_timeout / 2 : obd_timeout; + lustre_msg_set_timeout(req->rq_reqmsg, req->rq_timeout); + return; + } + + at = &req->rq_import->imp_at; + idx = import_at_get_index(req->rq_import, + req->rq_request_portal); + serv_est = at_get(&at->iat_service_estimate[idx]); + /* add an arbitrary minimum: 125% +5 sec */ + req->rq_timeout = serv_est + (serv_est >> 2) + 5; + /* We could get even fancier here, using history to predict increased + loading... */ + + /* Let the server know what this RPC timeout is by putting it in the + reqmsg*/ + lustre_msg_set_timeout(req->rq_reqmsg, req->rq_timeout); +} + +/* Adjust max service estimate based on server value */ +static void ptlrpc_at_adj_service(struct ptlrpc_request *req, + unsigned int serv_est) +{ + int idx; + unsigned int oldse; + struct imp_at *at; + + LASSERT(req->rq_import); + at = &req->rq_import->imp_at; + + idx = import_at_get_index(req->rq_import, req->rq_request_portal); + /* max service estimates are tracked on the server side, + so just keep minimal history here */ + oldse = at_add(&at->iat_service_estimate[idx], serv_est); + if (oldse != 0) + CDEBUG(D_ADAPTTO, "The RPC service estimate for %s ptl %d " + "has changed from %d to %d\n", + req->rq_import->imp_obd->obd_name,req->rq_request_portal, + oldse, at_get(&at->iat_service_estimate[idx])); +} + +/* Expected network latency per remote node (secs) */ +int ptlrpc_at_get_net_latency(struct ptlrpc_request *req) +{ + return AT_OFF ? 0 : at_get(&req->rq_import->imp_at.iat_net_latency); +} + +/* Adjust expected network latency */ +static void ptlrpc_at_adj_net_latency(struct ptlrpc_request *req, + unsigned int service_time) +{ + unsigned int nl, oldnl; + struct imp_at *at; + time_t now = cfs_time_current_sec(); + + LASSERT(req->rq_import); + at = &req->rq_import->imp_at; + + /* Network latency is total time less server processing time */ + nl = max_t(int, now - req->rq_sent - service_time, 0) +1/*st rounding*/; + if (service_time > now - req->rq_sent + 3 /* bz16408 */) + CWARN("Reported service time %u > total measured time " + CFS_DURATION_T"\n", service_time, + cfs_time_sub(now, req->rq_sent)); + + oldnl = at_add(&at->iat_net_latency, nl); + if (oldnl != 0) + CDEBUG(D_ADAPTTO, "The network latency for %s (nid %s) " + "has changed from %d to %d\n", + req->rq_import->imp_obd->obd_name, + obd_uuid2str( + &req->rq_import->imp_connection->c_remote_uuid), + oldnl, at_get(&at->iat_net_latency)); +} + +static int unpack_reply(struct ptlrpc_request *req) +{ + int rc; + + /* Clear reply swab mask; we may have already swabbed an early reply */ + req->rq_rep_swab_mask = 0; + + rc = lustre_unpack_msg(req->rq_repmsg, req->rq_replen); + if (rc) { + DEBUG_REQ(D_ERROR, req, "unpack_rep failed: %d", rc); + return(-EPROTO); + } + + rc = lustre_unpack_rep_ptlrpc_body(req, MSG_PTLRPC_BODY_OFF); + if (rc) { + DEBUG_REQ(D_ERROR, req, "unpack ptlrpc body failed: %d", rc); + return(-EPROTO); + } + return 0; +} + +/* + * Handle an early reply message, called with the rq_lock held. + * If anything goes wrong just ignore it - same as if it never happened + */ +static int ptlrpc_at_recv_early_reply(struct ptlrpc_request *req) +{ + struct ptlrpc_request *early_req; + time_t olddl; + int rc; + ENTRY; + + req->rq_early = 0; + spin_unlock(&req->rq_lock); + + rc = sptlrpc_cli_unwrap_early_reply(req, &early_req); + if (rc) { + spin_lock(&req->rq_lock); + RETURN(rc); + } + + rc = unpack_reply(early_req); + if (rc == 0) { + /* Expecting to increase the service time estimate here */ + ptlrpc_at_adj_service(req, + lustre_msg_get_timeout(early_req->rq_repmsg)); + ptlrpc_at_adj_net_latency(req, + lustre_msg_get_service_time(early_req->rq_repmsg)); + } + + sptlrpc_cli_finish_early_reply(early_req); + + spin_lock(&req->rq_lock); + + if (rc == 0) { + /* Adjust the local timeout for this req */ + ptlrpc_at_set_req_timeout(req); + + olddl = req->rq_deadline; + /* server assumes it now has rq_timeout from when it sent the + early reply, so client should give it at least that long. */ + req->rq_deadline = cfs_time_current_sec() + req->rq_timeout + + ptlrpc_at_get_net_latency(req); + + DEBUG_REQ(D_ADAPTTO, req, + "Early reply #%d, new deadline in "CFS_DURATION_T"s " + "("CFS_DURATION_T"s)", req->rq_early_count, + cfs_time_sub(req->rq_deadline, + cfs_time_current_sec()), + cfs_time_sub(req->rq_deadline, olddl)); + } + + RETURN(rc); +} + void ptlrpc_free_rq_pool(struct ptlrpc_request_pool *pool) { struct list_head *l, *tmp; @@ -200,8 +359,9 @@ void ptlrpc_free_rq_pool(struct ptlrpc_request_pool *pool) list_for_each_safe(l, tmp, &pool->prp_req_list) { req = list_entry(l, struct ptlrpc_request, rq_list); list_del(&req->rq_list); - LASSERT (req->rq_reqmsg); - OBD_FREE(req->rq_reqmsg, pool->prp_rq_size); + LASSERT(req->rq_reqbuf); + LASSERT(req->rq_reqbuf_len == pool->prp_rq_size); + OBD_FREE(req->rq_reqbuf, pool->prp_rq_size); OBD_FREE(req, sizeof(*req)); } OBD_FREE(pool, sizeof(*pool)); @@ -212,7 +372,7 @@ void ptlrpc_add_rqs_to_pool(struct ptlrpc_request_pool *pool, int num_rq) int i; int size = 1; - while (size < pool->prp_rq_size) + while (size < pool->prp_rq_size + SPTLRPC_MAX_PAYLOAD) size <<= 1; LASSERTF(list_empty(&pool->prp_req_list) || size == pool->prp_rq_size, @@ -234,7 +394,8 @@ void ptlrpc_add_rqs_to_pool(struct ptlrpc_request_pool *pool, int num_rq) OBD_FREE(req, sizeof(struct ptlrpc_request)); return; } - req->rq_reqmsg = msg; + req->rq_reqbuf = msg; + req->rq_reqbuf_len = size; req->rq_pool = pool; spin_lock(&pool->prp_lock); list_add_tail(&req->rq_list, &pool->prp_req_list); @@ -273,7 +434,7 @@ struct ptlrpc_request_pool *ptlrpc_init_rq_pool(int num_rq, int msgsize, static struct ptlrpc_request *ptlrpc_prep_req_from_pool(struct ptlrpc_request_pool *pool) { struct ptlrpc_request *request; - struct lustre_msg *reqmsg; + struct lustre_msg *reqbuf; if (!pool) return NULL; @@ -294,62 +455,57 @@ static struct ptlrpc_request *ptlrpc_prep_req_from_pool(struct ptlrpc_request_po list_del(&request->rq_list); spin_unlock(&pool->prp_lock); - LASSERT(request->rq_reqmsg); + LASSERT(request->rq_reqbuf); LASSERT(request->rq_pool); - reqmsg = request->rq_reqmsg; + reqbuf = request->rq_reqbuf; memset(request, 0, sizeof(*request)); - request->rq_reqmsg = reqmsg; + request->rq_reqbuf = reqbuf; + request->rq_reqbuf_len = pool->prp_rq_size; request->rq_pool = pool; - request->rq_reqlen = pool->prp_rq_size; + return request; } -struct ptlrpc_request * -ptlrpc_prep_req_pool(struct obd_import *imp, __u32 version, int opcode, - int count, int *lengths, char **bufs, - struct ptlrpc_request_pool *pool) +static void __ptlrpc_free_req_to_pool(struct ptlrpc_request *request) { - struct ptlrpc_request *request = NULL; - int rc; - ENTRY; - - /* The obd disconnected */ - if (imp == NULL) - return NULL; - - LASSERT(imp != LP_POISON); - LASSERT((unsigned long)imp->imp_client > 0x1000); - LASSERT(imp->imp_client != LP_POISON); + struct ptlrpc_request_pool *pool = request->rq_pool; - if (pool) - request = ptlrpc_prep_req_from_pool(pool); + spin_lock(&pool->prp_lock); + LASSERT(list_empty(&request->rq_list)); + list_add_tail(&request->rq_list, &pool->prp_req_list); + spin_unlock(&pool->prp_lock); +} - if (!request) - OBD_ALLOC(request, sizeof(*request)); +static int __ptlrpc_request_bufs_pack(struct ptlrpc_request *request, + __u32 version, int opcode, + int count, __u32 *lengths, char **bufs, + struct ptlrpc_cli_ctx *ctx) +{ + struct obd_import *imp = request->rq_import; + int rc; + ENTRY; - if (!request) { - CERROR("request allocation out of memory\n"); - RETURN(NULL); + if (unlikely(ctx)) + request->rq_cli_ctx = sptlrpc_cli_ctx_get(ctx); + else { + rc = sptlrpc_req_get_ctx(request); + if (rc) + GOTO(out_free, rc); } - rc = lustre_pack_request(request, imp->imp_msg_magic, count, lengths, - bufs); + sptlrpc_req_set_flavor(request, opcode); + + rc = lustre_pack_request(request, imp->imp_msg_magic, count, + lengths, bufs); if (rc) { LASSERT(!request->rq_pool); - OBD_FREE(request, sizeof(*request)); - RETURN(NULL); + GOTO(out_ctx, rc); } lustre_msg_add_version(request->rq_reqmsg, version); - - if (imp->imp_server_timeout) - request->rq_timeout = obd_timeout / 2; - else - request->rq_timeout = obd_timeout; request->rq_send_state = LUSTRE_IMP_FULL; request->rq_type = PTL_RPC_MSG_REQUEST; - request->rq_import = class_import_get(imp); request->rq_export = NULL; request->rq_req_cbid.cbid_fn = request_out_callback; @@ -360,27 +516,158 @@ ptlrpc_prep_req_pool(struct obd_import *imp, __u32 version, int opcode, request->rq_phase = RQ_PHASE_NEW; - /* XXX FIXME bug 249 */ request->rq_request_portal = imp->imp_client->cli_request_portal; request->rq_reply_portal = imp->imp_client->cli_reply_portal; + ptlrpc_at_set_req_timeout(request); + spin_lock_init(&request->rq_lock); CFS_INIT_LIST_HEAD(&request->rq_list); + CFS_INIT_LIST_HEAD(&request->rq_timed_list); CFS_INIT_LIST_HEAD(&request->rq_replay_list); + CFS_INIT_LIST_HEAD(&request->rq_mod_list); + CFS_INIT_LIST_HEAD(&request->rq_ctx_chain); CFS_INIT_LIST_HEAD(&request->rq_set_chain); + CFS_INIT_LIST_HEAD(&request->rq_history_list); cfs_waitq_init(&request->rq_reply_waitq); request->rq_xid = ptlrpc_next_xid(); atomic_set(&request->rq_refcount, 1); lustre_msg_set_opc(request->rq_reqmsg, opcode); - lustre_msg_set_flags(request->rq_reqmsg, 0); - RETURN(request); + RETURN(0); +out_ctx: + sptlrpc_cli_ctx_put(request->rq_cli_ctx, 1); +out_free: + class_import_put(imp); + return rc; +} + +int ptlrpc_request_bufs_pack(struct ptlrpc_request *request, + __u32 version, int opcode, char **bufs, + struct ptlrpc_cli_ctx *ctx) +{ + int count; + + count = req_capsule_filled_sizes(&request->rq_pill, RCL_CLIENT); + return __ptlrpc_request_bufs_pack(request, version, opcode, count, + request->rq_pill.rc_area[RCL_CLIENT], + bufs, ctx); +} +EXPORT_SYMBOL(ptlrpc_request_bufs_pack); + +int ptlrpc_request_pack(struct ptlrpc_request *request, + __u32 version, int opcode) +{ + return ptlrpc_request_bufs_pack(request, version, opcode, NULL, NULL); +} + +static inline +struct ptlrpc_request *__ptlrpc_request_alloc(struct obd_import *imp, + struct ptlrpc_request_pool *pool) +{ + struct ptlrpc_request *request = NULL; + + if (pool) + request = ptlrpc_prep_req_from_pool(pool); + + if (!request) + OBD_ALLOC_PTR(request); + + if (request) { + LASSERTF((unsigned long)imp > 0x1000, "%p", imp); + LASSERT(imp != LP_POISON); + LASSERTF((unsigned long)imp->imp_client > 0x1000, "%p", + imp->imp_client); + LASSERT(imp->imp_client != LP_POISON); + + request->rq_import = class_import_get(imp); + } else { + CERROR("request allocation out of memory\n"); + } + + return request; +} + +static struct ptlrpc_request * +ptlrpc_request_alloc_internal(struct obd_import *imp, + struct ptlrpc_request_pool * pool, + const struct req_format *format) +{ + struct ptlrpc_request *request; + + request = __ptlrpc_request_alloc(imp, pool); + if (request == NULL) + return NULL; + + req_capsule_init(&request->rq_pill, request, RCL_CLIENT); + req_capsule_set(&request->rq_pill, format); + return request; +} + +struct ptlrpc_request *ptlrpc_request_alloc(struct obd_import *imp, + const struct req_format *format) +{ + return ptlrpc_request_alloc_internal(imp, NULL, format); +} + +struct ptlrpc_request *ptlrpc_request_alloc_pool(struct obd_import *imp, + struct ptlrpc_request_pool * pool, + const struct req_format *format) +{ + return ptlrpc_request_alloc_internal(imp, pool, format); +} + +void ptlrpc_request_free(struct ptlrpc_request *request) +{ + if (request->rq_pool) + __ptlrpc_free_req_to_pool(request); + else + OBD_FREE_PTR(request); +} + +struct ptlrpc_request *ptlrpc_request_alloc_pack(struct obd_import *imp, + const struct req_format *format, + __u32 version, int opcode) +{ + struct ptlrpc_request *req = ptlrpc_request_alloc(imp, format); + int rc; + + if (req) { + rc = ptlrpc_request_pack(req, version, opcode); + if (rc) { + ptlrpc_request_free(req); + req = NULL; + } + } + return req; +} + +struct ptlrpc_request * +ptlrpc_prep_req_pool(struct obd_import *imp, + __u32 version, int opcode, + int count, __u32 *lengths, char **bufs, + struct ptlrpc_request_pool *pool) +{ + struct ptlrpc_request *request; + int rc; + + request = __ptlrpc_request_alloc(imp, pool); + if (!request) + return NULL; + + rc = __ptlrpc_request_bufs_pack(request, version, opcode, count, + lengths, bufs, NULL); + if (rc) { + ptlrpc_request_free(request); + request = NULL; + } + return request; } struct ptlrpc_request * ptlrpc_prep_req(struct obd_import *imp, __u32 version, int opcode, int count, - int *lengths, char **bufs) + __u32 *lengths, char **bufs) { return ptlrpc_prep_req_pool(imp, version, opcode, count, lengths, bufs, NULL); @@ -399,6 +686,7 @@ struct ptlrpc_request_set *ptlrpc_prep_set(void) set->set_remaining = 0; spin_lock_init(&set->set_new_req_lock); CFS_INIT_LIST_HEAD(&set->set_new_requests); + CFS_INIT_LIST_HEAD(&set->set_cblist); RETURN(set); } @@ -435,14 +723,13 @@ void ptlrpc_set_destroy(struct ptlrpc_request_set *set) if (req->rq_phase == RQ_PHASE_NEW) { if (req->rq_interpret_reply != NULL) { - int (*interpreter)(struct ptlrpc_request *, - void *, int) = + ptlrpc_interpterer_t interpreter = req->rq_interpret_reply; /* higher level (i.e. LOV) failed; * let the sub reqs clean up */ req->rq_status = -EBADR; - interpreter(req, &req->rq_async_args, + interpreter(NULL, req, &req->rq_async_args, req->rq_status); } set->set_remaining--; @@ -458,6 +745,22 @@ void ptlrpc_set_destroy(struct ptlrpc_request_set *set) EXIT; } +int ptlrpc_set_add_cb(struct ptlrpc_request_set *set, + set_interpreter_func fn, void *data) +{ + struct ptlrpc_set_cbdata *cbdata; + + OBD_ALLOC_PTR(cbdata); + if (cbdata == NULL) + RETURN(-ENOMEM); + + cbdata->psc_interpret = fn; + cbdata->psc_data = data; + list_add_tail(&cbdata->psc_item, &set->set_cblist); + + RETURN(0); +} + void ptlrpc_set_add_req(struct ptlrpc_request_set *set, struct ptlrpc_request *req) { @@ -469,16 +772,36 @@ void ptlrpc_set_add_req(struct ptlrpc_request_set *set, atomic_inc(&req->rq_import->imp_inflight); } -/* lock so many callers can add things, the context that owns the set - * is supposed to notice these and move them into the set proper. */ -void ptlrpc_set_add_new_req(struct ptlrpc_request_set *set, - struct ptlrpc_request *req) +/** + * Lock so many callers can add things, the context that owns the set + * is supposed to notice these and move them into the set proper. + */ +int ptlrpc_set_add_new_req(struct ptlrpcd_ctl *pc, + struct ptlrpc_request *req) { + struct ptlrpc_request_set *set = pc->pc_set; + + /* + * Let caller know that we stopped and will not handle this request. + * It needs to take care itself of request. + */ + if (test_bit(LIOD_STOP, &pc->pc_flags)) + return -EALREADY; + spin_lock(&set->set_new_req_lock); - /* The set takes over the caller's request reference */ + /* + * The set takes over the caller's request reference. + */ list_add_tail(&req->rq_set_chain, &set->set_new_requests); req->rq_set = set; spin_unlock(&set->set_new_req_lock); + + /* + * Let thead know that we added something and better it to wake up + * and process. + */ + cfs_waitq_signal(&set->set_waitq); + return 0; } /* @@ -500,7 +823,9 @@ static int ptlrpc_import_delay_req(struct obd_import *imp, LASSERT (status != NULL); *status = 0; - if (imp->imp_state == LUSTRE_IMP_NEW) { + if (req->rq_ctx_init || req->rq_ctx_fini) { + /* always allow ctx init/fini rpc go through */ + } else if (imp->imp_state == LUSTRE_IMP_NEW) { DEBUG_REQ(D_ERROR, req, "Uninitialized import."); *status = -EIO; LBUG(); @@ -508,23 +833,35 @@ static int ptlrpc_import_delay_req(struct obd_import *imp, DEBUG_REQ(D_ERROR, req, "IMP_CLOSED "); *status = -EIO; } else if (req->rq_send_state == LUSTRE_IMP_CONNECTING && - imp->imp_state == LUSTRE_IMP_CONNECTING) { + imp->imp_state == LUSTRE_IMP_CONNECTING) { /* allow CONNECT even if import is invalid */ ; - } else if (imp->imp_invalid) { + if (atomic_read(&imp->imp_inval_count) != 0) { + DEBUG_REQ(D_ERROR, req, "invalidate in flight"); + *status = -EIO; + } + } else if ((imp->imp_invalid && (!imp->imp_recon_bk)) || + imp->imp_obd->obd_no_recov) { /* If the import has been invalidated (such as by an OST - * failure), the request must fail with -EIO. */ + * failure), and if the import(MGC) tried all of its connection + * list (Bug 13464), the request must fail with -ESHUTDOWN. + * This indicates the requests should be discarded; an -EIO + * may result in a resend of the request. */ if (!imp->imp_deactive) - DEBUG_REQ(D_ERROR, req, "IMP_INVALID"); - *status = -EIO; + DEBUG_REQ(D_ERROR, req, "IMP_INVALID"); + *status = -ESHUTDOWN; /* bz 12940 */ } else if (req->rq_import_generation != imp->imp_generation) { DEBUG_REQ(D_ERROR, req, "req wrong generation:"); *status = -EIO; } else if (req->rq_send_state != imp->imp_state) { - if (imp->imp_obd->obd_no_recov || imp->imp_dlm_fake || - req->rq_no_delay) + /* invalidate in progress - any requests should be drop */ + if (atomic_read(&imp->imp_inval_count) != 0) { + DEBUG_REQ(D_ERROR, req, "invalidate in flight"); + *status = -EIO; + } else if (imp->imp_dlm_fake || req->rq_no_delay) { *status = -EWOULDBLOCK; - else + } else { delay = 1; + } } RETURN(delay); @@ -556,6 +893,12 @@ static int ptlrpc_check_reply(struct ptlrpc_request *req) if (req->rq_restart) GOTO(out, rc = 1); + + if (req->rq_early) { + ptlrpc_at_recv_early_reply(req); + GOTO(out, rc = 0); /* keep waiting */ + } + EXIT; out: spin_unlock(&req->rq_lock); @@ -570,8 +913,12 @@ static int ptlrpc_check_status(struct ptlrpc_request *req) err = lustre_msg_get_status(req->rq_repmsg); if (lustre_msg_get_type(req->rq_repmsg) == PTL_RPC_MSG_ERR) { - DEBUG_REQ(D_ERROR, req, "type == PTL_RPC_MSG_ERR, err == %d", - err); + struct obd_import *imp = req->rq_import; + __u32 opc = lustre_msg_get_opc(req->rq_reqmsg); + LCONSOLE_ERROR_MSG(0x011,"an error occurred while communicating" + " with %s. The %s operation failed with %d\n", + libcfs_nid2str(imp->imp_connection->c_peer.nid), + ll_opcode2str(opc), err); RETURN(err < 0 ? err : -EINVAL); } @@ -585,67 +932,100 @@ static int ptlrpc_check_status(struct ptlrpc_request *req) RETURN(err); } +/** + * Callback function called when client receives RPC reply for \a req. + */ static int after_reply(struct ptlrpc_request *req) { struct obd_import *imp = req->rq_import; + struct obd_device *obd = req->rq_import->imp_obd; int rc; + struct timeval work_start; + long timediff; ENTRY; LASSERT(!req->rq_receiving_reply); + LASSERT(obd); + LASSERT(req->rq_nob_received <= req->rq_repbuf_len); - /* NB Until this point, the whole of the incoming message, - * including buflens, status etc is in the sender's byte order. */ + /* + * NB Until this point, the whole of the incoming message, + * including buflens, status etc is in the sender's byte order. + */ -#if SWAB_PARANOIA - /* Clear reply swab mask; this is a new reply in sender's byte order */ - req->rq_rep_swab_mask = 0; -#endif - LASSERT (req->rq_nob_received <= req->rq_replen); - rc = lustre_unpack_msg(req->rq_repmsg, req->rq_nob_received); + rc = sptlrpc_cli_unwrap_reply(req); if (rc) { - DEBUG_REQ(D_ERROR, req, "unpack_rep failed: %d\n", rc); - RETURN(-EPROTO); + DEBUG_REQ(D_ERROR, req, "unwrap reply failed (%d):", rc); + RETURN(rc); } - rc = lustre_unpack_rep_ptlrpc_body(req, MSG_PTLRPC_BODY_OFF); - if (rc) { - DEBUG_REQ(D_ERROR, req, "unpack ptlrpc body failed: %d\n", rc); - RETURN(-EPROTO); - } + /* + * Security layer unwrap might ask resend this request. + */ + if (req->rq_resend) + RETURN(0); + + rc = unpack_reply(req); + if (rc) + RETURN(rc); + + do_gettimeofday(&work_start); + timediff = cfs_timeval_sub(&work_start, &req->rq_arrival_time, NULL); + if (obd->obd_svc_stats != NULL) + lprocfs_counter_add(obd->obd_svc_stats, PTLRPC_REQWAIT_CNTR, + timediff); if (lustre_msg_get_type(req->rq_repmsg) != PTL_RPC_MSG_REPLY && lustre_msg_get_type(req->rq_repmsg) != PTL_RPC_MSG_ERR) { - DEBUG_REQ(D_ERROR, req, "invalid packet received (type=%u)\n", + DEBUG_REQ(D_ERROR, req, "invalid packet received (type=%u)", lustre_msg_get_type(req->rq_repmsg)); RETURN(-EPROTO); } + OBD_FAIL_TIMEOUT(OBD_FAIL_PTLRPC_PAUSE_REP, obd_fail_val); + ptlrpc_at_adj_service(req, lustre_msg_get_timeout(req->rq_repmsg)); + ptlrpc_at_adj_net_latency(req, + lustre_msg_get_service_time(req->rq_repmsg)); + rc = ptlrpc_check_status(req); + imp->imp_connect_error = rc; - /* Either we've been evicted, or the server has failed for - * some reason. Try to reconnect, and if that fails, punt to the - * upcall. */ - if ((rc == -ENOTCONN) || (rc == -ENODEV)) { - if (req->rq_send_state != LUSTRE_IMP_FULL || - imp->imp_obd->obd_no_recov || imp->imp_dlm_fake) { - RETURN(-ENOTCONN); + if (rc) { + /* + * Either we've been evicted, or the server has failed for + * some reason. Try to reconnect, and if that fails, punt to + * the upcall. + */ + if (ll_rpc_recoverable_error(rc)) { + if (req->rq_send_state != LUSTRE_IMP_FULL || + imp->imp_obd->obd_no_recov || imp->imp_dlm_fake) { + RETURN(rc); + } + ptlrpc_request_handle_notconn(req); + RETURN(rc); } - - ptlrpc_request_handle_notconn(req); - - RETURN(rc); + } else { + /* + * Let's look if server sent slv. Do it only for RPC with + * rc == 0. + */ + ldlm_cli_update_pool(req); } - /* Store transno in reqmsg for replay. */ + /* + * Store transno in reqmsg for replay. + */ req->rq_transno = lustre_msg_get_transno(req->rq_repmsg); lustre_msg_set_transno(req->rq_reqmsg, req->rq_transno); if (req->rq_import->imp_replayable) { spin_lock(&imp->imp_lock); - /* no point in adding already-committed requests to the replay - * list, we will just remove them immediately. b=9829 */ - if (req->rq_transno != 0 && - (req->rq_transno > + /* + * No point in adding already-committed requests to the replay + * list, we will just remove them immediately. b=9829 + */ + if (req->rq_transno != 0 && + (req->rq_transno > lustre_msg_get_last_committed(req->rq_repmsg) || req->rq_replay)) ptlrpc_retain_replayable_request(req, imp); @@ -655,10 +1035,13 @@ static int after_reply(struct ptlrpc_request *req) spin_lock(&imp->imp_lock); } - /* Replay-enabled imports return commit-status information. */ - if (lustre_msg_get_last_committed(req->rq_repmsg)) + /* + * Replay-enabled imports return commit-status information. + */ + if (lustre_msg_get_last_committed(req->rq_repmsg)) { imp->imp_peer_committed_transno = lustre_msg_get_last_committed(req->rq_repmsg); + } ptlrpc_free_committed(imp); spin_unlock(&imp->imp_lock); } @@ -673,6 +1056,9 @@ static int ptlrpc_send_new_req(struct ptlrpc_request *req) ENTRY; LASSERT(req->rq_phase == RQ_PHASE_NEW); + if (req->rq_sent && (req->rq_sent > cfs_time_current_sec())) + RETURN (0); + req->rq_phase = RQ_PHASE_RPC; imp = req->rq_import; @@ -710,6 +1096,18 @@ static int ptlrpc_send_new_req(struct ptlrpc_request *req) spin_unlock(&imp->imp_lock); lustre_msg_set_status(req->rq_reqmsg, cfs_curproc_pid()); + + rc = sptlrpc_req_refresh_ctx(req, -1); + if (rc) { + if (req->rq_err) { + req->rq_status = rc; + RETURN(1); + } else { + req->rq_wait_ctx = 1; + RETURN(0); + } + } + CDEBUG(D_RPCTRACE, "Sending RPC pname:cluuid:pid:xid:nid:opc" " %s:%s:%d:"LPU64":%s:%d\n", cfs_curproc_comm(), imp->imp_obd->obd_uuid.uuid, @@ -727,7 +1125,7 @@ static int ptlrpc_send_new_req(struct ptlrpc_request *req) } /* this sends any unsent RPCs in @set and returns TRUE if all are sent */ -int ptlrpc_check_set(struct ptlrpc_request_set *set) +int ptlrpc_check_set(const struct lu_env *env, struct ptlrpc_request_set *set) { struct list_head *tmp; int force_timer_recalc = 0; @@ -746,6 +1144,9 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) ptlrpc_send_new_req(req)) { force_timer_recalc = 1; } + /* delayed send - skip */ + if (req->rq_phase == RQ_PHASE_NEW && req->rq_sent) + continue; if (!(req->rq_phase == RQ_PHASE_RPC || req->rq_phase == RQ_PHASE_BULK || @@ -766,6 +1167,7 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) if (req->rq_err) { ptlrpc_unregister_reply(req); + req->rq_replied = 0; if (req->rq_status == 0) req->rq_status = -EIO; req->rq_phase = RQ_PHASE_INTERPRET; @@ -782,7 +1184,8 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) * path sets rq_intr irrespective of whether ptlrpcd has * seen a timeout. our policy is to only interpret * interrupted rpcs after they have timed out */ - if (req->rq_intr && (req->rq_timedout || req->rq_waiting)) { + if (req->rq_intr && (req->rq_timedout || req->rq_waiting || + req->rq_wait_ctx)) { /* NB could be on delayed list */ ptlrpc_unregister_reply(req); req->rq_status = -EINTR; @@ -796,7 +1199,8 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) } if (req->rq_phase == RQ_PHASE_RPC) { - if (req->rq_timedout||req->rq_waiting||req->rq_resend) { + if (req->rq_timedout || req->rq_resend || + req->rq_waiting || req->rq_wait_ctx) { int status; ptlrpc_unregister_reply(req); @@ -815,7 +1219,7 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) spin_unlock(&imp->imp_lock); GOTO(interpret, req->rq_status); } - if (req->rq_no_resend) { + if (req->rq_no_resend && !req->rq_wait_ctx) { req->rq_status = -ENOTCONN; req->rq_phase = RQ_PHASE_INTERPRET; spin_unlock(&imp->imp_lock); @@ -827,7 +1231,11 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) spin_unlock(&imp->imp_lock); req->rq_waiting = 0; - if (req->rq_resend) { + + if (req->rq_timedout||req->rq_resend) { + /* This is re-sending anyways, + * let's mark req as resend. */ + req->rq_resend = 1; lustre_msg_add_flags(req->rq_reqmsg, MSG_RESENT); if (req->rq_bulk) { @@ -843,6 +1251,23 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) old_xid, req->rq_xid); } } + /* + * rq_wait_ctx is only touched by ptlrpcd, + * so no lock is needed here. + */ + status = sptlrpc_req_refresh_ctx(req, -1); + if (status) { + if (req->rq_err) { + req->rq_status = status; + force_timer_recalc = 1; + } else { + req->rq_wait_ctx = 1; + } + + continue; + } else { + req->rq_wait_ctx = 0; + } rc = ptl_send_rpc(req, 0); if (rc) { @@ -855,13 +1280,27 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) force_timer_recalc = 1; } + spin_lock(&req->rq_lock); + + if (req->rq_early) { + ptlrpc_at_recv_early_reply(req); + spin_unlock(&req->rq_lock); + continue; + } + /* Still waiting for a reply? */ - if (ptlrpc_client_receiving_reply(req)) + if (req->rq_receiving_reply) { + spin_unlock(&req->rq_lock); continue; + } /* Did we actually receive a reply? */ - if (!ptlrpc_client_replied(req)) + if (!req->rq_replied) { + spin_unlock(&req->rq_lock); continue; + } + + spin_unlock(&req->rq_lock); spin_lock(&imp->imp_lock); list_del_init(&req->rq_list); @@ -872,10 +1311,10 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) /* Add this req to the delayed list so it can be errored if the import is evicted after recovery. */ - spin_lock(&req->rq_lock); + spin_lock(&imp->imp_lock); list_add_tail(&req->rq_list, &imp->imp_delayed_list); - spin_unlock(&req->rq_lock); + spin_unlock(&imp->imp_lock); continue; } @@ -915,14 +1354,14 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) if (req->rq_bulk != NULL) ptlrpc_unregister_bulk (req); - req->rq_phase = RQ_PHASE_COMPLETE; - if (req->rq_interpret_reply != NULL) { - int (*interpreter)(struct ptlrpc_request *,void *,int) = + ptlrpc_interpterer_t interpreter = req->rq_interpret_reply; - req->rq_status = interpreter(req, &req->rq_async_args, + req->rq_status = interpreter(NULL, req, + &req->rq_async_args, req->rq_status); } + req->rq_phase = RQ_PHASE_COMPLETE; CDEBUG(D_RPCTRACE, "Completed RPC pname:cluuid:pid:xid:nid:" "opc %s:%s:%d:"LPU64":%s:%d\n", cfs_curproc_comm(), @@ -931,9 +1370,8 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) libcfs_nid2str(imp->imp_connection->c_peer.nid), lustre_msg_get_opc(req->rq_reqmsg)); - set->set_remaining--; - atomic_dec(&imp->imp_inflight); + set->set_remaining--; cfs_waitq_signal(&imp->imp_recovery_waitq); } @@ -941,14 +1379,28 @@ int ptlrpc_check_set(struct ptlrpc_request_set *set) RETURN(set->set_remaining == 0 || force_timer_recalc); } +/* Return 1 if we should give up, else 0 */ int ptlrpc_expire_one_request(struct ptlrpc_request *req) { struct obd_import *imp = req->rq_import; int rc = 0; ENTRY; - DEBUG_REQ(D_ERROR|D_NETERROR, req, "timeout (sent at %lu, %lus ago)", - (long)req->rq_sent, CURRENT_SECONDS - req->rq_sent); + DEBUG_REQ(D_ERROR|D_NETERROR, req, + "%s (sent at "CFS_TIME_T", "CFS_DURATION_T"s ago)", + req->rq_net_err ? "network error" : "timeout", + req->rq_sent, cfs_time_sub(cfs_time_current_sec(), + req->rq_sent)); + + if (imp) { + LCONSOLE_WARN("Request x"LPU64" sent from %s to NID %s " + CFS_DURATION_T"s ago has timed out " + "(limit "CFS_DURATION_T"s).\n", req->rq_xid, + req->rq_import->imp_obd->obd_name, + libcfs_nid2str(imp->imp_connection->c_peer.nid), + cfs_time_sub(cfs_time_current_sec(), req->rq_sent), + cfs_time_sub(req->rq_deadline, req->rq_sent)); + } if (imp != NULL && obd_debug_peer_on_timeout) LNetCtl(IOC_LIBCFS_DEBUG_PEER, &imp->imp_connection->c_peer); @@ -976,8 +1428,12 @@ int ptlrpc_expire_one_request(struct ptlrpc_request *req) /* If this request is for recovery or other primordial tasks, * then error it out here. */ - if (req->rq_send_state != LUSTRE_IMP_FULL || + if (req->rq_ctx_init || req->rq_ctx_fini || + req->rq_send_state != LUSTRE_IMP_FULL || imp->imp_obd->obd_no_recov) { + DEBUG_REQ(D_RPCTRACE, req, "err -110, sent_state=%s (now=%s)", + ptlrpc_import_state_name(req->rq_send_state), + ptlrpc_import_state_name(imp->imp_state)); spin_lock(&req->rq_lock); req->rq_status = -ETIMEDOUT; req->rq_err = 1; @@ -985,7 +1441,8 @@ int ptlrpc_expire_one_request(struct ptlrpc_request *req) RETURN(1); } - /* if request can't be resend we can't wait answer after timeout */ + /* if a request can't be resent we can't wait for an answer after + the timeout */ if (req->rq_no_resend) { DEBUG_REQ(D_RPCTRACE, req, "TIMEOUT-NORESEND:"); rc = 1; @@ -1000,7 +1457,7 @@ int ptlrpc_expired_set(void *data) { struct ptlrpc_request_set *set = data; struct list_head *tmp; - time_t now = CURRENT_SECONDS; + time_t now = cfs_time_current_sec(); ENTRY; LASSERT(set != NULL); @@ -1011,13 +1468,13 @@ int ptlrpc_expired_set(void *data) list_entry(tmp, struct ptlrpc_request, rq_set_chain); /* request in-flight? */ - if (!((req->rq_phase == RQ_PHASE_RPC && !req->rq_waiting && + if (!(((req->rq_phase == RQ_PHASE_RPC) && !req->rq_waiting && !req->rq_resend) || (req->rq_phase == RQ_PHASE_BULK))) continue; if (req->rq_timedout || /* already dealt with */ - req->rq_sent + req->rq_timeout > now) /* not expired */ + req->rq_deadline > now) /* not expired */ continue; /* deal with this guy */ @@ -1057,13 +1514,14 @@ void ptlrpc_interrupted_set(void *data) } } +/* get the smallest timeout in the set; this does NOT set a timeout. */ int ptlrpc_set_next_timeout(struct ptlrpc_request_set *set) { struct list_head *tmp; - time_t now = CURRENT_SECONDS; - time_t deadline; + time_t now = cfs_time_current_sec(); int timeout = 0; struct ptlrpc_request *req; + int deadline; ENTRY; SIGNAL_MASK_ASSERT(); /* XXX BUG 1511 */ @@ -1073,13 +1531,21 @@ int ptlrpc_set_next_timeout(struct ptlrpc_request_set *set) /* request in-flight? */ if (!((req->rq_phase == RQ_PHASE_RPC && !req->rq_waiting) || - (req->rq_phase == RQ_PHASE_BULK))) + (req->rq_phase == RQ_PHASE_BULK) || + (req->rq_phase == RQ_PHASE_NEW))) continue; if (req->rq_timedout) /* already timed out */ continue; - deadline = req->rq_sent + req->rq_timeout; + if (req->rq_wait_ctx) /* waiting for ctx */ + continue; + + if (req->rq_phase == RQ_PHASE_NEW) + deadline = req->rq_sent; + else + deadline = req->rq_sent + req->rq_timeout; + if (deadline <= now) /* actually expired already */ timeout = 1; /* ASAP */ else if (timeout == 0 || timeout > deadline - now) @@ -1110,12 +1576,13 @@ int ptlrpc_set_wait(struct ptlrpc_request_set *set) /* wait until all complete, interrupted, or an in-flight * req times out */ - CDEBUG(D_HA, "set %p going to sleep for %d seconds\n", + CDEBUG(D_RPCTRACE, "set %p going to sleep for %d seconds\n", set, timeout); lwi = LWI_TIMEOUT_INTR(cfs_time_seconds(timeout ? timeout : 1), ptlrpc_expired_set, ptlrpc_interrupted_set, set); - rc = l_wait_event(set->set_waitq, ptlrpc_check_set(set), &lwi); + rc = l_wait_event(set->set_waitq, + ptlrpc_check_set(NULL, set), &lwi); LASSERT(rc == 0 || rc == -EINTR || rc == -ETIMEDOUT); @@ -1143,20 +1610,23 @@ int ptlrpc_set_wait(struct ptlrpc_request_set *set) int (*interpreter)(struct ptlrpc_request_set *set,void *,int) = set->set_interpret; rc = interpreter (set, set->set_arg, rc); + } else { + struct ptlrpc_set_cbdata *cbdata, *n; + int err; + + list_for_each_entry_safe(cbdata, n, + &set->set_cblist, psc_item) { + list_del_init(&cbdata->psc_item); + err = cbdata->psc_interpret(set, cbdata->psc_data, rc); + if (err && !rc) + rc = err; + OBD_FREE_PTR(cbdata); + } } RETURN(rc); } -static void __ptlrpc_free_req_to_pool(struct ptlrpc_request *request) -{ - struct ptlrpc_request_pool *pool = request->rq_pool; - - spin_lock(&pool->prp_lock); - list_add_tail(&request->rq_list, &pool->prp_req_list); - spin_unlock(&pool->prp_lock); -} - static void __ptlrpc_free_req(struct ptlrpc_request *request, int locked) { ENTRY; @@ -1169,12 +1639,17 @@ static void __ptlrpc_free_req(struct ptlrpc_request *request, int locked) LASSERTF(request->rq_rqbd == NULL, "req %p\n",request);/* client-side */ LASSERTF(list_empty(&request->rq_list), "req %p\n", request); LASSERTF(list_empty(&request->rq_set_chain), "req %p\n", request); + LASSERTF(!request->rq_replay, "req %p\n", request); + LASSERT(request->rq_cli_ctx); + + req_capsule_fini(&request->rq_pill); /* We must take it off the imp_replay_list first. Otherwise, we'll set * request->rq_reqmsg to NULL while osc_close is dereferencing it. */ if (request->rq_import != NULL) { if (!locked) spin_lock(&request->rq_import->imp_lock); + list_del_init(&request->rq_mod_list); list_del_init(&request->rq_replay_list); if (!locked) spin_unlock(&request->rq_import->imp_lock); @@ -1187,10 +1662,8 @@ static void __ptlrpc_free_req(struct ptlrpc_request *request, int locked) LBUG(); } - if (request->rq_repmsg != NULL) { - OBD_FREE(request->rq_repmsg, request->rq_replen); - request->rq_repmsg = NULL; - } + if (request->rq_repbuf != NULL) + sptlrpc_cli_free_repbuf(request); if (request->rq_export != NULL) { class_export_put(request->rq_export); request->rq_export = NULL; @@ -1202,23 +1675,18 @@ static void __ptlrpc_free_req(struct ptlrpc_request *request, int locked) if (request->rq_bulk != NULL) ptlrpc_free_bulk(request->rq_bulk); - if (request->rq_pool) { + if (request->rq_reqbuf != NULL || request->rq_clrbuf != NULL) + sptlrpc_cli_free_reqbuf(request); + + sptlrpc_req_put_ctx(request, !locked); + + if (request->rq_pool) __ptlrpc_free_req_to_pool(request); - } else { - if (request->rq_reqmsg != NULL) { - OBD_FREE(request->rq_reqmsg, request->rq_reqlen); - request->rq_reqmsg = NULL; - } + else OBD_FREE(request, sizeof(*request)); - } EXIT; } -void ptlrpc_free_req(struct ptlrpc_request *request) -{ - __ptlrpc_free_req(request, 0); -} - static int __ptlrpc_req_finished(struct ptlrpc_request *request, int locked); void ptlrpc_req_finished_with_imp_lock(struct ptlrpc_request *request) { @@ -1271,17 +1739,17 @@ void ptlrpc_unregister_reply (struct ptlrpc_request *request) int rc; cfs_waitq_t *wq; struct l_wait_info lwi; - ENTRY; LASSERT(!in_interrupt ()); /* might sleep */ - - if (!ptlrpc_client_receiving_reply(request)) + if (!ptlrpc_client_recv_or_unlink(request)) + /* Nothing left to do */ return; LNetMDUnlink (request->rq_reply_md_h); /* We have to l_wait_event() whatever the result, to give liblustre - * a chance to run reply_in_callback() */ + * a chance to run reply_in_callback(), and to make sure we've + * unlinked before returning a req to the pool */ if (request->rq_set != NULL) wq = &request->rq_set->set_waitq; @@ -1291,15 +1759,17 @@ void ptlrpc_unregister_reply (struct ptlrpc_request *request) for (;;) { /* Network access will complete in finite time but the HUGE * timeout lets us CWARN for visibility of sluggish NALs */ - lwi = LWI_TIMEOUT(cfs_time_seconds(300), NULL, NULL); - rc = l_wait_event (*wq, !ptlrpc_client_receiving_reply(request), &lwi); + lwi = LWI_TIMEOUT(cfs_time_seconds(LONG_UNLINK), NULL, NULL); + rc = l_wait_event (*wq, !ptlrpc_client_recv_or_unlink(request), + &lwi); if (rc == 0) return; LASSERT (rc == -ETIMEDOUT); - DEBUG_REQ(D_WARNING, request, "Unexpectedly long timeout"); + DEBUG_REQ(D_WARNING, request, "Unexpectedly long timeout " + "rvcng=%d unlnk=%d", request->rq_receiving_reply, + request->rq_must_unlink); } - EXIT; } /* caller must hold imp->imp_lock */ @@ -1317,12 +1787,13 @@ void ptlrpc_free_committed(struct obd_import *imp) if (imp->imp_peer_committed_transno == imp->imp_last_transno_checked && imp->imp_generation == imp->imp_last_generation_checked) { - CDEBUG(D_HA, "%s: skip recheck for last_committed "LPU64"\n", + CDEBUG(D_RPCTRACE, "%s: skip recheck: last_committed "LPU64"\n", imp->imp_obd->obd_name, imp->imp_peer_committed_transno); + EXIT; return; } - - CDEBUG(D_HA, "%s: committing for last_committed "LPU64" gen %d\n", + + CDEBUG(D_RPCTRACE, "%s: committing for last_committed "LPU64" gen %d\n", imp->imp_obd->obd_name, imp->imp_peer_committed_transno, imp->imp_generation); imp->imp_last_transno_checked = imp->imp_peer_committed_transno; @@ -1336,22 +1807,22 @@ void ptlrpc_free_committed(struct obd_import *imp) last_req = req; if (req->rq_import_generation < imp->imp_generation) { - DEBUG_REQ(D_HA, req, "freeing request with old gen"); + DEBUG_REQ(D_RPCTRACE, req, "free request with old gen"); GOTO(free_req, 0); } if (req->rq_replay) { - DEBUG_REQ(D_HA, req, "keeping (FL_REPLAY)"); + DEBUG_REQ(D_RPCTRACE, req, "keeping (FL_REPLAY)"); continue; } /* not yet committed */ if (req->rq_transno > imp->imp_peer_committed_transno) { - DEBUG_REQ(D_HA, req, "stopping search"); + DEBUG_REQ(D_RPCTRACE, req, "stopping search"); break; } - DEBUG_REQ(D_HA, req, "committing (last_committed "LPU64")", + DEBUG_REQ(D_RPCTRACE, req, "commit (last_committed "LPU64")", imp->imp_peer_committed_transno); free_req: spin_lock(&req->rq_lock); @@ -1414,6 +1885,14 @@ static int expired_request(void *data) struct ptlrpc_request *req = data; ENTRY; + /* some failure can suspend regular timeouts */ + if (ptlrpc_check_suspend()) + RETURN(1); + + /* deadline may have changed with an early reply */ + if (req->rq_deadline > cfs_time_current_sec()) + RETURN(1); + RETURN(ptlrpc_expire_one_request(req)); } @@ -1485,7 +1964,8 @@ int ptlrpc_queue_wait(struct ptlrpc_request *req) int brc; struct l_wait_info lwi; struct obd_import *imp = req->rq_import; - cfs_duration_t timeout = 0; + cfs_duration_t timeout = CFS_TICK; + long timeoutl; ENTRY; LASSERT(req->rq_set == NULL); @@ -1533,6 +2013,7 @@ restart: list_del_init(&req->rq_list); if (req->rq_err) { + /* rq_status was set locally */ rc = -EIO; } else if (req->rq_intr) { @@ -1578,19 +2059,41 @@ restart: list_add_tail(&req->rq_list, &imp->imp_sending_list); spin_unlock(&imp->imp_lock); - rc = ptl_send_rpc(req, 0); + rc = sptlrpc_req_refresh_ctx(req, 0); if (rc) { - DEBUG_REQ(D_HA, req, "send failed (%d); recovering", rc); - timeout = CFS_TICK; - } else { - timeout = cfs_timeout_cap(cfs_time_seconds(req->rq_timeout)); - DEBUG_REQ(D_NET, req, - "-- sleeping for "CFS_DURATION_T" jiffies", timeout); + if (req->rq_err) { + /* we got fatal ctx refresh error, directly jump out + * thus we can pass back the actual error code. + */ + spin_lock(&imp->imp_lock); + list_del_init(&req->rq_list); + spin_unlock(&imp->imp_lock); + + CERROR("Failed to refresh ctx of req %p: %d\n", req, rc); + GOTO(out, rc); + } + /* simulating we got error during send rpc */ + goto after_send; } + + rc = ptl_send_rpc(req, 0); + if (rc) + DEBUG_REQ(D_HA, req, "send failed (%d); recovering", rc); + +repeat: + timeoutl = req->rq_deadline - cfs_time_current_sec(); + timeout = (timeoutl <= 0 || rc) ? CFS_TICK : + cfs_time_seconds(timeoutl); + DEBUG_REQ(D_NET, req, + "-- sleeping for "CFS_DURATION_T" ticks", timeout); lwi = LWI_TIMEOUT_INTR(timeout, expired_request, interrupted_request, req); - l_wait_event(req->rq_reply_waitq, ptlrpc_check_reply(req), &lwi); + brc = l_wait_event(req->rq_reply_waitq, ptlrpc_check_reply(req), &lwi); + if (brc == -ETIMEDOUT && ((req->rq_deadline > cfs_time_current_sec()) || + ptlrpc_check_and_wait_suspend(req))) + goto repeat; +after_send: CDEBUG(D_RPCTRACE, "Completed RPC pname:cluuid:pid:xid:nid:opc " "%s:%s:%d:"LPU64":%s:%d\n", cfs_curproc_comm(), imp->imp_obd->obd_uuid.uuid, @@ -1607,16 +2110,11 @@ restart: * req->rq_receiving_reply is clear and returns. */ ptlrpc_unregister_reply (req); - if (req->rq_err) - GOTO(out, rc = -EIO); - /* Resend if we need to, unless we were interrupted. */ - if (req->rq_resend && !req->rq_intr) { - /* ...unless we were specifically told otherwise. */ - if (req->rq_no_resend) - GOTO(out, rc = -ETIMEDOUT); - spin_lock(&imp->imp_lock); - goto restart; + if (req->rq_err) { + DEBUG_REQ(D_RPCTRACE, req, "err rc=%d status=%d", + rc, req->rq_status); + GOTO(out, rc = rc ? rc : -EIO); } if (req->rq_intr) { @@ -1627,6 +2125,15 @@ restart: GOTO(out, rc = -EINTR); } + /* Resend if we need to */ + if (req->rq_resend) { + /* ...unless we were specifically told otherwise. */ + if (req->rq_no_resend) + GOTO(out, rc = -ETIMEDOUT); + spin_lock(&imp->imp_lock); + goto restart; + } + if (req->rq_timedout) { /* non-recoverable timeout */ GOTO(out, rc = -ETIMEDOUT); } @@ -1684,7 +2191,8 @@ struct ptlrpc_replay_async_args { int praa_old_status; }; -static int ptlrpc_replay_interpret(struct ptlrpc_request *req, +static int ptlrpc_replay_interpret(const struct lu_env *env, + struct ptlrpc_request *req, void * data, int rc) { struct ptlrpc_replay_async_args *aa = data; @@ -1699,7 +2207,8 @@ static int ptlrpc_replay_interpret(struct ptlrpc_request *req, } if (lustre_msg_get_type(req->rq_repmsg) == PTL_RPC_MSG_ERR && - lustre_msg_get_status(req->rq_repmsg) == -ENOTCONN) + (lustre_msg_get_status(req->rq_repmsg) == -ENOTCONN || + lustre_msg_get_status(req->rq_repmsg) == -ENODEV)) GOTO(out, rc = lustre_msg_get_status(req->rq_repmsg)); /* The transno had better not change over replay. */ @@ -1722,10 +2231,17 @@ static int ptlrpc_replay_interpret(struct ptlrpc_request *req, lustre_msg_set_status(req->rq_repmsg, aa->praa_old_status); } - spin_lock(&imp->imp_lock); - imp->imp_last_replay_transno = req->rq_transno; - spin_unlock(&imp->imp_lock); - + /* + * Errors while replay can set transno to 0, but + * imp_last_replay_transno shouldn't be set to 0 anyway + */ + if (req->rq_transno > 0) { + spin_lock(&imp->imp_lock); + LASSERT(req->rq_transno <= imp->imp_last_replay_transno); + imp->imp_last_replay_transno = req->rq_transno; + spin_unlock(&imp->imp_lock); + } else + CERROR("Transno is 0 during replay!\n"); /* continue with recovery */ rc = ptlrpc_import_recovery_state_machine(imp); out: @@ -1738,31 +2254,32 @@ static int ptlrpc_replay_interpret(struct ptlrpc_request *req, RETURN(rc); } - int ptlrpc_replay_req(struct ptlrpc_request *req) { struct ptlrpc_replay_async_args *aa; ENTRY; LASSERT(req->rq_import->imp_state == LUSTRE_IMP_REPLAY); - /* Not handling automatic bulk replay yet (or ever?) */ LASSERT(req->rq_bulk == NULL); - DEBUG_REQ(D_HA, req, "REPLAY"); - LASSERT (sizeof (*aa) <= sizeof (req->rq_async_args)); - aa = (struct ptlrpc_replay_async_args *)&req->rq_async_args; + aa = ptlrpc_req_async_args(req); memset(aa, 0, sizeof *aa); /* Prepare request to be resent with ptlrpcd */ aa->praa_old_state = req->rq_send_state; req->rq_send_state = LUSTRE_IMP_REPLAY; req->rq_phase = RQ_PHASE_NEW; - aa->praa_old_status = lustre_msg_get_status(req->rq_repmsg); + if (req->rq_repmsg) + aa->praa_old_status = lustre_msg_get_status(req->rq_repmsg); req->rq_status = 0; - req->rq_interpret_reply = ptlrpc_replay_interpret; + /* Readjust the timeout for current conditions */ + ptlrpc_at_set_req_timeout(req); + + DEBUG_REQ(D_HA, req, "REPLAY"); + atomic_inc(&req->rq_import->imp_replay_inflight); ptlrpc_request_addref(req); /* ptlrpcd needs a ref */ @@ -1789,11 +2306,12 @@ void ptlrpc_abort_inflight(struct obd_import *imp) struct ptlrpc_request *req = list_entry(tmp, struct ptlrpc_request, rq_list); - DEBUG_REQ(D_HA, req, "inflight"); + DEBUG_REQ(D_RPCTRACE, req, "inflight"); spin_lock (&req->rq_lock); if (req->rq_import_generation < imp->imp_generation) { req->rq_err = 1; + req->rq_status = -EINTR; ptlrpc_wake_client_req(req); } spin_unlock (&req->rq_lock); @@ -1803,11 +2321,12 @@ void ptlrpc_abort_inflight(struct obd_import *imp) struct ptlrpc_request *req = list_entry(tmp, struct ptlrpc_request, rq_list); - DEBUG_REQ(D_HA, req, "aborting waiting req"); + DEBUG_REQ(D_RPCTRACE, req, "aborting waiting req"); spin_lock (&req->rq_lock); if (req->rq_import_generation < imp->imp_generation) { req->rq_err = 1; + req->rq_status = -EINTR; ptlrpc_wake_client_req(req); } spin_unlock (&req->rq_lock); @@ -1823,8 +2342,60 @@ void ptlrpc_abort_inflight(struct obd_import *imp) EXIT; } -static __u64 ptlrpc_last_xid = 0; -spinlock_t ptlrpc_last_xid_lock; +void ptlrpc_abort_set(struct ptlrpc_request_set *set) +{ + struct list_head *tmp, *n; + + LASSERT(set != NULL); + + list_for_each_safe(tmp, n, &set->set_requests) { + struct ptlrpc_request *req = + list_entry(tmp, struct ptlrpc_request, rq_set_chain); + + spin_lock (&req->rq_lock); + if (req->rq_phase != RQ_PHASE_RPC) { + spin_unlock (&req->rq_lock); + continue; + } + + req->rq_err = 1; + req->rq_status = -EINTR; + ptlrpc_wake_client_req(req); + spin_unlock (&req->rq_lock); + } +} + +static __u64 ptlrpc_last_xid; +static spinlock_t ptlrpc_last_xid_lock; + +/* Initialize the XID for the node. This is common among all requests on + * this node, and only requires the property that it is monotonically + * increasing. It does not need to be sequential. Since this is also used + * as the RDMA match bits, it is important that a single client NOT have + * the same match bits for two different in-flight requests, hence we do + * NOT want to have an XID per target or similar. + * + * To avoid an unlikely collision between match bits after a client reboot + * (which would cause old to be delivered into the wrong buffer) we initialize + * the XID based on the current time, assuming a maximum RPC rate of 1M RPC/s. + * If the time is clearly incorrect, we instead use a 62-bit random number. + * In the worst case the random number will overflow 1M RPCs per second in + * 9133 years, or permutations thereof. + */ +#define YEAR_2004 (1ULL << 30) +void ptlrpc_init_xid(void) +{ + time_t now = cfs_time_current_sec(); + + spin_lock_init(&ptlrpc_last_xid_lock); + if (now < YEAR_2004) { + ll_get_random_bytes(&ptlrpc_last_xid, sizeof(ptlrpc_last_xid)); + ptlrpc_last_xid >>= 2; + ptlrpc_last_xid |= (1ULL << 61); + } else { + ptlrpc_last_xid = (now << 20); + } +} __u64 ptlrpc_next_xid(void) { @@ -1837,10 +2408,16 @@ __u64 ptlrpc_next_xid(void) __u64 ptlrpc_sample_next_xid(void) { +#if BITS_PER_LONG == 32 + /* need to avoid possible word tearing on 32-bit systems */ __u64 tmp; spin_lock(&ptlrpc_last_xid_lock); tmp = ptlrpc_last_xid + 1; spin_unlock(&ptlrpc_last_xid_lock); return tmp; +#else + /* No need to lock, since returned value is racy anyways */ + return ptlrpc_last_xid + 1; +#endif } EXPORT_SYMBOL(ptlrpc_sample_next_xid);