blob: 76887d60f0c0f7adff0a75054514c240a5403b62 [file] [log] [blame]
Greg Kroah-Hartmanb2441312017-11-01 15:07:57 +01001/* SPDX-License-Identifier: GPL-2.0 */
Trond Myklebust82b0a4c2012-01-20 14:52:23 -05002#undef TRACE_SYSTEM
3#define TRACE_SYSTEM sunrpc
4
5#if !defined(_TRACE_SUNRPC_H) || defined(TRACE_HEADER_MULTI_READ)
6#define _TRACE_SUNRPC_H
7
8#include <linux/sunrpc/sched.h>
9#include <linux/sunrpc/clnt.h>
Jeff Layton860a0d92014-10-28 14:24:12 -040010#include <linux/sunrpc/svc.h>
Jeff Layton1a867a02014-10-28 14:24:14 -040011#include <linux/sunrpc/xprtsock.h>
Jeff Layton83a712e2014-11-21 14:19:31 -050012#include <linux/sunrpc/svc_xprt.h>
Trond Myklebust40b5ea02013-09-04 12:16:23 -040013#include <net/tcp_states.h>
14#include <linux/net.h>
Trond Myklebust82b0a4c2012-01-20 14:52:23 -050015#include <linux/tracepoint.h>
16
Steve Dickson5753cba2012-02-06 10:08:08 -050017DECLARE_EVENT_CLASS(rpc_task_status,
18
19 TP_PROTO(struct rpc_task *task),
20
21 TP_ARGS(task),
22
23 TP_STRUCT__entry(
Trond Myklebust92cb6c52013-09-04 22:09:50 -040024 __field(unsigned int, task_id)
25 __field(unsigned int, client_id)
Steve Dickson5753cba2012-02-06 10:08:08 -050026 __field(int, status)
27 ),
28
29 TP_fast_assign(
Trond Myklebust92cb6c52013-09-04 22:09:50 -040030 __entry->task_id = task->tk_pid;
31 __entry->client_id = task->tk_client->cl_clid;
Steve Dickson5753cba2012-02-06 10:08:08 -050032 __entry->status = task->tk_status;
33 ),
34
Chuck Lever52069442018-01-03 15:38:33 -050035 TP_printk("task:%u@%u status=%d",
Trond Myklebust92cb6c52013-09-04 22:09:50 -040036 __entry->task_id, __entry->client_id,
37 __entry->status)
Steve Dickson5753cba2012-02-06 10:08:08 -050038);
39
40DEFINE_EVENT(rpc_task_status, rpc_call_status,
41 TP_PROTO(struct rpc_task *task),
42
43 TP_ARGS(task)
44);
45
46DEFINE_EVENT(rpc_task_status, rpc_bind_status,
47 TP_PROTO(struct rpc_task *task),
48
49 TP_ARGS(task)
50);
51
52TRACE_EVENT(rpc_connect_status,
Chuck Levere671edb2018-03-16 10:33:44 -040053 TP_PROTO(const struct rpc_task *task),
Steve Dickson5753cba2012-02-06 10:08:08 -050054
Chuck Levere671edb2018-03-16 10:33:44 -040055 TP_ARGS(task),
Steve Dickson5753cba2012-02-06 10:08:08 -050056
57 TP_STRUCT__entry(
Trond Myklebust92cb6c52013-09-04 22:09:50 -040058 __field(unsigned int, task_id)
59 __field(unsigned int, client_id)
Steve Dickson5753cba2012-02-06 10:08:08 -050060 __field(int, status)
61 ),
62
63 TP_fast_assign(
Trond Myklebust92cb6c52013-09-04 22:09:50 -040064 __entry->task_id = task->tk_pid;
65 __entry->client_id = task->tk_client->cl_clid;
Chuck Levere671edb2018-03-16 10:33:44 -040066 __entry->status = task->tk_status;
Steve Dickson5753cba2012-02-06 10:08:08 -050067 ),
68
Chuck Lever52069442018-01-03 15:38:33 -050069 TP_printk("task:%u@%u status=%d",
Trond Myklebust92cb6c52013-09-04 22:09:50 -040070 __entry->task_id, __entry->client_id,
71 __entry->status)
Steve Dickson5753cba2012-02-06 10:08:08 -050072);
73
Chuck Leverc435da62017-11-03 13:46:14 -040074TRACE_EVENT(rpc_request,
75 TP_PROTO(const struct rpc_task *task),
76
77 TP_ARGS(task),
78
79 TP_STRUCT__entry(
80 __field(unsigned int, task_id)
81 __field(unsigned int, client_id)
82 __field(int, version)
83 __field(bool, async)
84 __string(progname, task->tk_client->cl_program->name)
85 __string(procname, rpc_proc_name(task))
86 ),
87
88 TP_fast_assign(
89 __entry->task_id = task->tk_pid;
90 __entry->client_id = task->tk_client->cl_clid;
91 __entry->version = task->tk_client->cl_vers;
92 __entry->async = RPC_IS_ASYNC(task);
93 __assign_str(progname, task->tk_client->cl_program->name)
94 __assign_str(procname, rpc_proc_name(task))
95 ),
96
97 TP_printk("task:%u@%u %sv%d %s (%ssync)",
98 __entry->task_id, __entry->client_id,
99 __get_str(progname), __entry->version,
100 __get_str(procname), __entry->async ? "a": ""
101 )
102);
103
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500104DECLARE_EVENT_CLASS(rpc_task_running,
105
Chuck Levere671edb2018-03-16 10:33:44 -0400106 TP_PROTO(const struct rpc_task *task, const void *action),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500107
Chuck Levere671edb2018-03-16 10:33:44 -0400108 TP_ARGS(task, action),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500109
110 TP_STRUCT__entry(
Trond Myklebust92cb6c52013-09-04 22:09:50 -0400111 __field(unsigned int, task_id)
112 __field(unsigned int, client_id)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500113 __field(const void *, action)
114 __field(unsigned long, runstate)
115 __field(int, status)
116 __field(unsigned short, flags)
117 ),
118
119 TP_fast_assign(
Chuck Levere671edb2018-03-16 10:33:44 -0400120 __entry->client_id = task->tk_client ?
121 task->tk_client->cl_clid : -1;
Trond Myklebust92cb6c52013-09-04 22:09:50 -0400122 __entry->task_id = task->tk_pid;
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500123 __entry->action = action;
124 __entry->runstate = task->tk_runstate;
125 __entry->status = task->tk_status;
126 __entry->flags = task->tk_flags;
127 ),
128
Ditang Chen2ca310f2014-03-07 13:27:57 +0800129 TP_printk("task:%u@%d flags=%4.4x state=%4.4lx status=%d action=%pf",
Trond Myklebust92cb6c52013-09-04 22:09:50 -0400130 __entry->task_id, __entry->client_id,
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500131 __entry->flags,
132 __entry->runstate,
133 __entry->status,
134 __entry->action
135 )
136);
137
138DEFINE_EVENT(rpc_task_running, rpc_task_begin,
139
Chuck Levere671edb2018-03-16 10:33:44 -0400140 TP_PROTO(const struct rpc_task *task, const void *action),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500141
Chuck Levere671edb2018-03-16 10:33:44 -0400142 TP_ARGS(task, action)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500143
144);
145
146DEFINE_EVENT(rpc_task_running, rpc_task_run_action,
147
Chuck Levere671edb2018-03-16 10:33:44 -0400148 TP_PROTO(const struct rpc_task *task, const void *action),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500149
Chuck Levere671edb2018-03-16 10:33:44 -0400150 TP_ARGS(task, action)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500151
152);
153
154DEFINE_EVENT(rpc_task_running, rpc_task_complete,
155
Chuck Levere671edb2018-03-16 10:33:44 -0400156 TP_PROTO(const struct rpc_task *task, const void *action),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500157
Chuck Levere671edb2018-03-16 10:33:44 -0400158 TP_ARGS(task, action)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500159
160);
161
162DECLARE_EVENT_CLASS(rpc_task_queued,
163
Chuck Levere671edb2018-03-16 10:33:44 -0400164 TP_PROTO(const struct rpc_task *task, const struct rpc_wait_queue *q),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500165
Chuck Levere671edb2018-03-16 10:33:44 -0400166 TP_ARGS(task, q),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500167
168 TP_STRUCT__entry(
Trond Myklebust92cb6c52013-09-04 22:09:50 -0400169 __field(unsigned int, task_id)
170 __field(unsigned int, client_id)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500171 __field(unsigned long, timeout)
172 __field(unsigned long, runstate)
173 __field(int, status)
174 __field(unsigned short, flags)
Trond Myklebust85c0d242012-02-06 10:31:35 -0500175 __string(q_name, rpc_qname(q))
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500176 ),
177
178 TP_fast_assign(
Chuck Levere671edb2018-03-16 10:33:44 -0400179 __entry->client_id = task->tk_client ?
180 task->tk_client->cl_clid : -1;
Trond Myklebust92cb6c52013-09-04 22:09:50 -0400181 __entry->task_id = task->tk_pid;
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500182 __entry->timeout = task->tk_timeout;
183 __entry->runstate = task->tk_runstate;
184 __entry->status = task->tk_status;
185 __entry->flags = task->tk_flags;
Trond Myklebust85c0d242012-02-06 10:31:35 -0500186 __assign_str(q_name, rpc_qname(q));
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500187 ),
188
Benjamin Coddington0be283f2018-01-23 09:32:35 -0500189 TP_printk("task:%u@%d flags=%4.4x state=%4.4lx status=%d timeout=%lu queue=%s",
Trond Myklebust92cb6c52013-09-04 22:09:50 -0400190 __entry->task_id, __entry->client_id,
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500191 __entry->flags,
192 __entry->runstate,
193 __entry->status,
194 __entry->timeout,
Trond Myklebust85c0d242012-02-06 10:31:35 -0500195 __get_str(q_name)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500196 )
197);
198
199DEFINE_EVENT(rpc_task_queued, rpc_task_sleep,
200
Chuck Levere671edb2018-03-16 10:33:44 -0400201 TP_PROTO(const struct rpc_task *task, const struct rpc_wait_queue *q),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500202
Chuck Levere671edb2018-03-16 10:33:44 -0400203 TP_ARGS(task, q)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500204
205);
206
207DEFINE_EVENT(rpc_task_queued, rpc_task_wakeup,
208
Chuck Levere671edb2018-03-16 10:33:44 -0400209 TP_PROTO(const struct rpc_task *task, const struct rpc_wait_queue *q),
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500210
Chuck Levere671edb2018-03-16 10:33:44 -0400211 TP_ARGS(task, q)
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500212
213);
214
Chuck Lever40bf7eb2018-03-16 10:33:49 -0400215TRACE_EVENT(rpc_stats_latency,
216
217 TP_PROTO(
218 const struct rpc_task *task,
219 ktime_t backlog,
220 ktime_t rtt,
221 ktime_t execute
222 ),
223
224 TP_ARGS(task, backlog, rtt, execute),
225
226 TP_STRUCT__entry(
227 __field(u32, xid)
228 __field(int, version)
229 __string(progname, task->tk_client->cl_program->name)
230 __string(procname, rpc_proc_name(task))
231 __field(unsigned long, backlog)
232 __field(unsigned long, rtt)
233 __field(unsigned long, execute)
234 __string(addr,
235 task->tk_xprt->address_strings[RPC_DISPLAY_ADDR])
236 __string(port,
237 task->tk_xprt->address_strings[RPC_DISPLAY_PORT])
238 ),
239
240 TP_fast_assign(
241 __entry->xid = be32_to_cpu(task->tk_rqstp->rq_xid);
242 __entry->version = task->tk_client->cl_vers;
243 __assign_str(progname, task->tk_client->cl_program->name)
244 __assign_str(procname, rpc_proc_name(task))
245 __entry->backlog = ktime_to_us(backlog);
246 __entry->rtt = ktime_to_us(rtt);
247 __entry->execute = ktime_to_us(execute);
248 __assign_str(addr,
249 task->tk_xprt->address_strings[RPC_DISPLAY_ADDR]);
250 __assign_str(port,
251 task->tk_xprt->address_strings[RPC_DISPLAY_PORT]);
252 ),
253
254 TP_printk("peer=[%s]:%s xid=0x%08x %sv%d %s backlog=%lu rtt=%lu execute=%lu",
255 __get_str(addr), __get_str(port), __entry->xid,
256 __get_str(progname), __entry->version, __get_str(procname),
257 __entry->backlog, __entry->rtt, __entry->execute)
258);
259
Steven Rostedt (Red Hat)6ba16ee2015-03-27 16:53:03 -0400260/*
261 * First define the enums in the below macros to be exported to userspace
262 * via TRACE_DEFINE_ENUM().
263 */
264#undef EM
265#undef EMe
266#define EM(a, b) TRACE_DEFINE_ENUM(a);
267#define EMe(a, b) TRACE_DEFINE_ENUM(a);
268
269#define RPC_SHOW_SOCKET \
270 EM( SS_FREE, "FREE" ) \
271 EM( SS_UNCONNECTED, "UNCONNECTED" ) \
272 EM( SS_CONNECTING, "CONNECTING," ) \
273 EM( SS_CONNECTED, "CONNECTED," ) \
274 EMe(SS_DISCONNECTING, "DISCONNECTING" )
275
Trond Myklebust40b5ea02013-09-04 12:16:23 -0400276#define rpc_show_socket_state(state) \
Steven Rostedt (Red Hat)6ba16ee2015-03-27 16:53:03 -0400277 __print_symbolic(state, RPC_SHOW_SOCKET)
278
279RPC_SHOW_SOCKET
280
281#define RPC_SHOW_SOCK \
282 EM( TCP_ESTABLISHED, "ESTABLISHED" ) \
283 EM( TCP_SYN_SENT, "SYN_SENT" ) \
284 EM( TCP_SYN_RECV, "SYN_RECV" ) \
285 EM( TCP_FIN_WAIT1, "FIN_WAIT1" ) \
286 EM( TCP_FIN_WAIT2, "FIN_WAIT2" ) \
287 EM( TCP_TIME_WAIT, "TIME_WAIT" ) \
288 EM( TCP_CLOSE, "CLOSE" ) \
289 EM( TCP_CLOSE_WAIT, "CLOSE_WAIT" ) \
290 EM( TCP_LAST_ACK, "LAST_ACK" ) \
291 EM( TCP_LISTEN, "LISTEN" ) \
292 EMe( TCP_CLOSING, "CLOSING" )
Trond Myklebust40b5ea02013-09-04 12:16:23 -0400293
294#define rpc_show_sock_state(state) \
Steven Rostedt (Red Hat)6ba16ee2015-03-27 16:53:03 -0400295 __print_symbolic(state, RPC_SHOW_SOCK)
296
297RPC_SHOW_SOCK
298
299/*
300 * Now redefine the EM() and EMe() macros to map the enums to the strings
301 * that will be printed in the output.
302 */
303#undef EM
304#undef EMe
305#define EM(a, b) {a, b},
306#define EMe(a, b) {a, b}
Trond Myklebust40b5ea02013-09-04 12:16:23 -0400307
308DECLARE_EVENT_CLASS(xs_socket_event,
309
310 TP_PROTO(
311 struct rpc_xprt *xprt,
312 struct socket *socket
313 ),
314
315 TP_ARGS(xprt, socket),
316
317 TP_STRUCT__entry(
318 __field(unsigned int, socket_state)
319 __field(unsigned int, sock_state)
320 __field(unsigned long long, ino)
321 __string(dstaddr,
322 xprt->address_strings[RPC_DISPLAY_ADDR])
323 __string(dstport,
324 xprt->address_strings[RPC_DISPLAY_PORT])
325 ),
326
327 TP_fast_assign(
328 struct inode *inode = SOCK_INODE(socket);
329 __entry->socket_state = socket->state;
330 __entry->sock_state = socket->sk->sk_state;
331 __entry->ino = (unsigned long long)inode->i_ino;
332 __assign_str(dstaddr,
333 xprt->address_strings[RPC_DISPLAY_ADDR]);
334 __assign_str(dstport,
335 xprt->address_strings[RPC_DISPLAY_PORT]);
336 ),
337
338 TP_printk(
339 "socket:[%llu] dstaddr=%s/%s "
340 "state=%u (%s) sk_state=%u (%s)",
341 __entry->ino, __get_str(dstaddr), __get_str(dstport),
342 __entry->socket_state,
343 rpc_show_socket_state(__entry->socket_state),
344 __entry->sock_state,
345 rpc_show_sock_state(__entry->sock_state)
346 )
347);
348#define DEFINE_RPC_SOCKET_EVENT(name) \
349 DEFINE_EVENT(xs_socket_event, name, \
350 TP_PROTO( \
351 struct rpc_xprt *xprt, \
352 struct socket *socket \
353 ), \
354 TP_ARGS(xprt, socket))
355
356DECLARE_EVENT_CLASS(xs_socket_event_done,
357
358 TP_PROTO(
359 struct rpc_xprt *xprt,
360 struct socket *socket,
361 int error
362 ),
363
364 TP_ARGS(xprt, socket, error),
365
366 TP_STRUCT__entry(
367 __field(int, error)
368 __field(unsigned int, socket_state)
369 __field(unsigned int, sock_state)
370 __field(unsigned long long, ino)
371 __string(dstaddr,
372 xprt->address_strings[RPC_DISPLAY_ADDR])
373 __string(dstport,
374 xprt->address_strings[RPC_DISPLAY_PORT])
375 ),
376
377 TP_fast_assign(
378 struct inode *inode = SOCK_INODE(socket);
379 __entry->socket_state = socket->state;
380 __entry->sock_state = socket->sk->sk_state;
381 __entry->ino = (unsigned long long)inode->i_ino;
382 __entry->error = error;
383 __assign_str(dstaddr,
384 xprt->address_strings[RPC_DISPLAY_ADDR]);
385 __assign_str(dstport,
386 xprt->address_strings[RPC_DISPLAY_PORT]);
387 ),
388
389 TP_printk(
390 "error=%d socket:[%llu] dstaddr=%s/%s "
391 "state=%u (%s) sk_state=%u (%s)",
392 __entry->error,
393 __entry->ino, __get_str(dstaddr), __get_str(dstport),
394 __entry->socket_state,
395 rpc_show_socket_state(__entry->socket_state),
396 __entry->sock_state,
397 rpc_show_sock_state(__entry->sock_state)
398 )
399);
400#define DEFINE_RPC_SOCKET_EVENT_DONE(name) \
401 DEFINE_EVENT(xs_socket_event_done, name, \
402 TP_PROTO( \
403 struct rpc_xprt *xprt, \
404 struct socket *socket, \
405 int error \
406 ), \
407 TP_ARGS(xprt, socket, error))
408
409DEFINE_RPC_SOCKET_EVENT(rpc_socket_state_change);
410DEFINE_RPC_SOCKET_EVENT_DONE(rpc_socket_connect);
Trond Myklebuste8353c762013-12-31 13:39:22 -0500411DEFINE_RPC_SOCKET_EVENT_DONE(rpc_socket_error);
Trond Myklebust40b5ea02013-09-04 12:16:23 -0400412DEFINE_RPC_SOCKET_EVENT_DONE(rpc_socket_reset_connection);
413DEFINE_RPC_SOCKET_EVENT(rpc_socket_close);
414DEFINE_RPC_SOCKET_EVENT(rpc_socket_shutdown);
415
Jeff Layton3705ad62014-10-28 14:24:13 -0400416DECLARE_EVENT_CLASS(rpc_xprt_event,
417 TP_PROTO(struct rpc_xprt *xprt, __be32 xid, int status),
418
419 TP_ARGS(xprt, xid, status),
420
421 TP_STRUCT__entry(
Chuck Levera30ccf12017-10-20 10:35:18 -0400422 __field(u32, xid)
Jeff Layton3705ad62014-10-28 14:24:13 -0400423 __field(int, status)
424 __string(addr, xprt->address_strings[RPC_DISPLAY_ADDR])
425 __string(port, xprt->address_strings[RPC_DISPLAY_PORT])
426 ),
427
428 TP_fast_assign(
Chuck Levera30ccf12017-10-20 10:35:18 -0400429 __entry->xid = be32_to_cpu(xid);
Jeff Layton3705ad62014-10-28 14:24:13 -0400430 __entry->status = status;
431 __assign_str(addr, xprt->address_strings[RPC_DISPLAY_ADDR]);
432 __assign_str(port, xprt->address_strings[RPC_DISPLAY_PORT]);
433 ),
434
Chuck Levera30ccf12017-10-20 10:35:18 -0400435 TP_printk("peer=[%s]:%s xid=0x%08x status=%d", __get_str(addr),
436 __get_str(port), __entry->xid,
Jeff Layton3705ad62014-10-28 14:24:13 -0400437 __entry->status)
438);
439
Chuck Lever82476d92018-01-03 15:38:25 -0500440DEFINE_EVENT(rpc_xprt_event, xprt_timer,
441 TP_PROTO(struct rpc_xprt *xprt, __be32 xid, int status),
442 TP_ARGS(xprt, xid, status));
443
Jeff Layton3705ad62014-10-28 14:24:13 -0400444DEFINE_EVENT(rpc_xprt_event, xprt_lookup_rqst,
445 TP_PROTO(struct rpc_xprt *xprt, __be32 xid, int status),
446 TP_ARGS(xprt, xid, status));
447
448DEFINE_EVENT(rpc_xprt_event, xprt_transmit,
449 TP_PROTO(struct rpc_xprt *xprt, __be32 xid, int status),
450 TP_ARGS(xprt, xid, status));
451
452DEFINE_EVENT(rpc_xprt_event, xprt_complete_rqst,
453 TP_PROTO(struct rpc_xprt *xprt, __be32 xid, int status),
454 TP_ARGS(xprt, xid, status));
455
Chuck Levera25a4cb2018-03-16 10:33:55 -0400456TRACE_EVENT(xprt_ping,
457 TP_PROTO(const struct rpc_xprt *xprt, int status),
458
459 TP_ARGS(xprt, status),
460
461 TP_STRUCT__entry(
462 __field(int, status)
463 __string(addr, xprt->address_strings[RPC_DISPLAY_ADDR])
464 __string(port, xprt->address_strings[RPC_DISPLAY_PORT])
465 ),
466
467 TP_fast_assign(
468 __entry->status = status;
469 __assign_str(addr, xprt->address_strings[RPC_DISPLAY_ADDR]);
470 __assign_str(port, xprt->address_strings[RPC_DISPLAY_PORT]);
471 ),
472
473 TP_printk("peer=[%s]:%s status=%d",
474 __get_str(addr), __get_str(port), __entry->status)
475);
476
Jeff Layton3705ad62014-10-28 14:24:13 -0400477TRACE_EVENT(xs_tcp_data_ready,
478 TP_PROTO(struct rpc_xprt *xprt, int err, unsigned int total),
479
480 TP_ARGS(xprt, err, total),
481
482 TP_STRUCT__entry(
483 __field(int, err)
484 __field(unsigned int, total)
485 __string(addr, xprt ? xprt->address_strings[RPC_DISPLAY_ADDR] :
486 "(null)")
487 __string(port, xprt ? xprt->address_strings[RPC_DISPLAY_PORT] :
488 "(null)")
489 ),
490
491 TP_fast_assign(
492 __entry->err = err;
493 __entry->total = total;
494 __assign_str(addr, xprt ?
495 xprt->address_strings[RPC_DISPLAY_ADDR] : "(null)");
496 __assign_str(port, xprt ?
497 xprt->address_strings[RPC_DISPLAY_PORT] : "(null)");
498 ),
499
500 TP_printk("peer=[%s]:%s err=%d total=%u", __get_str(addr),
501 __get_str(port), __entry->err, __entry->total)
502);
503
Jeff Layton1a867a02014-10-28 14:24:14 -0400504#define rpc_show_sock_xprt_flags(flags) \
505 __print_flags(flags, "|", \
506 { TCP_RCV_LAST_FRAG, "TCP_RCV_LAST_FRAG" }, \
507 { TCP_RCV_COPY_FRAGHDR, "TCP_RCV_COPY_FRAGHDR" }, \
508 { TCP_RCV_COPY_XID, "TCP_RCV_COPY_XID" }, \
509 { TCP_RCV_COPY_DATA, "TCP_RCV_COPY_DATA" }, \
510 { TCP_RCV_READ_CALLDIR, "TCP_RCV_READ_CALLDIR" }, \
511 { TCP_RCV_COPY_CALLDIR, "TCP_RCV_COPY_CALLDIR" }, \
512 { TCP_RPC_REPLY, "TCP_RPC_REPLY" })
513
514TRACE_EVENT(xs_tcp_data_recv,
515 TP_PROTO(struct sock_xprt *xs),
516
517 TP_ARGS(xs),
518
519 TP_STRUCT__entry(
520 __string(addr, xs->xprt.address_strings[RPC_DISPLAY_ADDR])
521 __string(port, xs->xprt.address_strings[RPC_DISPLAY_PORT])
Chuck Levera30ccf12017-10-20 10:35:18 -0400522 __field(u32, xid)
Jeff Layton1a867a02014-10-28 14:24:14 -0400523 __field(unsigned long, flags)
524 __field(unsigned long, copied)
525 __field(unsigned int, reclen)
526 __field(unsigned long, offset)
527 ),
528
529 TP_fast_assign(
530 __assign_str(addr, xs->xprt.address_strings[RPC_DISPLAY_ADDR]);
531 __assign_str(port, xs->xprt.address_strings[RPC_DISPLAY_PORT]);
Chuck Levera30ccf12017-10-20 10:35:18 -0400532 __entry->xid = be32_to_cpu(xs->tcp_xid);
Jeff Layton1a867a02014-10-28 14:24:14 -0400533 __entry->flags = xs->tcp_flags;
534 __entry->copied = xs->tcp_copied;
535 __entry->reclen = xs->tcp_reclen;
536 __entry->offset = xs->tcp_offset;
537 ),
538
Chuck Levera30ccf12017-10-20 10:35:18 -0400539 TP_printk("peer=[%s]:%s xid=0x%08x flags=%s copied=%lu reclen=%u offset=%lu",
540 __get_str(addr), __get_str(port), __entry->xid,
Jeff Layton1a867a02014-10-28 14:24:14 -0400541 rpc_show_sock_xprt_flags(__entry->flags),
542 __entry->copied, __entry->reclen, __entry->offset)
543);
544
Jeff Layton30660e04b2014-11-19 07:51:16 -0500545#define show_rqstp_flags(flags) \
546 __print_flags(flags, "|", \
547 { (1UL << RQ_SECURE), "RQ_SECURE"}, \
548 { (1UL << RQ_LOCAL), "RQ_LOCAL"}, \
Jeff Layton78b65eb2014-11-19 07:51:17 -0500549 { (1UL << RQ_USEDEFERRAL), "RQ_USEDEFERRAL"}, \
Jeff Layton779fb0f2014-11-19 07:51:18 -0500550 { (1UL << RQ_DROPME), "RQ_DROPME"}, \
Jeff Layton81244382014-11-21 14:19:28 -0500551 { (1UL << RQ_SPLICE_OK), "RQ_SPLICE_OK"}, \
Jeff Laytonb1691bc2014-11-21 14:19:30 -0500552 { (1UL << RQ_VICTIM), "RQ_VICTIM"}, \
553 { (1UL << RQ_BUSY), "RQ_BUSY"})
Jeff Layton4d152e22014-11-19 07:51:14 -0500554
Jeff Layton860a0d92014-10-28 14:24:12 -0400555TRACE_EVENT(svc_recv,
556 TP_PROTO(struct svc_rqst *rqst, int status),
557
558 TP_ARGS(rqst, status),
559
560 TP_STRUCT__entry(
Chuck Levera30ccf12017-10-20 10:35:18 -0400561 __field(u32, xid)
Jeff Layton860a0d92014-10-28 14:24:12 -0400562 __field(int, status)
Jeff Layton4d152e22014-11-19 07:51:14 -0500563 __field(unsigned long, flags)
Trond Myklebuste9d4bf22017-10-10 17:31:42 -0400564 __dynamic_array(unsigned char, addr, rqst->rq_addrlen)
Jeff Layton860a0d92014-10-28 14:24:12 -0400565 ),
566
567 TP_fast_assign(
Chuck Levera30ccf12017-10-20 10:35:18 -0400568 __entry->xid = status > 0 ? be32_to_cpu(rqst->rq_xid) : 0;
Jeff Layton860a0d92014-10-28 14:24:12 -0400569 __entry->status = status;
Jeff Layton4d152e22014-11-19 07:51:14 -0500570 __entry->flags = rqst->rq_flags;
Trond Myklebuste9d4bf22017-10-10 17:31:42 -0400571 memcpy(__get_dynamic_array(addr),
572 &rqst->rq_addr, rqst->rq_addrlen);
Jeff Layton860a0d92014-10-28 14:24:12 -0400573 ),
574
Linus Torvalds4dd3c2e2017-11-18 11:22:04 -0800575 TP_printk("addr=%pIScp xid=0x%08x status=%d flags=%s",
Trond Myklebuste9d4bf22017-10-10 17:31:42 -0400576 (struct sockaddr *)__get_dynamic_array(addr),
Chuck Levera30ccf12017-10-20 10:35:18 -0400577 __entry->xid, __entry->status,
Jeff Layton4d152e22014-11-19 07:51:14 -0500578 show_rqstp_flags(__entry->flags))
Jeff Layton860a0d92014-10-28 14:24:12 -0400579);
580
Trond Myklebust104f6352016-06-24 10:55:46 -0400581DECLARE_EVENT_CLASS(svc_rqst_event,
582
583 TP_PROTO(struct svc_rqst *rqst),
584
585 TP_ARGS(rqst),
586
587 TP_STRUCT__entry(
Chuck Levera30ccf12017-10-20 10:35:18 -0400588 __field(u32, xid)
Trond Myklebust104f6352016-06-24 10:55:46 -0400589 __field(unsigned long, flags)
590 __dynamic_array(unsigned char, addr, rqst->rq_addrlen)
591 ),
592
593 TP_fast_assign(
Chuck Levera30ccf12017-10-20 10:35:18 -0400594 __entry->xid = be32_to_cpu(rqst->rq_xid);
Trond Myklebust104f6352016-06-24 10:55:46 -0400595 __entry->flags = rqst->rq_flags;
596 memcpy(__get_dynamic_array(addr),
597 &rqst->rq_addr, rqst->rq_addrlen);
598 ),
599
Chuck Levera30ccf12017-10-20 10:35:18 -0400600 TP_printk("addr=%pIScp rq_xid=0x%08x flags=%s",
Trond Myklebust104f6352016-06-24 10:55:46 -0400601 (struct sockaddr *)__get_dynamic_array(addr),
Chuck Levera30ccf12017-10-20 10:35:18 -0400602 __entry->xid,
Trond Myklebust104f6352016-06-24 10:55:46 -0400603 show_rqstp_flags(__entry->flags))
604);
605
606DEFINE_EVENT(svc_rqst_event, svc_defer,
607 TP_PROTO(struct svc_rqst *rqst),
608 TP_ARGS(rqst));
609
610DEFINE_EVENT(svc_rqst_event, svc_drop,
611 TP_PROTO(struct svc_rqst *rqst),
612 TP_ARGS(rqst));
613
Jeff Layton860a0d92014-10-28 14:24:12 -0400614DECLARE_EVENT_CLASS(svc_rqst_status,
615
616 TP_PROTO(struct svc_rqst *rqst, int status),
617
618 TP_ARGS(rqst, status),
619
620 TP_STRUCT__entry(
Chuck Levera30ccf12017-10-20 10:35:18 -0400621 __field(u32, xid)
Jeff Layton860a0d92014-10-28 14:24:12 -0400622 __field(int, status)
Jeff Layton4d152e22014-11-19 07:51:14 -0500623 __field(unsigned long, flags)
Trond Myklebuste9d4bf22017-10-10 17:31:42 -0400624 __dynamic_array(unsigned char, addr, rqst->rq_addrlen)
Jeff Layton860a0d92014-10-28 14:24:12 -0400625 ),
626
627 TP_fast_assign(
Chuck Levera30ccf12017-10-20 10:35:18 -0400628 __entry->xid = be32_to_cpu(rqst->rq_xid);
Jeff Layton860a0d92014-10-28 14:24:12 -0400629 __entry->status = status;
Jeff Layton4d152e22014-11-19 07:51:14 -0500630 __entry->flags = rqst->rq_flags;
Trond Myklebuste9d4bf22017-10-10 17:31:42 -0400631 memcpy(__get_dynamic_array(addr),
632 &rqst->rq_addr, rqst->rq_addrlen);
Jeff Layton860a0d92014-10-28 14:24:12 -0400633 ),
634
Chuck Levera30ccf12017-10-20 10:35:18 -0400635 TP_printk("addr=%pIScp rq_xid=0x%08x status=%d flags=%s",
Trond Myklebuste9d4bf22017-10-10 17:31:42 -0400636 (struct sockaddr *)__get_dynamic_array(addr),
Linus Torvalds4dd3c2e2017-11-18 11:22:04 -0800637 __entry->xid,
Jeff Layton4d152e22014-11-19 07:51:14 -0500638 __entry->status, show_rqstp_flags(__entry->flags))
Jeff Layton860a0d92014-10-28 14:24:12 -0400639);
640
641DEFINE_EVENT(svc_rqst_status, svc_process,
642 TP_PROTO(struct svc_rqst *rqst, int status),
643 TP_ARGS(rqst, status));
644
645DEFINE_EVENT(svc_rqst_status, svc_send,
646 TP_PROTO(struct svc_rqst *rqst, int status),
647 TP_ARGS(rqst, status));
648
Jeff Layton83a712e2014-11-21 14:19:31 -0500649#define show_svc_xprt_flags(flags) \
650 __print_flags(flags, "|", \
651 { (1UL << XPT_BUSY), "XPT_BUSY"}, \
652 { (1UL << XPT_CONN), "XPT_CONN"}, \
653 { (1UL << XPT_CLOSE), "XPT_CLOSE"}, \
654 { (1UL << XPT_DATA), "XPT_DATA"}, \
655 { (1UL << XPT_TEMP), "XPT_TEMP"}, \
656 { (1UL << XPT_DEAD), "XPT_DEAD"}, \
657 { (1UL << XPT_CHNGBUF), "XPT_CHNGBUF"}, \
658 { (1UL << XPT_DEFERRED), "XPT_DEFERRED"}, \
659 { (1UL << XPT_OLD), "XPT_OLD"}, \
660 { (1UL << XPT_LISTENER), "XPT_LISTENER"}, \
661 { (1UL << XPT_CACHE_AUTH), "XPT_CACHE_AUTH"}, \
662 { (1UL << XPT_LOCAL), "XPT_LOCAL"})
663
664TRACE_EVENT(svc_xprt_do_enqueue,
665 TP_PROTO(struct svc_xprt *xprt, struct svc_rqst *rqst),
666
667 TP_ARGS(xprt, rqst),
668
669 TP_STRUCT__entry(
670 __field(struct svc_xprt *, xprt)
Pratyush Anand051ac382015-08-27 10:01:33 +0530671 __field(int, pid)
672 __field(unsigned long, flags)
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400673 __dynamic_array(unsigned char, addr, xprt != NULL ?
674 xprt->xpt_remotelen : 0)
Jeff Layton83a712e2014-11-21 14:19:31 -0500675 ),
676
677 TP_fast_assign(
678 __entry->xprt = xprt;
Pratyush Anand051ac382015-08-27 10:01:33 +0530679 __entry->pid = rqst? rqst->rq_task->pid : 0;
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400680 if (xprt) {
681 memcpy(__get_dynamic_array(addr),
682 &xprt->xpt_remote,
683 xprt->xpt_remotelen);
684 __entry->flags = xprt->xpt_flags;
685 } else
686 __entry->flags = 0;
Jeff Layton83a712e2014-11-21 14:19:31 -0500687 ),
688
689 TP_printk("xprt=0x%p addr=%pIScp pid=%d flags=%s", __entry->xprt,
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400690 __get_dynamic_array_len(addr) != 0 ?
691 (struct sockaddr *)__get_dynamic_array(addr) : NULL,
Pratyush Anand051ac382015-08-27 10:01:33 +0530692 __entry->pid, show_svc_xprt_flags(__entry->flags))
Jeff Layton83a712e2014-11-21 14:19:31 -0500693);
694
Trond Myklebust82ea2d72016-06-24 10:55:45 -0400695DECLARE_EVENT_CLASS(svc_xprt_event,
Jeff Layton83a712e2014-11-21 14:19:31 -0500696 TP_PROTO(struct svc_xprt *xprt),
697
698 TP_ARGS(xprt),
699
700 TP_STRUCT__entry(
701 __field(struct svc_xprt *, xprt)
Jeff Layton83a712e2014-11-21 14:19:31 -0500702 __field(unsigned long, flags)
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400703 __dynamic_array(unsigned char, addr, xprt != NULL ?
704 xprt->xpt_remotelen : 0)
Jeff Layton83a712e2014-11-21 14:19:31 -0500705 ),
706
707 TP_fast_assign(
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400708 __entry->xprt = xprt;
709 if (xprt) {
710 memcpy(__get_dynamic_array(addr),
711 &xprt->xpt_remote,
712 xprt->xpt_remotelen);
713 __entry->flags = xprt->xpt_flags;
714 } else
715 __entry->flags = 0;
Jeff Layton83a712e2014-11-21 14:19:31 -0500716 ),
717
718 TP_printk("xprt=0x%p addr=%pIScp flags=%s", __entry->xprt,
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400719 __get_dynamic_array_len(addr) != 0 ?
720 (struct sockaddr *)__get_dynamic_array(addr) : NULL,
Jeff Layton83a712e2014-11-21 14:19:31 -0500721 show_svc_xprt_flags(__entry->flags))
722);
723
Trond Myklebust82ea2d72016-06-24 10:55:45 -0400724DEFINE_EVENT(svc_xprt_event, svc_xprt_dequeue,
725 TP_PROTO(struct svc_xprt *xprt),
726 TP_ARGS(xprt));
727
728DEFINE_EVENT(svc_xprt_event, svc_xprt_no_write_space,
729 TP_PROTO(struct svc_xprt *xprt),
730 TP_ARGS(xprt));
731
Jeff Layton83a712e2014-11-21 14:19:31 -0500732TRACE_EVENT(svc_wake_up,
733 TP_PROTO(int pid),
734
735 TP_ARGS(pid),
736
737 TP_STRUCT__entry(
738 __field(int, pid)
739 ),
740
741 TP_fast_assign(
742 __entry->pid = pid;
743 ),
744
745 TP_printk("pid=%d", __entry->pid)
746);
747
748TRACE_EVENT(svc_handle_xprt,
749 TP_PROTO(struct svc_xprt *xprt, int len),
750
751 TP_ARGS(xprt, len),
752
753 TP_STRUCT__entry(
754 __field(struct svc_xprt *, xprt)
755 __field(int, len)
Pratyush Anand051ac382015-08-27 10:01:33 +0530756 __field(unsigned long, flags)
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400757 __dynamic_array(unsigned char, addr, xprt != NULL ?
758 xprt->xpt_remotelen : 0)
Jeff Layton83a712e2014-11-21 14:19:31 -0500759 ),
760
761 TP_fast_assign(
762 __entry->xprt = xprt;
763 __entry->len = len;
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400764 if (xprt) {
765 memcpy(__get_dynamic_array(addr),
766 &xprt->xpt_remote,
767 xprt->xpt_remotelen);
768 __entry->flags = xprt->xpt_flags;
769 } else
770 __entry->flags = 0;
Jeff Layton83a712e2014-11-21 14:19:31 -0500771 ),
772
773 TP_printk("xprt=0x%p addr=%pIScp len=%d flags=%s", __entry->xprt,
Trond Myklebustdb1bb442016-06-24 10:55:44 -0400774 __get_dynamic_array_len(addr) != 0 ?
775 (struct sockaddr *)__get_dynamic_array(addr) : NULL,
Pratyush Anand051ac382015-08-27 10:01:33 +0530776 __entry->len, show_svc_xprt_flags(__entry->flags))
Jeff Layton83a712e2014-11-21 14:19:31 -0500777);
Trond Myklebust104f6352016-06-24 10:55:46 -0400778
779
780DECLARE_EVENT_CLASS(svc_deferred_event,
781 TP_PROTO(struct svc_deferred_req *dr),
782
783 TP_ARGS(dr),
784
785 TP_STRUCT__entry(
Chuck Levera30ccf12017-10-20 10:35:18 -0400786 __field(u32, xid)
Trond Myklebust104f6352016-06-24 10:55:46 -0400787 __dynamic_array(unsigned char, addr, dr->addrlen)
788 ),
789
790 TP_fast_assign(
Chuck Levera30ccf12017-10-20 10:35:18 -0400791 __entry->xid = be32_to_cpu(*(__be32 *)(dr->args +
792 (dr->xprt_hlen>>2)));
Trond Myklebust104f6352016-06-24 10:55:46 -0400793 memcpy(__get_dynamic_array(addr), &dr->addr, dr->addrlen);
794 ),
795
Chuck Levera30ccf12017-10-20 10:35:18 -0400796 TP_printk("addr=%pIScp xid=0x%08x",
Trond Myklebust104f6352016-06-24 10:55:46 -0400797 (struct sockaddr *)__get_dynamic_array(addr),
Chuck Levera30ccf12017-10-20 10:35:18 -0400798 __entry->xid)
Trond Myklebust104f6352016-06-24 10:55:46 -0400799);
800
801DEFINE_EVENT(svc_deferred_event, svc_drop_deferred,
802 TP_PROTO(struct svc_deferred_req *dr),
803 TP_ARGS(dr));
804DEFINE_EVENT(svc_deferred_event, svc_revisit_deferred,
805 TP_PROTO(struct svc_deferred_req *dr),
806 TP_ARGS(dr));
Trond Myklebust82b0a4c2012-01-20 14:52:23 -0500807#endif /* _TRACE_SUNRPC_H */
808
809#include <trace/define_trace.h>