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_dq(q, msg) \
113 /* trim last char off format print */ \
114 pr_info("%s: func=\"%s\" file=\"%s\" " \
115 "module=\"%s\" format=\"%.*s\" " \
118 q->function ? q->function : "", \
119 q->filename ? q->filename : "", \
120 q->module ? q->module : "", \
121 (int)(q->format ? strlen(q->format) - 1 : 0), \
122 q->format ? q->format : "", \
123 q->first_lineno, q->last_lineno); \
127 * Search the tables for _ddebug's which match the given `query' and
128 * apply the `flags' and `mask' to them. Returns number of matching
129 * callsites, normally the same as number of changes. If verbose,
130 * logs the changes. Takes ddebug_lock.
132 static int ddebug_change(const struct ddebug_query *query,
133 unsigned int flags, unsigned int mask)
136 struct ddebug_table *dt;
137 unsigned int newflags;
138 unsigned int nfound = 0;
141 /* search for matching ddebugs */
142 mutex_lock(&ddebug_lock);
143 list_for_each_entry(dt, &ddebug_tables, link) {
145 /* match against the module name */
146 if (query->module && strcmp(query->module, dt->mod_name))
149 for (i = 0 ; i < dt->num_ddebugs ; i++) {
150 struct _ddebug *dp = &dt->ddebugs[i];
152 /* match against the source filename */
153 if (query->filename &&
154 strcmp(query->filename, dp->filename) &&
155 strcmp(query->filename, basename(dp->filename)) &&
156 strcmp(query->filename, trim_prefix(dp->filename)))
159 /* match against the function */
160 if (query->function &&
161 strcmp(query->function, dp->function))
164 /* match against the format */
166 !strstr(dp->format, query->format))
169 /* match against the line number range */
170 if (query->first_lineno &&
171 dp->lineno < query->first_lineno)
173 if (query->last_lineno &&
174 dp->lineno > query->last_lineno)
179 newflags = (dp->flags & mask) | flags;
180 if (newflags == dp->flags)
182 dp->flags = newflags;
184 pr_info("changed %s:%d [%s]%s =%s\n",
185 trim_prefix(dp->filename), dp->lineno,
186 dt->mod_name, dp->function,
187 ddebug_describe_flags(dp, flagbuf,
191 mutex_unlock(&ddebug_lock);
193 if (!nfound && verbose)
194 pr_info("no matches for query\n");
200 * Split the buffer `buf' into space-separated words.
201 * Handles simple " and ' quoting, i.e. without nested,
202 * embedded or escaped \". Return the number of words
205 static int ddebug_tokenize(char *buf, char *words[], int maxwords)
212 /* Skip leading whitespace */
213 buf = skip_spaces(buf);
215 break; /* oh, it was trailing whitespace */
217 break; /* token starts comment, skip rest of line */
219 /* find `end' of word, whitespace separated or quoted */
220 if (*buf == '"' || *buf == '\'') {
222 for (end = buf ; *end && *end != quote ; end++)
225 return -EINVAL; /* unclosed quote */
227 for (end = buf ; *end && !isspace(*end) ; end++)
232 /* `buf' is start of word, `end' is one past its end */
233 if (nwords == maxwords)
234 return -EINVAL; /* ran out of words[] before bytes */
236 *end++ = '\0'; /* terminate the word */
237 words[nwords++] = buf;
243 pr_info("split into words:");
244 for (i = 0 ; i < nwords ; i++)
245 pr_cont(" \"%s\"", words[i]);
253 * Parse a single line number. Note that the empty string ""
254 * is treated as a special case and converted to zero, which
255 * is later treated as a "don't care" value.
257 static inline int parse_lineno(const char *str, unsigned int *val)
265 *val = simple_strtoul(str, &end, 10);
266 return end == NULL || end == str || *end != '\0' ? -EINVAL : 0;
270 * Undo octal escaping in a string, inplace. This is useful to
271 * allow the user to express a query which matches a format
272 * containing embedded spaces.
274 #define isodigit(c) ((c) >= '0' && (c) <= '7')
275 static char *unescape(char *str)
286 } else if (in[1] == 't') {
290 } else if (in[1] == 'n') {
294 } else if (isodigit(in[1]) &&
297 *out++ = ((in[1] - '0')<<6) |
311 static int check_set(const char **dest, char *src, char *name)
317 pr_err("match-spec:%s val:%s overridden by %s",
325 * Parse words[] as a ddebug query specification, which is a series
326 * of (keyword, value) pairs chosen from these possibilities:
328 * func <function-name>
329 * file <full-pathname>
330 * file <base-filename>
331 * module <module-name>
332 * format <escaped-string-to-find-in-format>
334 * line <first-lineno>-<last-lineno> // where either may be empty
336 * Only 1 of each type is allowed.
337 * Returns 0 on success, <0 on error.
339 static int ddebug_parse_query(char *words[], int nwords,
340 struct ddebug_query *query)
345 /* check we have an even number of words */
348 memset(query, 0, sizeof(*query));
350 for (i = 0 ; i < nwords ; i += 2) {
351 if (!strcmp(words[i], "func"))
352 rc = check_set(&query->function, words[i+1], "func");
353 else if (!strcmp(words[i], "file"))
354 rc = check_set(&query->filename, words[i+1], "file");
355 else if (!strcmp(words[i], "module"))
356 rc = check_set(&query->module, words[i+1], "module");
357 else if (!strcmp(words[i], "format"))
358 rc = check_set(&query->format, unescape(words[i+1]),
360 else if (!strcmp(words[i], "line")) {
361 char *first = words[i+1];
362 char *last = strchr(first, '-');
363 if (query->first_lineno || query->last_lineno) {
364 pr_err("match-spec:line given 2 times\n");
369 if (parse_lineno(first, &query->first_lineno) < 0)
372 /* range <first>-<last> */
373 if (parse_lineno(last, &query->last_lineno)
374 < query->first_lineno) {
375 pr_err("last-line < 1st-line\n");
379 query->last_lineno = query->first_lineno;
382 pr_err("unknown keyword \"%s\"\n", words[i]);
388 vpr_info_dq(query, "parsed");
393 * Parse `str' as a flags specification, format [-+=][p]+.
394 * Sets up *maskp and *flagsp to be used when changing the
395 * flags fields of matched _ddebug's. Returns 0 on success
398 static int ddebug_parse_flags(const char *str, unsigned int *flagsp,
414 pr_info("op='%c'\n", op);
416 for ( ; *str ; ++str) {
417 for (i = ARRAY_SIZE(opt_array) - 1; i >= 0; i--) {
418 if (*str == opt_array[i].opt_char) {
419 flags |= opt_array[i].flag;
427 pr_info("flags=0x%x\n", flags);
429 /* calculate final *flagsp, *maskp according to mask and op */
445 pr_info("*flagsp=0x%x *maskp=0x%x\n", *flagsp, *maskp);
449 static int ddebug_exec_query(char *query_string)
451 unsigned int flags = 0, mask = 0;
452 struct ddebug_query query;
455 char *words[MAXWORDS];
457 nwords = ddebug_tokenize(query_string, words, MAXWORDS);
460 if (ddebug_parse_query(words, nwords-1, &query))
462 if (ddebug_parse_flags(words[nwords-1], &flags, &mask))
465 /* actually go and implement the change */
466 nfound = ddebug_change(&query, flags, mask);
467 vpr_info_dq((&query), (nfound) ? "applied" : "no-match");
472 /* handle multiple queries in query string, continue on error, return
473 last error or number of matching callsites. Module name is either
474 in param (for boot arg) or perhaps in query string.
476 static int ddebug_exec_queries(char *query)
479 int i, errs = 0, exitcode = 0, rc, nfound = 0;
481 for (i = 0; query; query = split) {
482 split = strpbrk(query, ";\n");
486 query = skip_spaces(query);
487 if (!query || !*query || *query == '#')
491 pr_info("query %d: \"%s\"\n", i, query);
493 rc = ddebug_exec_query(query);
501 pr_info("processed %d queries, with %d matches, %d errs\n",
509 #define PREFIX_SIZE 64
511 static int remaining(int wrote)
513 if (PREFIX_SIZE - wrote > 0)
514 return PREFIX_SIZE - wrote;
518 static char *dynamic_emit_prefix(const struct _ddebug *desc, char *buf)
523 pos += snprintf(buf + pos, remaining(pos), "%s", KERN_DEBUG);
524 if (desc->flags & _DPRINTK_FLAGS_INCL_TID) {
526 pos += snprintf(buf + pos, remaining(pos), "%s ",
529 pos += snprintf(buf + pos, remaining(pos), "[%d] ",
530 task_pid_vnr(current));
533 if (desc->flags & _DPRINTK_FLAGS_INCL_MODNAME)
534 pos += snprintf(buf + pos, remaining(pos), "%s:",
536 if (desc->flags & _DPRINTK_FLAGS_INCL_FUNCNAME)
537 pos += snprintf(buf + pos, remaining(pos), "%s:",
539 if (desc->flags & _DPRINTK_FLAGS_INCL_LINENO)
540 pos += snprintf(buf + pos, remaining(pos), "%d:",
542 if (pos - pos_after_tid)
543 pos += snprintf(buf + pos, remaining(pos), " ");
544 if (pos >= PREFIX_SIZE)
545 buf[PREFIX_SIZE - 1] = '\0';
550 int __dynamic_pr_debug(struct _ddebug *descriptor, const char *fmt, ...)
554 struct va_format vaf;
555 char buf[PREFIX_SIZE];
563 res = printk("%s%pV", dynamic_emit_prefix(descriptor, buf), &vaf);
568 EXPORT_SYMBOL(__dynamic_pr_debug);
570 int __dynamic_dev_dbg(struct _ddebug *descriptor,
571 const struct device *dev, const char *fmt, ...)
573 struct va_format vaf;
576 char buf[PREFIX_SIZE];
584 res = __dev_printk(dynamic_emit_prefix(descriptor, buf), dev, &vaf);
589 EXPORT_SYMBOL(__dynamic_dev_dbg);
593 int __dynamic_netdev_dbg(struct _ddebug *descriptor,
594 const struct net_device *dev, const char *fmt, ...)
596 struct va_format vaf;
599 char buf[PREFIX_SIZE];
607 res = __netdev_printk(dynamic_emit_prefix(descriptor, buf), dev, &vaf);
612 EXPORT_SYMBOL(__dynamic_netdev_dbg);
616 #define DDEBUG_STRING_SIZE 1024
617 static __initdata char ddebug_setup_string[DDEBUG_STRING_SIZE];
619 static __init int ddebug_setup_query(char *str)
621 if (strlen(str) >= DDEBUG_STRING_SIZE) {
622 pr_warn("ddebug boot param string too large\n");
625 strlcpy(ddebug_setup_string, str, DDEBUG_STRING_SIZE);
629 __setup("ddebug_query=", ddebug_setup_query);
632 * File_ops->write method for <debugfs>/dynamic_debug/conrol. Gathers the
633 * command text from userspace, parses and executes it.
635 #define USER_BUF_PAGE 4096
636 static ssize_t ddebug_proc_write(struct file *file, const char __user *ubuf,
637 size_t len, loff_t *offp)
644 if (len > USER_BUF_PAGE - 1) {
645 pr_warn("expected <%d bytes into control\n", USER_BUF_PAGE);
648 tmpbuf = kmalloc(len + 1, GFP_KERNEL);
651 if (copy_from_user(tmpbuf, ubuf, len)) {
657 pr_info("read %d bytes from userspace\n", (int)len);
659 ret = ddebug_exec_queries(tmpbuf);
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;
721 pr_info("called m=%p *pos=%lld\n", m, (unsigned long long)*pos);
723 mutex_lock(&ddebug_lock);
726 return SEQ_START_TOKEN;
729 dp = ddebug_iter_first(iter);
730 while (dp != NULL && --n > 0)
731 dp = ddebug_iter_next(iter);
736 * Seq_ops next method. Called several times within a read()
737 * call from userspace, with ddebug_lock held. Walks to the
738 * next _ddebug object with a special case for the header line.
740 static void *ddebug_proc_next(struct seq_file *m, void *p, loff_t *pos)
742 struct ddebug_iter *iter = m->private;
746 pr_info("called m=%p p=%p *pos=%lld\n",
747 m, p, (unsigned long long)*pos);
749 if (p == SEQ_START_TOKEN)
750 dp = ddebug_iter_first(iter);
752 dp = ddebug_iter_next(iter);
758 * Seq_ops show method. Called several times within a read()
759 * call from userspace, with ddebug_lock held. Formats the
760 * current _ddebug as a single human-readable line, with a
761 * special case for the header line.
763 static int ddebug_proc_show(struct seq_file *m, void *p)
765 struct ddebug_iter *iter = m->private;
766 struct _ddebug *dp = p;
770 pr_info("called m=%p p=%p\n", m, p);
772 if (p == SEQ_START_TOKEN) {
774 "# filename:lineno [module]function flags format\n");
778 seq_printf(m, "%s:%u [%s]%s =%s \"",
779 trim_prefix(dp->filename), dp->lineno,
780 iter->table->mod_name, dp->function,
781 ddebug_describe_flags(dp, flagsbuf, sizeof(flagsbuf)));
782 seq_escape(m, dp->format, "\t\r\n\"");
789 * Seq_ops stop method. Called at the end of each read()
790 * call from userspace. Drops ddebug_lock.
792 static void ddebug_proc_stop(struct seq_file *m, void *p)
795 pr_info("called m=%p p=%p\n", m, p);
796 mutex_unlock(&ddebug_lock);
799 static const struct seq_operations ddebug_proc_seqops = {
800 .start = ddebug_proc_start,
801 .next = ddebug_proc_next,
802 .show = ddebug_proc_show,
803 .stop = ddebug_proc_stop
807 * File_ops->open method for <debugfs>/dynamic_debug/control. Does
808 * the seq_file setup dance, and also creates an iterator to walk the
809 * _ddebugs. Note that we create a seq_file always, even for O_WRONLY
810 * files where it's not needed, as doing so simplifies the ->release
813 static int ddebug_proc_open(struct inode *inode, struct file *file)
815 struct ddebug_iter *iter;
821 iter = kzalloc(sizeof(*iter), GFP_KERNEL);
825 err = seq_open(file, &ddebug_proc_seqops);
830 ((struct seq_file *) file->private_data)->private = iter;
834 static const struct file_operations ddebug_proc_fops = {
835 .owner = THIS_MODULE,
836 .open = ddebug_proc_open,
839 .release = seq_release_private,
840 .write = ddebug_proc_write
844 * Allocate a new ddebug_table for the given module
845 * and add it to the global list.
847 int ddebug_add_module(struct _ddebug *tab, unsigned int n,
850 struct ddebug_table *dt;
853 dt = kzalloc(sizeof(*dt), GFP_KERNEL);
856 new_name = kstrdup(name, GFP_KERNEL);
857 if (new_name == NULL) {
861 dt->mod_name = new_name;
865 mutex_lock(&ddebug_lock);
866 list_add_tail(&dt->link, &ddebug_tables);
867 mutex_unlock(&ddebug_lock);
870 pr_info("%u debug prints in module %s\n", n, dt->mod_name);
873 EXPORT_SYMBOL_GPL(ddebug_add_module);
875 static void ddebug_table_free(struct ddebug_table *dt)
877 list_del_init(&dt->link);
883 * Called in response to a module being unloaded. Removes
884 * any ddebug_table's which point at the module.
886 int ddebug_remove_module(const char *mod_name)
888 struct ddebug_table *dt, *nextdt;
892 pr_info("removing module \"%s\"\n", mod_name);
894 mutex_lock(&ddebug_lock);
895 list_for_each_entry_safe(dt, nextdt, &ddebug_tables, link) {
896 if (!strcmp(dt->mod_name, mod_name)) {
897 ddebug_table_free(dt);
901 mutex_unlock(&ddebug_lock);
904 EXPORT_SYMBOL_GPL(ddebug_remove_module);
906 static void ddebug_remove_all_tables(void)
908 mutex_lock(&ddebug_lock);
909 while (!list_empty(&ddebug_tables)) {
910 struct ddebug_table *dt = list_entry(ddebug_tables.next,
913 ddebug_table_free(dt);
915 mutex_unlock(&ddebug_lock);
918 static __initdata int ddebug_init_success;
920 static int __init dynamic_debug_init_debugfs(void)
922 struct dentry *dir, *file;
924 if (!ddebug_init_success)
927 dir = debugfs_create_dir("dynamic_debug", NULL);
930 file = debugfs_create_file("control", 0644, dir, NULL,
939 static int __init dynamic_debug_init(void)
941 struct _ddebug *iter, *iter_start;
942 const char *modname = NULL;
946 if (__start___verbose == __stop___verbose) {
947 pr_warn("_ddebug table is empty in a "
948 "CONFIG_DYNAMIC_DEBUG build");
951 iter = __start___verbose;
952 modname = iter->modname;
954 for (; iter < __stop___verbose; iter++) {
955 if (strcmp(modname, iter->modname)) {
956 ret = ddebug_add_module(iter_start, n, modname);
960 modname = iter->modname;
965 ret = ddebug_add_module(iter_start, n, modname);
969 /* ddebug_query boot param got passed -> set it up */
970 if (ddebug_setup_string[0] != '\0') {
971 ret = ddebug_exec_queries(ddebug_setup_string);
973 pr_warn("Invalid ddebug boot param %s",
974 ddebug_setup_string);
976 pr_info("%d changes by ddebug_query\n", ret);
978 /* keep tables even on ddebug_query parse error */
984 ddebug_remove_all_tables();
986 ddebug_init_success = 1;
989 /* Allow early initialization for boot messages via boot param */
990 arch_initcall(dynamic_debug_init);
991 /* Debugfs setup must be done later */
992 module_init(dynamic_debug_init_debugfs);