xref: /openbmc/linux/lib/dynamic_debug.c (revision 29c37341)
1 /*
2  * lib/dynamic_debug.c
3  *
4  * make pr_debug()/dev_dbg() calls runtime configurable based upon their
5  * source module.
6  *
7  * Copyright (C) 2008 Jason Baron <jbaron@redhat.com>
8  * By Greg Banks <gnb@melbourne.sgi.com>
9  * Copyright (c) 2008 Silicon Graphics Inc.  All Rights Reserved.
10  * Copyright (C) 2011 Bart Van Assche.  All Rights Reserved.
11  * Copyright (C) 2013 Du, Changbin <changbin.du@gmail.com>
12  */
13 
14 #define pr_fmt(fmt) "dyndbg: " fmt
15 
16 #include <linux/kernel.h>
17 #include <linux/module.h>
18 #include <linux/moduleparam.h>
19 #include <linux/kallsyms.h>
20 #include <linux/types.h>
21 #include <linux/mutex.h>
22 #include <linux/proc_fs.h>
23 #include <linux/seq_file.h>
24 #include <linux/list.h>
25 #include <linux/sysctl.h>
26 #include <linux/ctype.h>
27 #include <linux/string.h>
28 #include <linux/parser.h>
29 #include <linux/string_helpers.h>
30 #include <linux/uaccess.h>
31 #include <linux/dynamic_debug.h>
32 #include <linux/debugfs.h>
33 #include <linux/slab.h>
34 #include <linux/jump_label.h>
35 #include <linux/hardirq.h>
36 #include <linux/sched.h>
37 #include <linux/device.h>
38 #include <linux/netdevice.h>
39 
40 #include <rdma/ib_verbs.h>
41 
42 extern struct _ddebug __start___dyndbg[];
43 extern struct _ddebug __stop___dyndbg[];
44 
45 struct ddebug_table {
46 	struct list_head link;
47 	const char *mod_name;
48 	unsigned int num_ddebugs;
49 	struct _ddebug *ddebugs;
50 };
51 
52 struct ddebug_query {
53 	const char *filename;
54 	const char *module;
55 	const char *function;
56 	const char *format;
57 	unsigned int first_lineno, last_lineno;
58 };
59 
60 struct ddebug_iter {
61 	struct ddebug_table *table;
62 	unsigned int idx;
63 };
64 
65 struct flag_settings {
66 	unsigned int flags;
67 	unsigned int mask;
68 };
69 
70 static DEFINE_MUTEX(ddebug_lock);
71 static LIST_HEAD(ddebug_tables);
72 static int verbose;
73 module_param(verbose, int, 0644);
74 
75 /* Return the path relative to source root */
76 static inline const char *trim_prefix(const char *path)
77 {
78 	int skip = strlen(__FILE__) - strlen("lib/dynamic_debug.c");
79 
80 	if (strncmp(path, __FILE__, skip))
81 		skip = 0; /* prefix mismatch, don't skip */
82 
83 	return path + skip;
84 }
85 
86 static struct { unsigned flag:8; char opt_char; } opt_array[] = {
87 	{ _DPRINTK_FLAGS_PRINT, 'p' },
88 	{ _DPRINTK_FLAGS_INCL_MODNAME, 'm' },
89 	{ _DPRINTK_FLAGS_INCL_FUNCNAME, 'f' },
90 	{ _DPRINTK_FLAGS_INCL_LINENO, 'l' },
91 	{ _DPRINTK_FLAGS_INCL_TID, 't' },
92 	{ _DPRINTK_FLAGS_NONE, '_' },
93 };
94 
95 struct flagsbuf { char buf[ARRAY_SIZE(opt_array)+1]; };
96 
97 /* format a string into buf[] which describes the _ddebug's flags */
98 static char *ddebug_describe_flags(unsigned int flags, struct flagsbuf *fb)
99 {
100 	char *p = fb->buf;
101 	int i;
102 
103 	for (i = 0; i < ARRAY_SIZE(opt_array); ++i)
104 		if (flags & opt_array[i].flag)
105 			*p++ = opt_array[i].opt_char;
106 	if (p == fb->buf)
107 		*p++ = '_';
108 	*p = '\0';
109 
110 	return fb->buf;
111 }
112 
113 #define vnpr_info(lvl, fmt, ...)				\
114 do {								\
115 	if (verbose >= lvl)					\
116 		pr_info(fmt, ##__VA_ARGS__);			\
117 } while (0)
118 
119 #define vpr_info(fmt, ...)	vnpr_info(1, fmt, ##__VA_ARGS__)
120 #define v2pr_info(fmt, ...)	vnpr_info(2, fmt, ##__VA_ARGS__)
121 
122 static void vpr_info_dq(const struct ddebug_query *query, const char *msg)
123 {
124 	/* trim any trailing newlines */
125 	int fmtlen = 0;
126 
127 	if (query->format) {
128 		fmtlen = strlen(query->format);
129 		while (fmtlen && query->format[fmtlen - 1] == '\n')
130 			fmtlen--;
131 	}
132 
133 	vpr_info("%s: func=\"%s\" file=\"%s\" module=\"%s\" format=\"%.*s\" lineno=%u-%u\n",
134 		 msg,
135 		 query->function ?: "",
136 		 query->filename ?: "",
137 		 query->module ?: "",
138 		 fmtlen, query->format ?: "",
139 		 query->first_lineno, query->last_lineno);
140 }
141 
142 /*
143  * Search the tables for _ddebug's which match the given `query' and
144  * apply the `flags' and `mask' to them.  Returns number of matching
145  * callsites, normally the same as number of changes.  If verbose,
146  * logs the changes.  Takes ddebug_lock.
147  */
148 static int ddebug_change(const struct ddebug_query *query,
149 			 struct flag_settings *modifiers)
150 {
151 	int i;
152 	struct ddebug_table *dt;
153 	unsigned int newflags;
154 	unsigned int nfound = 0;
155 	struct flagsbuf fbuf;
156 
157 	/* search for matching ddebugs */
158 	mutex_lock(&ddebug_lock);
159 	list_for_each_entry(dt, &ddebug_tables, link) {
160 
161 		/* match against the module name */
162 		if (query->module &&
163 		    !match_wildcard(query->module, dt->mod_name))
164 			continue;
165 
166 		for (i = 0; i < dt->num_ddebugs; i++) {
167 			struct _ddebug *dp = &dt->ddebugs[i];
168 
169 			/* match against the source filename */
170 			if (query->filename &&
171 			    !match_wildcard(query->filename, dp->filename) &&
172 			    !match_wildcard(query->filename,
173 					   kbasename(dp->filename)) &&
174 			    !match_wildcard(query->filename,
175 					   trim_prefix(dp->filename)))
176 				continue;
177 
178 			/* match against the function */
179 			if (query->function &&
180 			    !match_wildcard(query->function, dp->function))
181 				continue;
182 
183 			/* match against the format */
184 			if (query->format) {
185 				if (*query->format == '^') {
186 					char *p;
187 					/* anchored search. match must be at beginning */
188 					p = strstr(dp->format, query->format+1);
189 					if (p != dp->format)
190 						continue;
191 				} else if (!strstr(dp->format, query->format))
192 					continue;
193 			}
194 
195 			/* match against the line number range */
196 			if (query->first_lineno &&
197 			    dp->lineno < query->first_lineno)
198 				continue;
199 			if (query->last_lineno &&
200 			    dp->lineno > query->last_lineno)
201 				continue;
202 
203 			nfound++;
204 
205 			newflags = (dp->flags & modifiers->mask) | modifiers->flags;
206 			if (newflags == dp->flags)
207 				continue;
208 #ifdef CONFIG_JUMP_LABEL
209 			if (dp->flags & _DPRINTK_FLAGS_PRINT) {
210 				if (!(modifiers->flags & _DPRINTK_FLAGS_PRINT))
211 					static_branch_disable(&dp->key.dd_key_true);
212 			} else if (modifiers->flags & _DPRINTK_FLAGS_PRINT)
213 				static_branch_enable(&dp->key.dd_key_true);
214 #endif
215 			dp->flags = newflags;
216 			v2pr_info("changed %s:%d [%s]%s =%s\n",
217 				 trim_prefix(dp->filename), dp->lineno,
218 				 dt->mod_name, dp->function,
219 				 ddebug_describe_flags(dp->flags, &fbuf));
220 		}
221 	}
222 	mutex_unlock(&ddebug_lock);
223 
224 	if (!nfound && verbose)
225 		pr_info("no matches for query\n");
226 
227 	return nfound;
228 }
229 
230 /*
231  * Split the buffer `buf' into space-separated words.
232  * Handles simple " and ' quoting, i.e. without nested,
233  * embedded or escaped \".  Return the number of words
234  * or <0 on error.
235  */
236 static int ddebug_tokenize(char *buf, char *words[], int maxwords)
237 {
238 	int nwords = 0;
239 
240 	while (*buf) {
241 		char *end;
242 
243 		/* Skip leading whitespace */
244 		buf = skip_spaces(buf);
245 		if (!*buf)
246 			break;	/* oh, it was trailing whitespace */
247 		if (*buf == '#')
248 			break;	/* token starts comment, skip rest of line */
249 
250 		/* find `end' of word, whitespace separated or quoted */
251 		if (*buf == '"' || *buf == '\'') {
252 			int quote = *buf++;
253 			for (end = buf; *end && *end != quote; end++)
254 				;
255 			if (!*end) {
256 				pr_err("unclosed quote: %s\n", buf);
257 				return -EINVAL;	/* unclosed quote */
258 			}
259 		} else {
260 			for (end = buf; *end && !isspace(*end); end++)
261 				;
262 			BUG_ON(end == buf);
263 		}
264 
265 		/* `buf' is start of word, `end' is one past its end */
266 		if (nwords == maxwords) {
267 			pr_err("too many words, legal max <=%d\n", maxwords);
268 			return -EINVAL;	/* ran out of words[] before bytes */
269 		}
270 		if (*end)
271 			*end++ = '\0';	/* terminate the word */
272 		words[nwords++] = buf;
273 		buf = end;
274 	}
275 
276 	if (verbose) {
277 		int i;
278 		pr_info("split into words:");
279 		for (i = 0; i < nwords; i++)
280 			pr_cont(" \"%s\"", words[i]);
281 		pr_cont("\n");
282 	}
283 
284 	return nwords;
285 }
286 
287 /*
288  * Parse a single line number.  Note that the empty string ""
289  * is treated as a special case and converted to zero, which
290  * is later treated as a "don't care" value.
291  */
292 static inline int parse_lineno(const char *str, unsigned int *val)
293 {
294 	BUG_ON(str == NULL);
295 	if (*str == '\0') {
296 		*val = 0;
297 		return 0;
298 	}
299 	if (kstrtouint(str, 10, val) < 0) {
300 		pr_err("bad line-number: %s\n", str);
301 		return -EINVAL;
302 	}
303 	return 0;
304 }
305 
306 static int parse_linerange(struct ddebug_query *query, const char *first)
307 {
308 	char *last = strchr(first, '-');
309 
310 	if (query->first_lineno || query->last_lineno) {
311 		pr_err("match-spec: line used 2x\n");
312 		return -EINVAL;
313 	}
314 	if (last)
315 		*last++ = '\0';
316 	if (parse_lineno(first, &query->first_lineno) < 0)
317 		return -EINVAL;
318 	if (last) {
319 		/* range <first>-<last> */
320 		if (parse_lineno(last, &query->last_lineno) < 0)
321 			return -EINVAL;
322 
323 		/* special case for last lineno not specified */
324 		if (query->last_lineno == 0)
325 			query->last_lineno = UINT_MAX;
326 
327 		if (query->last_lineno < query->first_lineno) {
328 			pr_err("last-line:%d < 1st-line:%d\n",
329 			       query->last_lineno,
330 			       query->first_lineno);
331 			return -EINVAL;
332 		}
333 	} else {
334 		query->last_lineno = query->first_lineno;
335 	}
336 	vpr_info("parsed line %d-%d\n", query->first_lineno,
337 		 query->last_lineno);
338 	return 0;
339 }
340 
341 static int check_set(const char **dest, char *src, char *name)
342 {
343 	int rc = 0;
344 
345 	if (*dest) {
346 		rc = -EINVAL;
347 		pr_err("match-spec:%s val:%s overridden by %s\n",
348 		       name, *dest, src);
349 	}
350 	*dest = src;
351 	return rc;
352 }
353 
354 /*
355  * Parse words[] as a ddebug query specification, which is a series
356  * of (keyword, value) pairs or combined keyword=value terms,
357  * chosen from these possibilities:
358  *
359  * func <function-name>
360  * file <full-pathname>
361  * file <base-filename>
362  * module <module-name>
363  * format <escaped-string-to-find-in-format>
364  * line <lineno>
365  * line <first-lineno>-<last-lineno> // where either may be empty
366  *
367  * Only 1 of each type is allowed.
368  * Returns 0 on success, <0 on error.
369  */
370 static int ddebug_parse_query(char *words[], int nwords,
371 			struct ddebug_query *query, const char *modname)
372 {
373 	unsigned int i;
374 	int rc = 0;
375 	char *fline;
376 	char *keyword, *arg;
377 
378 	if (modname)
379 		/* support $modname.dyndbg=<multiple queries> */
380 		query->module = modname;
381 
382 	for (i = 0; i < nwords; i++) {
383 		/* accept keyword=arg */
384 		vpr_info("%d w:%s\n", i, words[i]);
385 
386 		keyword = words[i];
387 		arg = strchr(keyword, '=');
388 		if (arg) {
389 			*arg++ = '\0';
390 		} else {
391 			i++; /* next word is arg */
392 			if (!(i < nwords)) {
393 				pr_err("missing arg to keyword: %s\n", keyword);
394 				return -EINVAL;
395 			}
396 			arg = words[i];
397 		}
398 		vpr_info("%d key:%s arg:%s\n", i, keyword, arg);
399 
400 		if (!strcmp(keyword, "func")) {
401 			rc = check_set(&query->function, arg, "func");
402 		} else if (!strcmp(keyword, "file")) {
403 			if (check_set(&query->filename, arg, "file"))
404 				return -EINVAL;
405 
406 			/* tail :$info is function or line-range */
407 			fline = strchr(query->filename, ':');
408 			if (!fline)
409 				break;
410 			*fline++ = '\0';
411 			if (isalpha(*fline) || *fline == '*' || *fline == '?') {
412 				/* take as function name */
413 				if (check_set(&query->function, fline, "func"))
414 					return -EINVAL;
415 			} else {
416 				if (parse_linerange(query, fline))
417 					return -EINVAL;
418 			}
419 		} else if (!strcmp(keyword, "module")) {
420 			rc = check_set(&query->module, arg, "module");
421 		} else if (!strcmp(keyword, "format")) {
422 			string_unescape_inplace(arg, UNESCAPE_SPACE |
423 							    UNESCAPE_OCTAL |
424 							    UNESCAPE_SPECIAL);
425 			rc = check_set(&query->format, arg, "format");
426 		} else if (!strcmp(keyword, "line")) {
427 			if (parse_linerange(query, arg))
428 				return -EINVAL;
429 		} else {
430 			pr_err("unknown keyword \"%s\"\n", keyword);
431 			return -EINVAL;
432 		}
433 		if (rc)
434 			return rc;
435 	}
436 	vpr_info_dq(query, "parsed");
437 	return 0;
438 }
439 
440 /*
441  * Parse `str' as a flags specification, format [-+=][p]+.
442  * Sets up *maskp and *flagsp to be used when changing the
443  * flags fields of matched _ddebug's.  Returns 0 on success
444  * or <0 on error.
445  */
446 static int ddebug_parse_flags(const char *str, struct flag_settings *modifiers)
447 {
448 	int op, i;
449 
450 	switch (*str) {
451 	case '+':
452 	case '-':
453 	case '=':
454 		op = *str++;
455 		break;
456 	default:
457 		pr_err("bad flag-op %c, at start of %s\n", *str, str);
458 		return -EINVAL;
459 	}
460 	vpr_info("op='%c'\n", op);
461 
462 	for (; *str ; ++str) {
463 		for (i = ARRAY_SIZE(opt_array) - 1; i >= 0; i--) {
464 			if (*str == opt_array[i].opt_char) {
465 				modifiers->flags |= opt_array[i].flag;
466 				break;
467 			}
468 		}
469 		if (i < 0) {
470 			pr_err("unknown flag '%c'\n", *str);
471 			return -EINVAL;
472 		}
473 	}
474 	vpr_info("flags=0x%x\n", modifiers->flags);
475 
476 	/* calculate final flags, mask based upon op */
477 	switch (op) {
478 	case '=':
479 		/* modifiers->flags already set */
480 		modifiers->mask = 0;
481 		break;
482 	case '+':
483 		modifiers->mask = ~0U;
484 		break;
485 	case '-':
486 		modifiers->mask = ~modifiers->flags;
487 		modifiers->flags = 0;
488 		break;
489 	}
490 	vpr_info("*flagsp=0x%x *maskp=0x%x\n", modifiers->flags, modifiers->mask);
491 
492 	return 0;
493 }
494 
495 static int ddebug_exec_query(char *query_string, const char *modname)
496 {
497 	struct flag_settings modifiers = {};
498 	struct ddebug_query query = {};
499 #define MAXWORDS 9
500 	int nwords, nfound;
501 	char *words[MAXWORDS];
502 
503 	nwords = ddebug_tokenize(query_string, words, MAXWORDS);
504 	if (nwords <= 0) {
505 		pr_err("tokenize failed\n");
506 		return -EINVAL;
507 	}
508 	/* check flags 1st (last arg) so query is pairs of spec,val */
509 	if (ddebug_parse_flags(words[nwords-1], &modifiers)) {
510 		pr_err("flags parse failed\n");
511 		return -EINVAL;
512 	}
513 	if (ddebug_parse_query(words, nwords-1, &query, modname)) {
514 		pr_err("query parse failed\n");
515 		return -EINVAL;
516 	}
517 	/* actually go and implement the change */
518 	nfound = ddebug_change(&query, &modifiers);
519 	vpr_info_dq(&query, nfound ? "applied" : "no-match");
520 
521 	return nfound;
522 }
523 
524 /* handle multiple queries in query string, continue on error, return
525    last error or number of matching callsites.  Module name is either
526    in param (for boot arg) or perhaps in query string.
527 */
528 int ddebug_exec_queries(char *query, const char *modname)
529 {
530 	char *split;
531 	int i, errs = 0, exitcode = 0, rc, nfound = 0;
532 
533 	for (i = 0; query; query = split) {
534 		split = strpbrk(query, ";\n");
535 		if (split)
536 			*split++ = '\0';
537 
538 		query = skip_spaces(query);
539 		if (!query || !*query || *query == '#')
540 			continue;
541 
542 		vpr_info("query %d: \"%s\"\n", i, query);
543 
544 		rc = ddebug_exec_query(query, modname);
545 		if (rc < 0) {
546 			errs++;
547 			exitcode = rc;
548 		} else {
549 			nfound += rc;
550 		}
551 		i++;
552 	}
553 	vpr_info("processed %d queries, with %d matches, %d errs\n",
554 		 i, nfound, errs);
555 
556 	if (exitcode)
557 		return exitcode;
558 	return nfound;
559 }
560 EXPORT_SYMBOL_GPL(ddebug_exec_queries);
561 
562 #define PREFIX_SIZE 64
563 
564 static int remaining(int wrote)
565 {
566 	if (PREFIX_SIZE - wrote > 0)
567 		return PREFIX_SIZE - wrote;
568 	return 0;
569 }
570 
571 static char *dynamic_emit_prefix(const struct _ddebug *desc, char *buf)
572 {
573 	int pos_after_tid;
574 	int pos = 0;
575 
576 	*buf = '\0';
577 
578 	if (desc->flags & _DPRINTK_FLAGS_INCL_TID) {
579 		if (in_interrupt())
580 			pos += snprintf(buf + pos, remaining(pos), "<intr> ");
581 		else
582 			pos += snprintf(buf + pos, remaining(pos), "[%d] ",
583 					task_pid_vnr(current));
584 	}
585 	pos_after_tid = pos;
586 	if (desc->flags & _DPRINTK_FLAGS_INCL_MODNAME)
587 		pos += snprintf(buf + pos, remaining(pos), "%s:",
588 				desc->modname);
589 	if (desc->flags & _DPRINTK_FLAGS_INCL_FUNCNAME)
590 		pos += snprintf(buf + pos, remaining(pos), "%s:",
591 				desc->function);
592 	if (desc->flags & _DPRINTK_FLAGS_INCL_LINENO)
593 		pos += snprintf(buf + pos, remaining(pos), "%d:",
594 				desc->lineno);
595 	if (pos - pos_after_tid)
596 		pos += snprintf(buf + pos, remaining(pos), " ");
597 	if (pos >= PREFIX_SIZE)
598 		buf[PREFIX_SIZE - 1] = '\0';
599 
600 	return buf;
601 }
602 
603 void __dynamic_pr_debug(struct _ddebug *descriptor, const char *fmt, ...)
604 {
605 	va_list args;
606 	struct va_format vaf;
607 	char buf[PREFIX_SIZE];
608 
609 	BUG_ON(!descriptor);
610 	BUG_ON(!fmt);
611 
612 	va_start(args, fmt);
613 
614 	vaf.fmt = fmt;
615 	vaf.va = &args;
616 
617 	printk(KERN_DEBUG "%s%pV", dynamic_emit_prefix(descriptor, buf), &vaf);
618 
619 	va_end(args);
620 }
621 EXPORT_SYMBOL(__dynamic_pr_debug);
622 
623 void __dynamic_dev_dbg(struct _ddebug *descriptor,
624 		      const struct device *dev, const char *fmt, ...)
625 {
626 	struct va_format vaf;
627 	va_list args;
628 
629 	BUG_ON(!descriptor);
630 	BUG_ON(!fmt);
631 
632 	va_start(args, fmt);
633 
634 	vaf.fmt = fmt;
635 	vaf.va = &args;
636 
637 	if (!dev) {
638 		printk(KERN_DEBUG "(NULL device *): %pV", &vaf);
639 	} else {
640 		char buf[PREFIX_SIZE];
641 
642 		dev_printk_emit(LOGLEVEL_DEBUG, dev, "%s%s %s: %pV",
643 				dynamic_emit_prefix(descriptor, buf),
644 				dev_driver_string(dev), dev_name(dev),
645 				&vaf);
646 	}
647 
648 	va_end(args);
649 }
650 EXPORT_SYMBOL(__dynamic_dev_dbg);
651 
652 #ifdef CONFIG_NET
653 
654 void __dynamic_netdev_dbg(struct _ddebug *descriptor,
655 			  const struct net_device *dev, const char *fmt, ...)
656 {
657 	struct va_format vaf;
658 	va_list args;
659 
660 	BUG_ON(!descriptor);
661 	BUG_ON(!fmt);
662 
663 	va_start(args, fmt);
664 
665 	vaf.fmt = fmt;
666 	vaf.va = &args;
667 
668 	if (dev && dev->dev.parent) {
669 		char buf[PREFIX_SIZE];
670 
671 		dev_printk_emit(LOGLEVEL_DEBUG, dev->dev.parent,
672 				"%s%s %s %s%s: %pV",
673 				dynamic_emit_prefix(descriptor, buf),
674 				dev_driver_string(dev->dev.parent),
675 				dev_name(dev->dev.parent),
676 				netdev_name(dev), netdev_reg_state(dev),
677 				&vaf);
678 	} else if (dev) {
679 		printk(KERN_DEBUG "%s%s: %pV", netdev_name(dev),
680 		       netdev_reg_state(dev), &vaf);
681 	} else {
682 		printk(KERN_DEBUG "(NULL net_device): %pV", &vaf);
683 	}
684 
685 	va_end(args);
686 }
687 EXPORT_SYMBOL(__dynamic_netdev_dbg);
688 
689 #endif
690 
691 #if IS_ENABLED(CONFIG_INFINIBAND)
692 
693 void __dynamic_ibdev_dbg(struct _ddebug *descriptor,
694 			 const struct ib_device *ibdev, const char *fmt, ...)
695 {
696 	struct va_format vaf;
697 	va_list args;
698 
699 	va_start(args, fmt);
700 
701 	vaf.fmt = fmt;
702 	vaf.va = &args;
703 
704 	if (ibdev && ibdev->dev.parent) {
705 		char buf[PREFIX_SIZE];
706 
707 		dev_printk_emit(LOGLEVEL_DEBUG, ibdev->dev.parent,
708 				"%s%s %s %s: %pV",
709 				dynamic_emit_prefix(descriptor, buf),
710 				dev_driver_string(ibdev->dev.parent),
711 				dev_name(ibdev->dev.parent),
712 				dev_name(&ibdev->dev),
713 				&vaf);
714 	} else if (ibdev) {
715 		printk(KERN_DEBUG "%s: %pV", dev_name(&ibdev->dev), &vaf);
716 	} else {
717 		printk(KERN_DEBUG "(NULL ib_device): %pV", &vaf);
718 	}
719 
720 	va_end(args);
721 }
722 EXPORT_SYMBOL(__dynamic_ibdev_dbg);
723 
724 #endif
725 
726 #define DDEBUG_STRING_SIZE 1024
727 static __initdata char ddebug_setup_string[DDEBUG_STRING_SIZE];
728 
729 static __init int ddebug_setup_query(char *str)
730 {
731 	if (strlen(str) >= DDEBUG_STRING_SIZE) {
732 		pr_warn("ddebug boot param string too large\n");
733 		return 0;
734 	}
735 	strlcpy(ddebug_setup_string, str, DDEBUG_STRING_SIZE);
736 	return 1;
737 }
738 
739 __setup("ddebug_query=", ddebug_setup_query);
740 
741 /*
742  * File_ops->write method for <debugfs>/dynamic_debug/control.  Gathers the
743  * command text from userspace, parses and executes it.
744  */
745 #define USER_BUF_PAGE 4096
746 static ssize_t ddebug_proc_write(struct file *file, const char __user *ubuf,
747 				  size_t len, loff_t *offp)
748 {
749 	char *tmpbuf;
750 	int ret;
751 
752 	if (len == 0)
753 		return 0;
754 	if (len > USER_BUF_PAGE - 1) {
755 		pr_warn("expected <%d bytes into control\n", USER_BUF_PAGE);
756 		return -E2BIG;
757 	}
758 	tmpbuf = memdup_user_nul(ubuf, len);
759 	if (IS_ERR(tmpbuf))
760 		return PTR_ERR(tmpbuf);
761 	vpr_info("read %d bytes from userspace\n", (int)len);
762 
763 	ret = ddebug_exec_queries(tmpbuf, NULL);
764 	kfree(tmpbuf);
765 	if (ret < 0)
766 		return ret;
767 
768 	*offp += len;
769 	return len;
770 }
771 
772 /*
773  * Set the iterator to point to the first _ddebug object
774  * and return a pointer to that first object.  Returns
775  * NULL if there are no _ddebugs at all.
776  */
777 static struct _ddebug *ddebug_iter_first(struct ddebug_iter *iter)
778 {
779 	if (list_empty(&ddebug_tables)) {
780 		iter->table = NULL;
781 		iter->idx = 0;
782 		return NULL;
783 	}
784 	iter->table = list_entry(ddebug_tables.next,
785 				 struct ddebug_table, link);
786 	iter->idx = 0;
787 	return &iter->table->ddebugs[iter->idx];
788 }
789 
790 /*
791  * Advance the iterator to point to the next _ddebug
792  * object from the one the iterator currently points at,
793  * and returns a pointer to the new _ddebug.  Returns
794  * NULL if the iterator has seen all the _ddebugs.
795  */
796 static struct _ddebug *ddebug_iter_next(struct ddebug_iter *iter)
797 {
798 	if (iter->table == NULL)
799 		return NULL;
800 	if (++iter->idx == iter->table->num_ddebugs) {
801 		/* iterate to next table */
802 		iter->idx = 0;
803 		if (list_is_last(&iter->table->link, &ddebug_tables)) {
804 			iter->table = NULL;
805 			return NULL;
806 		}
807 		iter->table = list_entry(iter->table->link.next,
808 					 struct ddebug_table, link);
809 	}
810 	return &iter->table->ddebugs[iter->idx];
811 }
812 
813 /*
814  * Seq_ops start method.  Called at the start of every
815  * read() call from userspace.  Takes the ddebug_lock and
816  * seeks the seq_file's iterator to the given position.
817  */
818 static void *ddebug_proc_start(struct seq_file *m, loff_t *pos)
819 {
820 	struct ddebug_iter *iter = m->private;
821 	struct _ddebug *dp;
822 	int n = *pos;
823 
824 	mutex_lock(&ddebug_lock);
825 
826 	if (!n)
827 		return SEQ_START_TOKEN;
828 	if (n < 0)
829 		return NULL;
830 	dp = ddebug_iter_first(iter);
831 	while (dp != NULL && --n > 0)
832 		dp = ddebug_iter_next(iter);
833 	return dp;
834 }
835 
836 /*
837  * Seq_ops next method.  Called several times within a read()
838  * call from userspace, with ddebug_lock held.  Walks to the
839  * next _ddebug object with a special case for the header line.
840  */
841 static void *ddebug_proc_next(struct seq_file *m, void *p, loff_t *pos)
842 {
843 	struct ddebug_iter *iter = m->private;
844 	struct _ddebug *dp;
845 
846 	if (p == SEQ_START_TOKEN)
847 		dp = ddebug_iter_first(iter);
848 	else
849 		dp = ddebug_iter_next(iter);
850 	++*pos;
851 	return dp;
852 }
853 
854 /*
855  * Seq_ops show method.  Called several times within a read()
856  * call from userspace, with ddebug_lock held.  Formats the
857  * current _ddebug as a single human-readable line, with a
858  * special case for the header line.
859  */
860 static int ddebug_proc_show(struct seq_file *m, void *p)
861 {
862 	struct ddebug_iter *iter = m->private;
863 	struct _ddebug *dp = p;
864 	struct flagsbuf flags;
865 
866 	if (p == SEQ_START_TOKEN) {
867 		seq_puts(m,
868 			 "# filename:lineno [module]function flags format\n");
869 		return 0;
870 	}
871 
872 	seq_printf(m, "%s:%u [%s]%s =%s \"",
873 		   trim_prefix(dp->filename), dp->lineno,
874 		   iter->table->mod_name, dp->function,
875 		   ddebug_describe_flags(dp->flags, &flags));
876 	seq_escape(m, dp->format, "\t\r\n\"");
877 	seq_puts(m, "\"\n");
878 
879 	return 0;
880 }
881 
882 /*
883  * Seq_ops stop method.  Called at the end of each read()
884  * call from userspace.  Drops ddebug_lock.
885  */
886 static void ddebug_proc_stop(struct seq_file *m, void *p)
887 {
888 	mutex_unlock(&ddebug_lock);
889 }
890 
891 static const struct seq_operations ddebug_proc_seqops = {
892 	.start = ddebug_proc_start,
893 	.next = ddebug_proc_next,
894 	.show = ddebug_proc_show,
895 	.stop = ddebug_proc_stop
896 };
897 
898 static int ddebug_proc_open(struct inode *inode, struct file *file)
899 {
900 	vpr_info("called\n");
901 	return seq_open_private(file, &ddebug_proc_seqops,
902 				sizeof(struct ddebug_iter));
903 }
904 
905 static const struct file_operations ddebug_proc_fops = {
906 	.owner = THIS_MODULE,
907 	.open = ddebug_proc_open,
908 	.read = seq_read,
909 	.llseek = seq_lseek,
910 	.release = seq_release_private,
911 	.write = ddebug_proc_write
912 };
913 
914 static const struct proc_ops proc_fops = {
915 	.proc_open = ddebug_proc_open,
916 	.proc_read = seq_read,
917 	.proc_lseek = seq_lseek,
918 	.proc_release = seq_release_private,
919 	.proc_write = ddebug_proc_write
920 };
921 
922 /*
923  * Allocate a new ddebug_table for the given module
924  * and add it to the global list.
925  */
926 int ddebug_add_module(struct _ddebug *tab, unsigned int n,
927 			     const char *name)
928 {
929 	struct ddebug_table *dt;
930 
931 	dt = kzalloc(sizeof(*dt), GFP_KERNEL);
932 	if (dt == NULL) {
933 		pr_err("error adding module: %s\n", name);
934 		return -ENOMEM;
935 	}
936 	/*
937 	 * For built-in modules, name lives in .rodata and is
938 	 * immortal. For loaded modules, name points at the name[]
939 	 * member of struct module, which lives at least as long as
940 	 * this struct ddebug_table.
941 	 */
942 	dt->mod_name = name;
943 	dt->num_ddebugs = n;
944 	dt->ddebugs = tab;
945 
946 	mutex_lock(&ddebug_lock);
947 	list_add(&dt->link, &ddebug_tables);
948 	mutex_unlock(&ddebug_lock);
949 
950 	v2pr_info("%u debug prints in module %s\n", n, dt->mod_name);
951 	return 0;
952 }
953 
954 /* helper for ddebug_dyndbg_(boot|module)_param_cb */
955 static int ddebug_dyndbg_param_cb(char *param, char *val,
956 				const char *modname, int on_err)
957 {
958 	char *sep;
959 
960 	sep = strchr(param, '.');
961 	if (sep) {
962 		/* needed only for ddebug_dyndbg_boot_param_cb */
963 		*sep = '\0';
964 		modname = param;
965 		param = sep + 1;
966 	}
967 	if (strcmp(param, "dyndbg"))
968 		return on_err; /* determined by caller */
969 
970 	ddebug_exec_queries((val ? val : "+p"), modname);
971 
972 	return 0; /* query failure shouldnt stop module load */
973 }
974 
975 /* handle both dyndbg and $module.dyndbg params at boot */
976 static int ddebug_dyndbg_boot_param_cb(char *param, char *val,
977 				const char *unused, void *arg)
978 {
979 	vpr_info("%s=\"%s\"\n", param, val);
980 	return ddebug_dyndbg_param_cb(param, val, NULL, 0);
981 }
982 
983 /*
984  * modprobe foo finds foo.params in boot-args, strips "foo.", and
985  * passes them to load_module().  This callback gets unknown params,
986  * processes dyndbg params, rejects others.
987  */
988 int ddebug_dyndbg_module_param_cb(char *param, char *val, const char *module)
989 {
990 	vpr_info("module: %s %s=\"%s\"\n", module, param, val);
991 	return ddebug_dyndbg_param_cb(param, val, module, -ENOENT);
992 }
993 
994 static void ddebug_table_free(struct ddebug_table *dt)
995 {
996 	list_del_init(&dt->link);
997 	kfree(dt);
998 }
999 
1000 /*
1001  * Called in response to a module being unloaded.  Removes
1002  * any ddebug_table's which point at the module.
1003  */
1004 int ddebug_remove_module(const char *mod_name)
1005 {
1006 	struct ddebug_table *dt, *nextdt;
1007 	int ret = -ENOENT;
1008 
1009 	v2pr_info("removing module \"%s\"\n", mod_name);
1010 
1011 	mutex_lock(&ddebug_lock);
1012 	list_for_each_entry_safe(dt, nextdt, &ddebug_tables, link) {
1013 		if (dt->mod_name == mod_name) {
1014 			ddebug_table_free(dt);
1015 			ret = 0;
1016 			break;
1017 		}
1018 	}
1019 	mutex_unlock(&ddebug_lock);
1020 	return ret;
1021 }
1022 
1023 static void ddebug_remove_all_tables(void)
1024 {
1025 	mutex_lock(&ddebug_lock);
1026 	while (!list_empty(&ddebug_tables)) {
1027 		struct ddebug_table *dt = list_entry(ddebug_tables.next,
1028 						      struct ddebug_table,
1029 						      link);
1030 		ddebug_table_free(dt);
1031 	}
1032 	mutex_unlock(&ddebug_lock);
1033 }
1034 
1035 static __initdata int ddebug_init_success;
1036 
1037 static int __init dynamic_debug_init_control(void)
1038 {
1039 	struct proc_dir_entry *procfs_dir;
1040 	struct dentry *debugfs_dir;
1041 
1042 	if (!ddebug_init_success)
1043 		return -ENODEV;
1044 
1045 	/* Create the control file in debugfs if it is enabled */
1046 	if (debugfs_initialized()) {
1047 		debugfs_dir = debugfs_create_dir("dynamic_debug", NULL);
1048 		debugfs_create_file("control", 0644, debugfs_dir, NULL,
1049 				    &ddebug_proc_fops);
1050 	}
1051 
1052 	/* Also create the control file in procfs */
1053 	procfs_dir = proc_mkdir("dynamic_debug", NULL);
1054 	if (procfs_dir)
1055 		proc_create("control", 0644, procfs_dir, &proc_fops);
1056 
1057 	return 0;
1058 }
1059 
1060 static int __init dynamic_debug_init(void)
1061 {
1062 	struct _ddebug *iter, *iter_start;
1063 	const char *modname = NULL;
1064 	char *cmdline;
1065 	int ret = 0;
1066 	int n = 0, entries = 0, modct = 0;
1067 
1068 	if (&__start___dyndbg == &__stop___dyndbg) {
1069 		if (IS_ENABLED(CONFIG_DYNAMIC_DEBUG)) {
1070 			pr_warn("_ddebug table is empty in a CONFIG_DYNAMIC_DEBUG build\n");
1071 			return 1;
1072 		}
1073 		pr_info("Ignore empty _ddebug table in a CONFIG_DYNAMIC_DEBUG_CORE build\n");
1074 		ddebug_init_success = 1;
1075 		return 0;
1076 	}
1077 	iter = __start___dyndbg;
1078 	modname = iter->modname;
1079 	iter_start = iter;
1080 	for (; iter < __stop___dyndbg; iter++) {
1081 		entries++;
1082 		if (strcmp(modname, iter->modname)) {
1083 			modct++;
1084 			ret = ddebug_add_module(iter_start, n, modname);
1085 			if (ret)
1086 				goto out_err;
1087 			n = 0;
1088 			modname = iter->modname;
1089 			iter_start = iter;
1090 		}
1091 		n++;
1092 	}
1093 	ret = ddebug_add_module(iter_start, n, modname);
1094 	if (ret)
1095 		goto out_err;
1096 
1097 	ddebug_init_success = 1;
1098 	vpr_info("%d modules, %d entries and %d bytes in ddebug tables, %d bytes in __dyndbg section\n",
1099 		 modct, entries, (int)(modct * sizeof(struct ddebug_table)),
1100 		 (int)(entries * sizeof(struct _ddebug)));
1101 
1102 	/* apply ddebug_query boot param, dont unload tables on err */
1103 	if (ddebug_setup_string[0] != '\0') {
1104 		pr_warn("ddebug_query param name is deprecated, change it to dyndbg\n");
1105 		ret = ddebug_exec_queries(ddebug_setup_string, NULL);
1106 		if (ret < 0)
1107 			pr_warn("Invalid ddebug boot param %s\n",
1108 				ddebug_setup_string);
1109 		else
1110 			pr_info("%d changes by ddebug_query\n", ret);
1111 	}
1112 	/* now that ddebug tables are loaded, process all boot args
1113 	 * again to find and activate queries given in dyndbg params.
1114 	 * While this has already been done for known boot params, it
1115 	 * ignored the unknown ones (dyndbg in particular).  Reusing
1116 	 * parse_args avoids ad-hoc parsing.  This will also attempt
1117 	 * to activate queries for not-yet-loaded modules, which is
1118 	 * slightly noisy if verbose, but harmless.
1119 	 */
1120 	cmdline = kstrdup(saved_command_line, GFP_KERNEL);
1121 	parse_args("dyndbg params", cmdline, NULL,
1122 		   0, 0, 0, NULL, &ddebug_dyndbg_boot_param_cb);
1123 	kfree(cmdline);
1124 	return 0;
1125 
1126 out_err:
1127 	ddebug_remove_all_tables();
1128 	return 0;
1129 }
1130 /* Allow early initialization for boot messages via boot param */
1131 early_initcall(dynamic_debug_init);
1132 
1133 /* Debugfs setup must be done later */
1134 fs_initcall(dynamic_debug_init_control);
1135