4 * make pr_debug()/dev_dbg() calls runtime configurable based upon their
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.
13 #define pr_fmt(fmt) KBUILD_MODNAME ":%s: " fmt, __func__
15 #include <linux/kernel.h>
16 #include <linux/module.h>
17 #include <linux/moduleparam.h>
18 #include <linux/kallsyms.h>
19 #include <linux/types.h>
20 #include <linux/mutex.h>
21 #include <linux/proc_fs.h>
22 #include <linux/seq_file.h>
23 #include <linux/list.h>
24 #include <linux/sysctl.h>
25 #include <linux/ctype.h>
26 #include <linux/string.h>
27 #include <linux/uaccess.h>
28 #include <linux/dynamic_debug.h>
29 #include <linux/debugfs.h>
30 #include <linux/slab.h>
31 #include <linux/jump_label.h>
32 #include <linux/hardirq.h>
33 #include <linux/sched.h>
34 #include <linux/device.h>
35 #include <linux/netdevice.h>
37 extern struct _ddebug __start___verbose[];
38 extern struct _ddebug __stop___verbose[];
41 struct list_head link;
43 unsigned int num_ddebugs;
44 struct _ddebug *ddebugs;
52 unsigned int first_lineno, last_lineno;
56 struct ddebug_table *table;
60 static DEFINE_MUTEX(ddebug_lock);
61 static LIST_HEAD(ddebug_tables);
62 static int verbose = 0;
63 module_param(verbose, int, 0644);
65 /* Return the last part of a pathname */
66 static inline const char *basename(const char *path)
68 const char *tail = strrchr(path, '/');
69 return tail ? tail+1 : path;
72 /* Return the path relative to source root */
73 static inline const char *trim_prefix(const char *path)
75 int skip = strlen(__FILE__) - strlen("lib/dynamic_debug.c");
77 if (strncmp(path, __FILE__, skip))
78 skip = 0; /* prefix mismatch, don't skip */
83 static struct { unsigned flag:8; char opt_char; } opt_array[] = {
84 { _DPRINTK_FLAGS_PRINT, 'p' },
85 { _DPRINTK_FLAGS_INCL_MODNAME, 'm' },
86 { _DPRINTK_FLAGS_INCL_FUNCNAME, 'f' },
87 { _DPRINTK_FLAGS_INCL_LINENO, 'l' },
88 { _DPRINTK_FLAGS_INCL_TID, 't' },
89 { _DPRINTK_FLAGS_NONE, '_' },
92 /* format a string into buf[] which describes the _ddebug's flags */
93 static char *ddebug_describe_flags(struct _ddebug *dp, char *buf,
100 for (i = 0; i < ARRAY_SIZE(opt_array); ++i)
101 if (dp->flags & opt_array[i].flag)
102 *p++ = opt_array[i].opt_char;
110 #define vpr_info(fmt, ...) \
111 if (verbose) do { pr_info(fmt, ##__VA_ARGS__); } while (0)
113 #define vpr_info_dq(q, msg) \
115 /* trim last char off format print */ \
116 vpr_info("%s: func=\"%s\" file=\"%s\" " \
117 "module=\"%s\" format=\"%.*s\" " \
120 q->function ? q->function : "", \
121 q->filename ? q->filename : "", \
122 q->module ? q->module : "", \
123 (int)(q->format ? strlen(q->format) - 1 : 0), \
124 q->format ? q->format : "", \
125 q->first_lineno, q->last_lineno); \
129 * Search the tables for _ddebug's which match the given `query' and
130 * apply the `flags' and `mask' to them. Returns number of matching
131 * callsites, normally the same as number of changes. If verbose,
132 * logs the changes. Takes ddebug_lock.
134 static int ddebug_change(const struct ddebug_query *query,
135 unsigned int flags, unsigned int mask)
138 struct ddebug_table *dt;
139 unsigned int newflags;
140 unsigned int nfound = 0;
143 /* search for matching ddebugs */
144 mutex_lock(&ddebug_lock);
145 list_for_each_entry(dt, &ddebug_tables, link) {
147 /* match against the module name */
148 if (query->module && strcmp(query->module, dt->mod_name))
151 for (i = 0 ; i < dt->num_ddebugs ; i++) {
152 struct _ddebug *dp = &dt->ddebugs[i];
154 /* match against the source filename */
155 if (query->filename &&
156 strcmp(query->filename, dp->filename) &&
157 strcmp(query->filename, basename(dp->filename)) &&
158 strcmp(query->filename, trim_prefix(dp->filename)))
161 /* match against the function */
162 if (query->function &&
163 strcmp(query->function, dp->function))
166 /* match against the format */
168 !strstr(dp->format, query->format))
171 /* match against the line number range */
172 if (query->first_lineno &&
173 dp->lineno < query->first_lineno)
175 if (query->last_lineno &&
176 dp->lineno > query->last_lineno)
181 newflags = (dp->flags & mask) | flags;
182 if (newflags == dp->flags)
184 dp->flags = newflags;
185 vpr_info("changed %s:%d [%s]%s =%s\n",
186 trim_prefix(dp->filename), dp->lineno,
187 dt->mod_name, dp->function,
188 ddebug_describe_flags(dp, flagbuf,
192 mutex_unlock(&ddebug_lock);
194 if (!nfound && verbose)
195 pr_info("no matches for query\n");
201 * Split the buffer `buf' into space-separated words.
202 * Handles simple " and ' quoting, i.e. without nested,
203 * embedded or escaped \". Return the number of words
206 static int ddebug_tokenize(char *buf, char *words[], int maxwords)
213 /* Skip leading whitespace */
214 buf = skip_spaces(buf);
216 break; /* oh, it was trailing whitespace */
218 break; /* token starts comment, skip rest of line */
220 /* find `end' of word, whitespace separated or quoted */
221 if (*buf == '"' || *buf == '\'') {
223 for (end = buf ; *end && *end != quote ; end++)
226 return -EINVAL; /* unclosed quote */
228 for (end = buf ; *end && !isspace(*end) ; end++)
233 /* `buf' is start of word, `end' is one past its end */
234 if (nwords == maxwords)
235 return -EINVAL; /* ran out of words[] before bytes */
237 *end++ = '\0'; /* terminate the word */
238 words[nwords++] = buf;
244 pr_info("split into words:");
245 for (i = 0 ; i < nwords ; i++)
246 pr_cont(" \"%s\"", words[i]);
254 * Parse a single line number. Note that the empty string ""
255 * is treated as a special case and converted to zero, which
256 * is later treated as a "don't care" value.
258 static inline int parse_lineno(const char *str, unsigned int *val)
266 *val = simple_strtoul(str, &end, 10);
267 return end == NULL || end == str || *end != '\0' ? -EINVAL : 0;
271 * Undo octal escaping in a string, inplace. This is useful to
272 * allow the user to express a query which matches a format
273 * containing embedded spaces.
275 #define isodigit(c) ((c) >= '0' && (c) <= '7')
276 static char *unescape(char *str)
287 } else if (in[1] == 't') {
291 } else if (in[1] == 'n') {
295 } else if (isodigit(in[1]) &&
298 *out++ = ((in[1] - '0')<<6) |
312 static int check_set(const char **dest, char *src, char *name)
318 pr_err("match-spec:%s val:%s overridden by %s",
326 * Parse words[] as a ddebug query specification, which is a series
327 * of (keyword, value) pairs chosen from these possibilities:
329 * func <function-name>
330 * file <full-pathname>
331 * file <base-filename>
332 * module <module-name>
333 * format <escaped-string-to-find-in-format>
335 * line <first-lineno>-<last-lineno> // where either may be empty
337 * Only 1 of each type is allowed.
338 * Returns 0 on success, <0 on error.
340 static int ddebug_parse_query(char *words[], int nwords,
341 struct ddebug_query *query, const char *modname)
346 /* check we have an even number of words */
349 memset(query, 0, sizeof(*query));
352 /* support $modname.dyndbg=<multiple queries> */
353 query->module = modname;
355 for (i = 0 ; i < nwords ; i += 2) {
356 if (!strcmp(words[i], "func"))
357 rc = check_set(&query->function, words[i+1], "func");
358 else if (!strcmp(words[i], "file"))
359 rc = check_set(&query->filename, words[i+1], "file");
360 else if (!strcmp(words[i], "module"))
361 rc = check_set(&query->module, words[i+1], "module");
362 else if (!strcmp(words[i], "format"))
363 rc = check_set(&query->format, unescape(words[i+1]),
365 else if (!strcmp(words[i], "line")) {
366 char *first = words[i+1];
367 char *last = strchr(first, '-');
368 if (query->first_lineno || query->last_lineno) {
369 pr_err("match-spec:line given 2 times\n");
374 if (parse_lineno(first, &query->first_lineno) < 0)
377 /* range <first>-<last> */
378 if (parse_lineno(last, &query->last_lineno)
379 < query->first_lineno) {
380 pr_err("last-line < 1st-line\n");
384 query->last_lineno = query->first_lineno;
387 pr_err("unknown keyword \"%s\"\n", words[i]);
393 vpr_info_dq(query, "parsed");
398 * Parse `str' as a flags specification, format [-+=][p]+.
399 * Sets up *maskp and *flagsp to be used when changing the
400 * flags fields of matched _ddebug's. Returns 0 on success
403 static int ddebug_parse_flags(const char *str, unsigned int *flagsp,
418 vpr_info("op='%c'\n", op);
420 for ( ; *str ; ++str) {
421 for (i = ARRAY_SIZE(opt_array) - 1; i >= 0; i--) {
422 if (*str == opt_array[i].opt_char) {
423 flags |= opt_array[i].flag;
430 vpr_info("flags=0x%x\n", flags);
432 /* calculate final *flagsp, *maskp according to mask and op */
447 vpr_info("*flagsp=0x%x *maskp=0x%x\n", *flagsp, *maskp);
451 static int ddebug_exec_query(char *query_string, const char *modname)
453 unsigned int flags = 0, mask = 0;
454 struct ddebug_query query;
457 char *words[MAXWORDS];
459 nwords = ddebug_tokenize(query_string, words, MAXWORDS);
462 if (ddebug_parse_query(words, nwords-1, &query, modname))
464 if (ddebug_parse_flags(words[nwords-1], &flags, &mask))
467 /* actually go and implement the change */
468 nfound = ddebug_change(&query, flags, mask);
469 vpr_info_dq((&query), (nfound) ? "applied" : "no-match");
474 /* handle multiple queries in query string, continue on error, return
475 last error or number of matching callsites. Module name is either
476 in param (for boot arg) or perhaps in query string.
478 static int ddebug_exec_queries(char *query, const char *modname)
481 int i, errs = 0, exitcode = 0, rc, nfound = 0;
483 for (i = 0; query; query = split) {
484 split = strpbrk(query, ";\n");
488 query = skip_spaces(query);
489 if (!query || !*query || *query == '#')
492 vpr_info("query %d: \"%s\"\n", i, query);
494 rc = ddebug_exec_query(query, modname);
502 vpr_info("processed %d queries, with %d matches, %d errs\n",
510 #define PREFIX_SIZE 64
512 static int remaining(int wrote)
514 if (PREFIX_SIZE - wrote > 0)
515 return PREFIX_SIZE - wrote;
519 static char *dynamic_emit_prefix(const struct _ddebug *desc, char *buf)
524 pos += snprintf(buf + pos, remaining(pos), "%s", KERN_DEBUG);
525 if (desc->flags & _DPRINTK_FLAGS_INCL_TID) {
527 pos += snprintf(buf + pos, remaining(pos), "%s ",
530 pos += snprintf(buf + pos, remaining(pos), "[%d] ",
531 task_pid_vnr(current));
534 if (desc->flags & _DPRINTK_FLAGS_INCL_MODNAME)
535 pos += snprintf(buf + pos, remaining(pos), "%s:",
537 if (desc->flags & _DPRINTK_FLAGS_INCL_FUNCNAME)
538 pos += snprintf(buf + pos, remaining(pos), "%s:",
540 if (desc->flags & _DPRINTK_FLAGS_INCL_LINENO)
541 pos += snprintf(buf + pos, remaining(pos), "%d:",
543 if (pos - pos_after_tid)
544 pos += snprintf(buf + pos, remaining(pos), " ");
545 if (pos >= PREFIX_SIZE)
546 buf[PREFIX_SIZE - 1] = '\0';
551 int __dynamic_pr_debug(struct _ddebug *descriptor, const char *fmt, ...)
555 struct va_format vaf;
556 char buf[PREFIX_SIZE];
564 res = printk("%s%pV", dynamic_emit_prefix(descriptor, buf), &vaf);
569 EXPORT_SYMBOL(__dynamic_pr_debug);
571 int __dynamic_dev_dbg(struct _ddebug *descriptor,
572 const struct device *dev, const char *fmt, ...)
574 struct va_format vaf;
577 char buf[PREFIX_SIZE];
585 res = __dev_printk(dynamic_emit_prefix(descriptor, buf), dev, &vaf);
590 EXPORT_SYMBOL(__dynamic_dev_dbg);
594 int __dynamic_netdev_dbg(struct _ddebug *descriptor,
595 const struct net_device *dev, const char *fmt, ...)
597 struct va_format vaf;
600 char buf[PREFIX_SIZE];
608 res = __netdev_printk(dynamic_emit_prefix(descriptor, buf), dev, &vaf);
613 EXPORT_SYMBOL(__dynamic_netdev_dbg);
617 #define DDEBUG_STRING_SIZE 1024
618 static __initdata char ddebug_setup_string[DDEBUG_STRING_SIZE];
620 static __init int ddebug_setup_query(char *str)
622 if (strlen(str) >= DDEBUG_STRING_SIZE) {
623 pr_warn("ddebug boot param string too large\n");
626 strlcpy(ddebug_setup_string, str, DDEBUG_STRING_SIZE);
630 __setup("ddebug_query=", ddebug_setup_query);
633 * File_ops->write method for <debugfs>/dynamic_debug/conrol. Gathers the
634 * command text from userspace, parses and executes it.
636 #define USER_BUF_PAGE 4096
637 static ssize_t ddebug_proc_write(struct file *file, const char __user *ubuf,
638 size_t len, loff_t *offp)
645 if (len > USER_BUF_PAGE - 1) {
646 pr_warn("expected <%d bytes into control\n", USER_BUF_PAGE);
649 tmpbuf = kmalloc(len + 1, GFP_KERNEL);
652 if (copy_from_user(tmpbuf, ubuf, len)) {
657 vpr_info("read %d bytes from userspace\n", (int)len);
659 ret = ddebug_exec_queries(tmpbuf, NULL);
669 * Set the iterator to point to the first _ddebug object
670 * and return a pointer to that first object. Returns
671 * NULL if there are no _ddebugs at all.
673 static struct _ddebug *ddebug_iter_first(struct ddebug_iter *iter)
675 if (list_empty(&ddebug_tables)) {
680 iter->table = list_entry(ddebug_tables.next,
681 struct ddebug_table, link);
683 return &iter->table->ddebugs[iter->idx];
687 * Advance the iterator to point to the next _ddebug
688 * object from the one the iterator currently points at,
689 * and returns a pointer to the new _ddebug. Returns
690 * NULL if the iterator has seen all the _ddebugs.
692 static struct _ddebug *ddebug_iter_next(struct ddebug_iter *iter)
694 if (iter->table == NULL)
696 if (++iter->idx == iter->table->num_ddebugs) {
697 /* iterate to next table */
699 if (list_is_last(&iter->table->link, &ddebug_tables)) {
703 iter->table = list_entry(iter->table->link.next,
704 struct ddebug_table, link);
706 return &iter->table->ddebugs[iter->idx];
710 * Seq_ops start method. Called at the start of every
711 * read() call from userspace. Takes the ddebug_lock and
712 * seeks the seq_file's iterator to the given position.
714 static void *ddebug_proc_start(struct seq_file *m, loff_t *pos)
716 struct ddebug_iter *iter = m->private;
720 vpr_info("called m=%p *pos=%lld\n", m, (unsigned long long)*pos);
722 mutex_lock(&ddebug_lock);
725 return SEQ_START_TOKEN;
728 dp = ddebug_iter_first(iter);
729 while (dp != NULL && --n > 0)
730 dp = ddebug_iter_next(iter);
735 * Seq_ops next method. Called several times within a read()
736 * call from userspace, with ddebug_lock held. Walks to the
737 * next _ddebug object with a special case for the header line.
739 static void *ddebug_proc_next(struct seq_file *m, void *p, loff_t *pos)
741 struct ddebug_iter *iter = m->private;
744 vpr_info("called m=%p p=%p *pos=%lld\n",
745 m, p, (unsigned long long)*pos);
747 if (p == SEQ_START_TOKEN)
748 dp = ddebug_iter_first(iter);
750 dp = ddebug_iter_next(iter);
756 * Seq_ops show method. Called several times within a read()
757 * call from userspace, with ddebug_lock held. Formats the
758 * current _ddebug as a single human-readable line, with a
759 * special case for the header line.
761 static int ddebug_proc_show(struct seq_file *m, void *p)
763 struct ddebug_iter *iter = m->private;
764 struct _ddebug *dp = p;
767 vpr_info("called m=%p p=%p\n", m, p);
769 if (p == SEQ_START_TOKEN) {
771 "# filename:lineno [module]function flags format\n");
775 seq_printf(m, "%s:%u [%s]%s =%s \"",
776 trim_prefix(dp->filename), dp->lineno,
777 iter->table->mod_name, dp->function,
778 ddebug_describe_flags(dp, flagsbuf, sizeof(flagsbuf)));
779 seq_escape(m, dp->format, "\t\r\n\"");
786 * Seq_ops stop method. Called at the end of each read()
787 * call from userspace. Drops ddebug_lock.
789 static void ddebug_proc_stop(struct seq_file *m, void *p)
791 vpr_info("called m=%p p=%p\n", m, p);
792 mutex_unlock(&ddebug_lock);
795 static const struct seq_operations ddebug_proc_seqops = {
796 .start = ddebug_proc_start,
797 .next = ddebug_proc_next,
798 .show = ddebug_proc_show,
799 .stop = ddebug_proc_stop
803 * File_ops->open method for <debugfs>/dynamic_debug/control. Does
804 * the seq_file setup dance, and also creates an iterator to walk the
805 * _ddebugs. Note that we create a seq_file always, even for O_WRONLY
806 * files where it's not needed, as doing so simplifies the ->release
809 static int ddebug_proc_open(struct inode *inode, struct file *file)
811 struct ddebug_iter *iter;
814 vpr_info("called\n");
816 iter = kzalloc(sizeof(*iter), GFP_KERNEL);
820 err = seq_open(file, &ddebug_proc_seqops);
825 ((struct seq_file *) file->private_data)->private = iter;
829 static const struct file_operations ddebug_proc_fops = {
830 .owner = THIS_MODULE,
831 .open = ddebug_proc_open,
834 .release = seq_release_private,
835 .write = ddebug_proc_write
839 * Allocate a new ddebug_table for the given module
840 * and add it to the global list.
842 int ddebug_add_module(struct _ddebug *tab, unsigned int n,
845 struct ddebug_table *dt;
848 dt = kzalloc(sizeof(*dt), GFP_KERNEL);
851 new_name = kstrdup(name, GFP_KERNEL);
852 if (new_name == NULL) {
856 dt->mod_name = new_name;
860 mutex_lock(&ddebug_lock);
861 list_add_tail(&dt->link, &ddebug_tables);
862 mutex_unlock(&ddebug_lock);
864 vpr_info("%u debug prints in module %s\n", n, dt->mod_name);
867 EXPORT_SYMBOL_GPL(ddebug_add_module);
869 /* helper for ddebug_dyndbg_(boot|module)_param_cb */
870 static int ddebug_dyndbg_param_cb(char *param, char *val,
871 const char *modname, int on_err)
875 sep = strchr(param, '.');
877 /* needed only for ddebug_dyndbg_boot_param_cb */
882 if (strcmp(param, "dyndbg"))
883 return on_err; /* determined by caller */
885 ddebug_exec_queries((val ? val : "+p"), modname);
887 return 0; /* query failure shouldnt stop module load */
890 /* handle both dyndbg and $module.dyndbg params at boot */
891 static int ddebug_dyndbg_boot_param_cb(char *param, char *val,
894 vpr_info("%s=\"%s\"\n", param, val);
895 return ddebug_dyndbg_param_cb(param, val, NULL, 0);
899 * modprobe foo finds foo.params in boot-args, strips "foo.", and
900 * passes them to load_module(). This callback gets unknown params,
901 * processes dyndbg params, rejects others.
903 int ddebug_dyndbg_module_param_cb(char *param, char *val, const char *module)
905 vpr_info("module: %s %s=\"%s\"\n", module, param, val);
906 return ddebug_dyndbg_param_cb(param, val, module, -ENOENT);
909 static void ddebug_table_free(struct ddebug_table *dt)
911 list_del_init(&dt->link);
917 * Called in response to a module being unloaded. Removes
918 * any ddebug_table's which point at the module.
920 int ddebug_remove_module(const char *mod_name)
922 struct ddebug_table *dt, *nextdt;
925 vpr_info("removing module \"%s\"\n", mod_name);
927 mutex_lock(&ddebug_lock);
928 list_for_each_entry_safe(dt, nextdt, &ddebug_tables, link) {
929 if (!strcmp(dt->mod_name, mod_name)) {
930 ddebug_table_free(dt);
934 mutex_unlock(&ddebug_lock);
937 EXPORT_SYMBOL_GPL(ddebug_remove_module);
939 static void ddebug_remove_all_tables(void)
941 mutex_lock(&ddebug_lock);
942 while (!list_empty(&ddebug_tables)) {
943 struct ddebug_table *dt = list_entry(ddebug_tables.next,
946 ddebug_table_free(dt);
948 mutex_unlock(&ddebug_lock);
951 static __initdata int ddebug_init_success;
953 static int __init dynamic_debug_init_debugfs(void)
955 struct dentry *dir, *file;
957 if (!ddebug_init_success)
960 dir = debugfs_create_dir("dynamic_debug", NULL);
963 file = debugfs_create_file("control", 0644, dir, NULL,
972 static int __init dynamic_debug_init(void)
974 struct _ddebug *iter, *iter_start;
975 const char *modname = NULL;
978 int n = 0, entries = 0, modct = 0;
979 int verbose_bytes = 0;
981 if (__start___verbose == __stop___verbose) {
982 pr_warn("_ddebug table is empty in a "
983 "CONFIG_DYNAMIC_DEBUG build");
986 iter = __start___verbose;
987 modname = iter->modname;
989 for (; iter < __stop___verbose; iter++) {
991 verbose_bytes += strlen(iter->modname) + strlen(iter->function)
992 + strlen(iter->filename) + strlen(iter->format);
994 if (strcmp(modname, iter->modname)) {
996 ret = ddebug_add_module(iter_start, n, modname);
1000 modname = iter->modname;
1005 ret = ddebug_add_module(iter_start, n, modname);
1009 ddebug_init_success = 1;
1010 vpr_info("%d modules, %d entries and %d bytes in ddebug tables,"
1011 " %d bytes in (readonly) verbose section\n",
1012 modct, entries, (int)( modct * sizeof(struct ddebug_table)),
1013 verbose_bytes + (int)(__stop___verbose - __start___verbose));
1015 /* apply ddebug_query boot param, dont unload tables on err */
1016 if (ddebug_setup_string[0] != '\0') {
1017 pr_warn("ddebug_query param name is deprecated,"
1018 " change it to dyndbg\n");
1019 ret = ddebug_exec_queries(ddebug_setup_string, NULL);
1021 pr_warn("Invalid ddebug boot param %s",
1022 ddebug_setup_string);
1024 pr_info("%d changes by ddebug_query\n", ret);
1026 /* now that ddebug tables are loaded, process all boot args
1027 * again to find and activate queries given in dyndbg params.
1028 * While this has already been done for known boot params, it
1029 * ignored the unknown ones (dyndbg in particular). Reusing
1030 * parse_args avoids ad-hoc parsing. This will also attempt
1031 * to activate queries for not-yet-loaded modules, which is
1032 * slightly noisy if verbose, but harmless.
1034 cmdline = kstrdup(saved_command_line, GFP_KERNEL);
1035 parse_args("dyndbg params", cmdline, NULL,
1036 0, 0, 0, &ddebug_dyndbg_boot_param_cb);
1041 ddebug_remove_all_tables();
1044 /* Allow early initialization for boot messages via boot param */
1045 early_initcall(dynamic_debug_init);
1047 /* Debugfs setup must be done later */
1048 fs_initcall(dynamic_debug_init_debugfs);