1 // SPDX-License-Identifier: GPL-2.0
5 * Copyright (C) 2008 Red Hat Inc, Steven Rostedt <srostedt@redhat.com>
8 #include <linux/module.h>
9 #include <linux/mutex.h>
10 #include <linux/ftrace.h>
11 #include <linux/kprobes.h>
12 #include <linux/sched/clock.h>
13 #include <linux/sched/mm.h>
14 #include <linux/idr.h>
16 #include "trace_output.h"
18 /* must be a power of 2 */
19 #define EVENT_HASHSIZE 128
21 DECLARE_RWSEM(trace_event_sem
);
23 static struct hlist_head event_hash
[EVENT_HASHSIZE
] __read_mostly
;
25 enum print_line_t
trace_print_bputs_msg_only(struct trace_iterator
*iter
)
27 struct trace_seq
*s
= &iter
->seq
;
28 struct trace_entry
*entry
= iter
->ent
;
29 struct bputs_entry
*field
;
31 trace_assign_type(field
, entry
);
33 trace_seq_puts(s
, field
->str
);
35 return trace_handle_return(s
);
38 enum print_line_t
trace_print_bprintk_msg_only(struct trace_iterator
*iter
)
40 struct trace_seq
*s
= &iter
->seq
;
41 struct trace_entry
*entry
= iter
->ent
;
42 struct bprint_entry
*field
;
44 trace_assign_type(field
, entry
);
46 trace_seq_bprintf(s
, field
->fmt
, field
->buf
);
48 return trace_handle_return(s
);
51 enum print_line_t
trace_print_printk_msg_only(struct trace_iterator
*iter
)
53 struct trace_seq
*s
= &iter
->seq
;
54 struct trace_entry
*entry
= iter
->ent
;
55 struct print_entry
*field
;
57 trace_assign_type(field
, entry
);
59 trace_seq_puts(s
, field
->buf
);
61 return trace_handle_return(s
);
65 trace_print_flags_seq(struct trace_seq
*p
, const char *delim
,
67 const struct trace_print_flags
*flag_array
)
71 const char *ret
= trace_seq_buffer_ptr(p
);
74 for (i
= 0; flag_array
[i
].name
&& flags
; i
++) {
76 mask
= flag_array
[i
].mask
;
77 if ((flags
& mask
) != mask
)
80 str
= flag_array
[i
].name
;
83 trace_seq_puts(p
, delim
);
86 trace_seq_puts(p
, str
);
89 /* check for left over flags */
92 trace_seq_puts(p
, delim
);
93 trace_seq_printf(p
, "0x%lx", flags
);
100 EXPORT_SYMBOL(trace_print_flags_seq
);
103 trace_print_symbols_seq(struct trace_seq
*p
, unsigned long val
,
104 const struct trace_print_flags
*symbol_array
)
107 const char *ret
= trace_seq_buffer_ptr(p
);
109 for (i
= 0; symbol_array
[i
].name
; i
++) {
111 if (val
!= symbol_array
[i
].mask
)
114 trace_seq_puts(p
, symbol_array
[i
].name
);
118 if (ret
== (const char *)(trace_seq_buffer_ptr(p
)))
119 trace_seq_printf(p
, "0x%lx", val
);
121 trace_seq_putc(p
, 0);
125 EXPORT_SYMBOL(trace_print_symbols_seq
);
127 #if BITS_PER_LONG == 32
129 trace_print_flags_seq_u64(struct trace_seq
*p
, const char *delim
,
130 unsigned long long flags
,
131 const struct trace_print_flags_u64
*flag_array
)
133 unsigned long long mask
;
135 const char *ret
= trace_seq_buffer_ptr(p
);
138 for (i
= 0; flag_array
[i
].name
&& flags
; i
++) {
140 mask
= flag_array
[i
].mask
;
141 if ((flags
& mask
) != mask
)
144 str
= flag_array
[i
].name
;
147 trace_seq_puts(p
, delim
);
150 trace_seq_puts(p
, str
);
153 /* check for left over flags */
156 trace_seq_puts(p
, delim
);
157 trace_seq_printf(p
, "0x%llx", flags
);
160 trace_seq_putc(p
, 0);
164 EXPORT_SYMBOL(trace_print_flags_seq_u64
);
167 trace_print_symbols_seq_u64(struct trace_seq
*p
, unsigned long long val
,
168 const struct trace_print_flags_u64
*symbol_array
)
171 const char *ret
= trace_seq_buffer_ptr(p
);
173 for (i
= 0; symbol_array
[i
].name
; i
++) {
175 if (val
!= symbol_array
[i
].mask
)
178 trace_seq_puts(p
, symbol_array
[i
].name
);
182 if (ret
== (const char *)(trace_seq_buffer_ptr(p
)))
183 trace_seq_printf(p
, "0x%llx", val
);
185 trace_seq_putc(p
, 0);
189 EXPORT_SYMBOL(trace_print_symbols_seq_u64
);
193 trace_print_bitmask_seq(struct trace_seq
*p
, void *bitmask_ptr
,
194 unsigned int bitmask_size
)
196 const char *ret
= trace_seq_buffer_ptr(p
);
198 trace_seq_bitmask(p
, bitmask_ptr
, bitmask_size
* 8);
199 trace_seq_putc(p
, 0);
203 EXPORT_SYMBOL_GPL(trace_print_bitmask_seq
);
206 * trace_print_hex_seq - print buffer as hex sequence
207 * @p: trace seq struct to write to
208 * @buf: The buffer to print
209 * @buf_len: Length of @buf in bytes
210 * @concatenate: Print @buf as single hex string or with spacing
212 * Prints the passed buffer as a hex sequence either as a whole,
213 * single hex string if @concatenate is true or with spacing after
214 * each byte in case @concatenate is false.
217 trace_print_hex_seq(struct trace_seq
*p
, const unsigned char *buf
, int buf_len
,
221 const char *ret
= trace_seq_buffer_ptr(p
);
222 const char *fmt
= concatenate
? "%*phN" : "%*ph";
224 for (i
= 0; i
< buf_len
; i
+= 16) {
225 if (!concatenate
&& i
!= 0)
226 trace_seq_putc(p
, ' ');
227 trace_seq_printf(p
, fmt
, min(buf_len
- i
, 16), &buf
[i
]);
229 trace_seq_putc(p
, 0);
233 EXPORT_SYMBOL(trace_print_hex_seq
);
236 trace_print_array_seq(struct trace_seq
*p
, const void *buf
, int count
,
239 const char *ret
= trace_seq_buffer_ptr(p
);
240 const char *prefix
= "";
241 void *ptr
= (void *)buf
;
242 size_t buf_len
= count
* el_size
;
244 trace_seq_putc(p
, '{');
246 while (ptr
< buf
+ buf_len
) {
249 trace_seq_printf(p
, "%s0x%x", prefix
,
253 trace_seq_printf(p
, "%s0x%x", prefix
,
257 trace_seq_printf(p
, "%s0x%x", prefix
,
261 trace_seq_printf(p
, "%s0x%llx", prefix
,
265 trace_seq_printf(p
, "BAD SIZE:%zu 0x%x", el_size
,
273 trace_seq_putc(p
, '}');
274 trace_seq_putc(p
, 0);
278 EXPORT_SYMBOL(trace_print_array_seq
);
281 trace_print_hex_dump_seq(struct trace_seq
*p
, const char *prefix_str
,
282 int prefix_type
, int rowsize
, int groupsize
,
283 const void *buf
, size_t len
, bool ascii
)
285 const char *ret
= trace_seq_buffer_ptr(p
);
287 trace_seq_putc(p
, '\n');
288 trace_seq_hex_dump(p
, prefix_str
, prefix_type
,
289 rowsize
, groupsize
, buf
, len
, ascii
);
290 trace_seq_putc(p
, 0);
293 EXPORT_SYMBOL(trace_print_hex_dump_seq
);
295 int trace_raw_output_prep(struct trace_iterator
*iter
,
296 struct trace_event
*trace_event
)
298 struct trace_event_call
*event
;
299 struct trace_seq
*s
= &iter
->seq
;
300 struct trace_seq
*p
= &iter
->tmp_seq
;
301 struct trace_entry
*entry
;
303 event
= container_of(trace_event
, struct trace_event_call
, event
);
306 if (entry
->type
!= event
->event
.type
) {
308 return TRACE_TYPE_UNHANDLED
;
312 trace_seq_printf(s
, "%s: ", trace_event_name(event
));
314 return trace_handle_return(s
);
316 EXPORT_SYMBOL(trace_raw_output_prep
);
318 void trace_event_printf(struct trace_iterator
*iter
, const char *fmt
, ...)
323 trace_check_vprintf(iter
, trace_event_format(iter
, fmt
), ap
);
326 EXPORT_SYMBOL(trace_event_printf
);
328 static __printf(3, 0)
329 int trace_output_raw(struct trace_iterator
*iter
, char *name
,
330 char *fmt
, va_list ap
)
332 struct trace_seq
*s
= &iter
->seq
;
334 trace_seq_printf(s
, "%s: ", name
);
335 trace_seq_vprintf(s
, trace_event_format(iter
, fmt
), ap
);
337 return trace_handle_return(s
);
340 int trace_output_call(struct trace_iterator
*iter
, char *name
, char *fmt
, ...)
346 ret
= trace_output_raw(iter
, name
, fmt
, ap
);
351 EXPORT_SYMBOL_GPL(trace_output_call
);
353 static inline const char *kretprobed(const char *name
, unsigned long addr
)
355 if (is_kretprobe_trampoline(addr
))
356 return "[unknown/kretprobe'd]";
361 trace_seq_print_sym(struct trace_seq
*s
, unsigned long address
, bool offset
)
363 #ifdef CONFIG_KALLSYMS
364 char str
[KSYM_SYMBOL_LEN
];
368 sprint_symbol(str
, address
);
370 kallsyms_lookup(address
, NULL
, NULL
, NULL
, str
);
371 name
= kretprobed(str
, address
);
373 if (name
&& strlen(name
)) {
374 trace_seq_puts(s
, name
);
378 trace_seq_printf(s
, "0x%08lx", address
);
382 # define IP_FMT "%08lx"
384 # define IP_FMT "%016lx"
387 static int seq_print_user_ip(struct trace_seq
*s
, struct mm_struct
*mm
,
388 unsigned long ip
, unsigned long sym_flags
)
390 struct file
*file
= NULL
;
391 unsigned long vmstart
= 0;
398 const struct vm_area_struct
*vma
;
401 vma
= find_vma(mm
, ip
);
404 vmstart
= vma
->vm_start
;
407 ret
= trace_seq_path(s
, file_user_path(file
));
409 trace_seq_printf(s
, "[+0x%lx]",
412 mmap_read_unlock(mm
);
414 if (ret
&& ((sym_flags
& TRACE_ITER_SYM_ADDR
) || !file
))
415 trace_seq_printf(s
, " <" IP_FMT
">", ip
);
416 return !trace_seq_has_overflowed(s
);
420 seq_print_ip_sym(struct trace_seq
*s
, unsigned long ip
, unsigned long sym_flags
)
423 trace_seq_putc(s
, '0');
427 trace_seq_print_sym(s
, ip
, sym_flags
& TRACE_ITER_SYM_OFFSET
);
429 if (sym_flags
& TRACE_ITER_SYM_ADDR
)
430 trace_seq_printf(s
, " <" IP_FMT
">", ip
);
433 return !trace_seq_has_overflowed(s
);
437 * trace_print_lat_fmt - print the irq, preempt and lockdep fields
438 * @s: trace seq struct to write to
439 * @entry: The trace entry field from the ring buffer
441 * Prints the generic fields of irqs off, in hard or softirq, preempt
444 int trace_print_lat_fmt(struct trace_seq
*s
, struct trace_entry
*entry
)
454 nmi
= entry
->flags
& TRACE_FLAG_NMI
;
455 hardirq
= entry
->flags
& TRACE_FLAG_HARDIRQ
;
456 softirq
= entry
->flags
& TRACE_FLAG_SOFTIRQ
;
457 bh_off
= entry
->flags
& TRACE_FLAG_BH_OFF
;
460 (entry
->flags
& TRACE_FLAG_IRQS_OFF
&& bh_off
) ? 'D' :
461 (entry
->flags
& TRACE_FLAG_IRQS_OFF
) ? 'd' :
463 (entry
->flags
& TRACE_FLAG_IRQS_NOSUPPORT
) ? 'X' :
466 switch (entry
->flags
& (TRACE_FLAG_NEED_RESCHED
|
467 TRACE_FLAG_PREEMPT_RESCHED
)) {
468 case TRACE_FLAG_NEED_RESCHED
| TRACE_FLAG_PREEMPT_RESCHED
:
471 case TRACE_FLAG_NEED_RESCHED
:
474 case TRACE_FLAG_PREEMPT_RESCHED
:
483 (nmi
&& hardirq
) ? 'Z' :
485 (hardirq
&& softirq
) ? 'H' :
490 trace_seq_printf(s
, "%c%c%c",
491 irqs_off
, need_resched
, hardsoft_irq
);
493 if (entry
->preempt_count
& 0xf)
494 trace_seq_printf(s
, "%x", entry
->preempt_count
& 0xf);
496 trace_seq_putc(s
, '.');
498 if (entry
->preempt_count
& 0xf0)
499 trace_seq_printf(s
, "%x", entry
->preempt_count
>> 4);
501 trace_seq_putc(s
, '.');
503 return !trace_seq_has_overflowed(s
);
507 lat_print_generic(struct trace_seq
*s
, struct trace_entry
*entry
, int cpu
)
509 char comm
[TASK_COMM_LEN
];
511 trace_find_cmdline(entry
->pid
, comm
);
513 trace_seq_printf(s
, "%8.8s-%-7d %3d",
514 comm
, entry
->pid
, cpu
);
516 return trace_print_lat_fmt(s
, entry
);
520 #define MARK(v, s) {.val = v, .sym = s}
521 /* trace overhead mark */
522 static const struct trace_mark
{
523 unsigned long long val
; /* unit: nsec */
526 MARK(1000000000ULL , '$'), /* 1 sec */
527 MARK(100000000ULL , '@'), /* 100 msec */
528 MARK(10000000ULL , '*'), /* 10 msec */
529 MARK(1000000ULL , '#'), /* 1000 usecs */
530 MARK(100000ULL , '!'), /* 100 usecs */
531 MARK(10000ULL , '+'), /* 10 usecs */
535 char trace_find_mark(unsigned long long d
)
538 int size
= ARRAY_SIZE(mark
);
540 for (i
= 0; i
< size
; i
++) {
545 return (i
== size
) ? ' ' : mark
[i
].sym
;
549 lat_print_timestamp(struct trace_iterator
*iter
, u64 next_ts
)
551 struct trace_array
*tr
= iter
->tr
;
552 unsigned long verbose
= tr
->trace_flags
& TRACE_ITER_VERBOSE
;
553 unsigned long in_ns
= iter
->iter_flags
& TRACE_FILE_TIME_IN_NS
;
554 unsigned long long abs_ts
= iter
->ts
- iter
->array_buffer
->time_start
;
555 unsigned long long rel_ts
= next_ts
- iter
->ts
;
556 struct trace_seq
*s
= &iter
->seq
;
559 abs_ts
= ns2usecs(abs_ts
);
560 rel_ts
= ns2usecs(rel_ts
);
563 if (verbose
&& in_ns
) {
564 unsigned long abs_usec
= do_div(abs_ts
, USEC_PER_MSEC
);
565 unsigned long abs_msec
= (unsigned long)abs_ts
;
566 unsigned long rel_usec
= do_div(rel_ts
, USEC_PER_MSEC
);
567 unsigned long rel_msec
= (unsigned long)rel_ts
;
570 s
, "[%08llx] %ld.%03ldms (+%ld.%03ldms): ",
575 } else if (verbose
&& !in_ns
) {
577 s
, "[%016llx] %lld (+%lld): ",
578 iter
->ts
, abs_ts
, rel_ts
);
580 } else if (!verbose
&& in_ns
) {
584 trace_find_mark(rel_ts
* NSEC_PER_USEC
));
586 } else { /* !verbose && !in_ns */
587 trace_seq_printf(s
, " %4lld: ", abs_ts
);
590 return !trace_seq_has_overflowed(s
);
593 static void trace_print_time(struct trace_seq
*s
, struct trace_iterator
*iter
,
594 unsigned long long ts
)
596 unsigned long secs
, usec_rem
;
597 unsigned long long t
;
599 if (iter
->iter_flags
& TRACE_FILE_TIME_IN_NS
) {
601 usec_rem
= do_div(t
, USEC_PER_SEC
);
602 secs
= (unsigned long)t
;
603 trace_seq_printf(s
, " %5lu.%06lu", secs
, usec_rem
);
605 trace_seq_printf(s
, " %12llu", ts
);
608 int trace_print_context(struct trace_iterator
*iter
)
610 struct trace_array
*tr
= iter
->tr
;
611 struct trace_seq
*s
= &iter
->seq
;
612 struct trace_entry
*entry
= iter
->ent
;
613 char comm
[TASK_COMM_LEN
];
615 trace_find_cmdline(entry
->pid
, comm
);
617 trace_seq_printf(s
, "%16s-%-7d ", comm
, entry
->pid
);
619 if (tr
->trace_flags
& TRACE_ITER_RECORD_TGID
) {
620 unsigned int tgid
= trace_find_tgid(entry
->pid
);
623 trace_seq_printf(s
, "(-------) ");
625 trace_seq_printf(s
, "(%7d) ", tgid
);
628 trace_seq_printf(s
, "[%03d] ", iter
->cpu
);
630 if (tr
->trace_flags
& TRACE_ITER_IRQ_INFO
)
631 trace_print_lat_fmt(s
, entry
);
633 trace_print_time(s
, iter
, iter
->ts
);
634 trace_seq_puts(s
, ": ");
636 return !trace_seq_has_overflowed(s
);
639 int trace_print_lat_context(struct trace_iterator
*iter
)
641 struct trace_entry
*entry
, *next_entry
;
642 struct trace_array
*tr
= iter
->tr
;
643 struct trace_seq
*s
= &iter
->seq
;
644 unsigned long verbose
= (tr
->trace_flags
& TRACE_ITER_VERBOSE
);
647 next_entry
= trace_find_next_entry(iter
, NULL
, &next_ts
);
651 /* trace_find_next_entry() may change iter->ent */
655 char comm
[TASK_COMM_LEN
];
657 trace_find_cmdline(entry
->pid
, comm
);
660 s
, "%16s %7d %3d %d %08x %08lx ",
661 comm
, entry
->pid
, iter
->cpu
, entry
->flags
,
662 entry
->preempt_count
& 0xf, iter
->idx
);
664 lat_print_generic(s
, entry
, iter
->cpu
);
667 lat_print_timestamp(iter
, next_ts
);
669 return !trace_seq_has_overflowed(s
);
673 * ftrace_find_event - find a registered event
674 * @type: the type of event to look for
676 * Returns an event of type @type otherwise NULL
677 * Called with trace_event_read_lock() held.
679 struct trace_event
*ftrace_find_event(int type
)
681 struct trace_event
*event
;
684 key
= type
& (EVENT_HASHSIZE
- 1);
686 hlist_for_each_entry(event
, &event_hash
[key
], node
) {
687 if (event
->type
== type
)
694 static DEFINE_IDA(trace_event_ida
);
696 static void free_trace_event_type(int type
)
698 if (type
>= __TRACE_LAST_TYPE
)
699 ida_free(&trace_event_ida
, type
);
702 static int alloc_trace_event_type(void)
706 /* Skip static defined type numbers */
707 next
= ida_alloc_range(&trace_event_ida
, __TRACE_LAST_TYPE
,
708 TRACE_EVENT_TYPE_MAX
, GFP_KERNEL
);
714 void trace_event_read_lock(void)
716 down_read(&trace_event_sem
);
719 void trace_event_read_unlock(void)
721 up_read(&trace_event_sem
);
725 * register_trace_event - register output for an event type
726 * @event: the event type to register
728 * Event types are stored in a hash and this hash is used to
729 * find a way to print an event. If the @event->type is set
730 * then it will use that type, otherwise it will assign a
733 * If you assign your own type, please make sure it is added
734 * to the trace_type enum in trace.h, to avoid collisions
735 * with the dynamic types.
737 * Returns the event type number or zero on error.
739 int register_trace_event(struct trace_event
*event
)
744 down_write(&trace_event_sem
);
749 if (WARN_ON(!event
->funcs
))
753 event
->type
= alloc_trace_event_type();
756 } else if (WARN(event
->type
> __TRACE_LAST_TYPE
,
757 "Need to add type to trace.h")) {
760 /* Is this event already used */
761 if (ftrace_find_event(event
->type
))
765 if (event
->funcs
->trace
== NULL
)
766 event
->funcs
->trace
= trace_nop_print
;
767 if (event
->funcs
->raw
== NULL
)
768 event
->funcs
->raw
= trace_nop_print
;
769 if (event
->funcs
->hex
== NULL
)
770 event
->funcs
->hex
= trace_nop_print
;
771 if (event
->funcs
->binary
== NULL
)
772 event
->funcs
->binary
= trace_nop_print
;
774 key
= event
->type
& (EVENT_HASHSIZE
- 1);
776 hlist_add_head(&event
->node
, &event_hash
[key
]);
780 up_write(&trace_event_sem
);
784 EXPORT_SYMBOL_GPL(register_trace_event
);
787 * Used by module code with the trace_event_sem held for write.
789 int __unregister_trace_event(struct trace_event
*event
)
791 hlist_del(&event
->node
);
792 free_trace_event_type(event
->type
);
797 * unregister_trace_event - remove a no longer used event
798 * @event: the event to remove
800 int unregister_trace_event(struct trace_event
*event
)
802 down_write(&trace_event_sem
);
803 __unregister_trace_event(event
);
804 up_write(&trace_event_sem
);
808 EXPORT_SYMBOL_GPL(unregister_trace_event
);
814 static void print_array(struct trace_iterator
*iter
, void *pos
,
815 struct ftrace_event_field
*field
)
821 offset
= *(int *)pos
& 0xffff;
822 len
= *(int *)pos
>> 16;
825 offset
+= field
->offset
+ sizeof(int);
827 if (offset
+ len
> iter
->ent_size
) {
828 trace_seq_puts(&iter
->seq
, "<OVERFLOW>");
832 pos
= (void *)iter
->ent
+ offset
;
834 for (i
= 0; i
< len
; i
++, pos
++) {
836 trace_seq_putc(&iter
->seq
, ',');
837 trace_seq_printf(&iter
->seq
, "%02x", *(unsigned char *)pos
);
841 static void print_fields(struct trace_iterator
*iter
, struct trace_event_call
*call
,
842 struct list_head
*head
)
844 struct ftrace_event_field
*field
;
850 list_for_each_entry_reverse(field
, head
, link
) {
851 trace_seq_printf(&iter
->seq
, " %s=", field
->name
);
852 if (field
->offset
+ field
->size
> iter
->ent_size
) {
853 trace_seq_puts(&iter
->seq
, "<OVERFLOW>");
856 pos
= (void *)iter
->ent
+ field
->offset
;
858 switch (field
->filter_type
) {
860 case FILTER_STATIC_STRING
:
861 trace_seq_printf(&iter
->seq
, "%.*s", field
->size
, (char *)pos
);
863 case FILTER_RDYN_STRING
:
864 case FILTER_DYN_STRING
:
865 offset
= *(int *)pos
& 0xffff;
866 len
= *(int *)pos
>> 16;
868 if (field
->filter_type
== FILTER_RDYN_STRING
)
869 offset
+= field
->offset
+ sizeof(int);
871 if (offset
+ len
> iter
->ent_size
) {
872 trace_seq_puts(&iter
->seq
, "<OVERFLOW>");
875 pos
= (void *)iter
->ent
+ offset
;
876 trace_seq_printf(&iter
->seq
, "%.*s", len
, (char *)pos
);
878 case FILTER_PTR_STRING
:
880 trace_iter_expand_format(iter
);
882 ret
= strncpy_from_kernel_nofault(iter
->fmt
, pos
,
885 trace_seq_printf(&iter
->seq
, "(0x%px)", pos
);
887 trace_seq_printf(&iter
->seq
, "(0x%px:%s)",
890 case FILTER_TRACE_FN
:
892 trace_seq_printf(&iter
->seq
, "%pS", pos
);
896 switch (field
->size
) {
898 if (isprint(*(char *)pos
)) {
899 trace_seq_printf(&iter
->seq
, "'%c'",
900 *(unsigned char *)pos
);
902 trace_seq_printf(&iter
->seq
, "(%d)",
903 *(unsigned char *)pos
);
906 trace_seq_printf(&iter
->seq
, "0x%x (%d)",
907 *(unsigned short *)pos
,
908 *(unsigned short *)pos
);
911 /* dynamic array info is 4 bytes */
912 if (strstr(field
->type
, "__data_loc")) {
913 print_array(iter
, pos
, NULL
);
917 if (strstr(field
->type
, "__rel_loc")) {
918 print_array(iter
, pos
, field
);
922 trace_seq_printf(&iter
->seq
, "0x%x (%d)",
923 *(unsigned int *)pos
,
924 *(unsigned int *)pos
);
927 trace_seq_printf(&iter
->seq
, "0x%llx (%lld)",
928 *(unsigned long long *)pos
,
929 *(unsigned long long *)pos
);
932 trace_seq_puts(&iter
->seq
, "<INVALID-SIZE>");
937 trace_seq_puts(&iter
->seq
, "<INVALID-TYPE>");
940 trace_seq_putc(&iter
->seq
, '\n');
943 enum print_line_t
print_event_fields(struct trace_iterator
*iter
,
944 struct trace_event
*event
)
946 struct trace_event_call
*call
;
947 struct list_head
*head
;
949 /* ftrace defined events have separate call structures */
950 if (event
->type
<= __TRACE_LAST_TYPE
) {
953 down_read(&trace_event_sem
);
954 list_for_each_entry(call
, &ftrace_events
, list
) {
955 if (call
->event
.type
== event
->type
) {
959 /* No need to search all events */
960 if (call
->event
.type
> __TRACE_LAST_TYPE
)
963 up_read(&trace_event_sem
);
965 trace_seq_printf(&iter
->seq
, "UNKNOWN TYPE %d\n", event
->type
);
969 call
= container_of(event
, struct trace_event_call
, event
);
971 head
= trace_get_fields(call
);
973 trace_seq_printf(&iter
->seq
, "%s:", trace_event_name(call
));
975 if (head
&& !list_empty(head
))
976 print_fields(iter
, call
, head
);
978 trace_seq_puts(&iter
->seq
, "No fields found\n");
981 return trace_handle_return(&iter
->seq
);
984 enum print_line_t
trace_nop_print(struct trace_iterator
*iter
, int flags
,
985 struct trace_event
*event
)
987 trace_seq_printf(&iter
->seq
, "type: %d\n", iter
->ent
->type
);
989 return trace_handle_return(&iter
->seq
);
992 static void print_fn_trace(struct trace_seq
*s
, unsigned long ip
,
993 unsigned long parent_ip
, long delta
, int flags
)
998 seq_print_ip_sym(s
, ip
, flags
);
1000 if ((flags
& TRACE_ITER_PRINT_PARENT
) && parent_ip
) {
1001 trace_seq_puts(s
, " <-");
1002 seq_print_ip_sym(s
, parent_ip
, flags
);
1007 static enum print_line_t
trace_fn_trace(struct trace_iterator
*iter
, int flags
,
1008 struct trace_event
*event
)
1010 struct ftrace_entry
*field
;
1011 struct trace_seq
*s
= &iter
->seq
;
1013 trace_assign_type(field
, iter
->ent
);
1015 print_fn_trace(s
, field
->ip
, field
->parent_ip
, iter
->tr
->text_delta
, flags
);
1016 trace_seq_putc(s
, '\n');
1018 return trace_handle_return(s
);
1021 static enum print_line_t
trace_fn_raw(struct trace_iterator
*iter
, int flags
,
1022 struct trace_event
*event
)
1024 struct ftrace_entry
*field
;
1026 trace_assign_type(field
, iter
->ent
);
1028 trace_seq_printf(&iter
->seq
, "%lx %lx\n",
1032 return trace_handle_return(&iter
->seq
);
1035 static enum print_line_t
trace_fn_hex(struct trace_iterator
*iter
, int flags
,
1036 struct trace_event
*event
)
1038 struct ftrace_entry
*field
;
1039 struct trace_seq
*s
= &iter
->seq
;
1041 trace_assign_type(field
, iter
->ent
);
1043 SEQ_PUT_HEX_FIELD(s
, field
->ip
);
1044 SEQ_PUT_HEX_FIELD(s
, field
->parent_ip
);
1046 return trace_handle_return(s
);
1049 static enum print_line_t
trace_fn_bin(struct trace_iterator
*iter
, int flags
,
1050 struct trace_event
*event
)
1052 struct ftrace_entry
*field
;
1053 struct trace_seq
*s
= &iter
->seq
;
1055 trace_assign_type(field
, iter
->ent
);
1057 SEQ_PUT_FIELD(s
, field
->ip
);
1058 SEQ_PUT_FIELD(s
, field
->parent_ip
);
1060 return trace_handle_return(s
);
1063 static struct trace_event_functions trace_fn_funcs
= {
1064 .trace
= trace_fn_trace
,
1065 .raw
= trace_fn_raw
,
1066 .hex
= trace_fn_hex
,
1067 .binary
= trace_fn_bin
,
1070 static struct trace_event trace_fn_event
= {
1072 .funcs
= &trace_fn_funcs
,
1075 /* TRACE_CTX an TRACE_WAKE */
1076 static enum print_line_t
trace_ctxwake_print(struct trace_iterator
*iter
,
1079 struct ctx_switch_entry
*field
;
1080 char comm
[TASK_COMM_LEN
];
1084 trace_assign_type(field
, iter
->ent
);
1086 T
= task_index_to_char(field
->next_state
);
1087 S
= task_index_to_char(field
->prev_state
);
1088 trace_find_cmdline(field
->next_pid
, comm
);
1089 trace_seq_printf(&iter
->seq
,
1090 " %7d:%3d:%c %s [%03d] %7d:%3d:%c %s\n",
1099 return trace_handle_return(&iter
->seq
);
1102 static enum print_line_t
trace_ctx_print(struct trace_iterator
*iter
, int flags
,
1103 struct trace_event
*event
)
1105 return trace_ctxwake_print(iter
, "==>");
1108 static enum print_line_t
trace_wake_print(struct trace_iterator
*iter
,
1109 int flags
, struct trace_event
*event
)
1111 return trace_ctxwake_print(iter
, " +");
1114 static int trace_ctxwake_raw(struct trace_iterator
*iter
, char S
)
1116 struct ctx_switch_entry
*field
;
1119 trace_assign_type(field
, iter
->ent
);
1122 S
= task_index_to_char(field
->prev_state
);
1123 T
= task_index_to_char(field
->next_state
);
1124 trace_seq_printf(&iter
->seq
, "%d %d %c %d %d %d %c\n",
1133 return trace_handle_return(&iter
->seq
);
1136 static enum print_line_t
trace_ctx_raw(struct trace_iterator
*iter
, int flags
,
1137 struct trace_event
*event
)
1139 return trace_ctxwake_raw(iter
, 0);
1142 static enum print_line_t
trace_wake_raw(struct trace_iterator
*iter
, int flags
,
1143 struct trace_event
*event
)
1145 return trace_ctxwake_raw(iter
, '+');
1149 static int trace_ctxwake_hex(struct trace_iterator
*iter
, char S
)
1151 struct ctx_switch_entry
*field
;
1152 struct trace_seq
*s
= &iter
->seq
;
1155 trace_assign_type(field
, iter
->ent
);
1158 S
= task_index_to_char(field
->prev_state
);
1159 T
= task_index_to_char(field
->next_state
);
1161 SEQ_PUT_HEX_FIELD(s
, field
->prev_pid
);
1162 SEQ_PUT_HEX_FIELD(s
, field
->prev_prio
);
1163 SEQ_PUT_HEX_FIELD(s
, S
);
1164 SEQ_PUT_HEX_FIELD(s
, field
->next_cpu
);
1165 SEQ_PUT_HEX_FIELD(s
, field
->next_pid
);
1166 SEQ_PUT_HEX_FIELD(s
, field
->next_prio
);
1167 SEQ_PUT_HEX_FIELD(s
, T
);
1169 return trace_handle_return(s
);
1172 static enum print_line_t
trace_ctx_hex(struct trace_iterator
*iter
, int flags
,
1173 struct trace_event
*event
)
1175 return trace_ctxwake_hex(iter
, 0);
1178 static enum print_line_t
trace_wake_hex(struct trace_iterator
*iter
, int flags
,
1179 struct trace_event
*event
)
1181 return trace_ctxwake_hex(iter
, '+');
1184 static enum print_line_t
trace_ctxwake_bin(struct trace_iterator
*iter
,
1185 int flags
, struct trace_event
*event
)
1187 struct ctx_switch_entry
*field
;
1188 struct trace_seq
*s
= &iter
->seq
;
1190 trace_assign_type(field
, iter
->ent
);
1192 SEQ_PUT_FIELD(s
, field
->prev_pid
);
1193 SEQ_PUT_FIELD(s
, field
->prev_prio
);
1194 SEQ_PUT_FIELD(s
, field
->prev_state
);
1195 SEQ_PUT_FIELD(s
, field
->next_cpu
);
1196 SEQ_PUT_FIELD(s
, field
->next_pid
);
1197 SEQ_PUT_FIELD(s
, field
->next_prio
);
1198 SEQ_PUT_FIELD(s
, field
->next_state
);
1200 return trace_handle_return(s
);
1203 static struct trace_event_functions trace_ctx_funcs
= {
1204 .trace
= trace_ctx_print
,
1205 .raw
= trace_ctx_raw
,
1206 .hex
= trace_ctx_hex
,
1207 .binary
= trace_ctxwake_bin
,
1210 static struct trace_event trace_ctx_event
= {
1212 .funcs
= &trace_ctx_funcs
,
1215 static struct trace_event_functions trace_wake_funcs
= {
1216 .trace
= trace_wake_print
,
1217 .raw
= trace_wake_raw
,
1218 .hex
= trace_wake_hex
,
1219 .binary
= trace_ctxwake_bin
,
1222 static struct trace_event trace_wake_event
= {
1224 .funcs
= &trace_wake_funcs
,
1229 static enum print_line_t
trace_stack_print(struct trace_iterator
*iter
,
1230 int flags
, struct trace_event
*event
)
1232 struct stack_entry
*field
;
1233 struct trace_seq
*s
= &iter
->seq
;
1236 long delta
= iter
->tr
->text_delta
;
1238 trace_assign_type(field
, iter
->ent
);
1239 end
= (unsigned long *)((long)iter
->ent
+ iter
->ent_size
);
1241 trace_seq_puts(s
, "<stack trace>\n");
1243 for (p
= field
->caller
; p
&& p
< end
&& *p
!= ULONG_MAX
; p
++) {
1245 if (trace_seq_has_overflowed(s
))
1248 trace_seq_puts(s
, " => ");
1249 seq_print_ip_sym(s
, (*p
) + delta
, flags
);
1250 trace_seq_putc(s
, '\n');
1253 return trace_handle_return(s
);
1256 static struct trace_event_functions trace_stack_funcs
= {
1257 .trace
= trace_stack_print
,
1260 static struct trace_event trace_stack_event
= {
1261 .type
= TRACE_STACK
,
1262 .funcs
= &trace_stack_funcs
,
1265 /* TRACE_USER_STACK */
1266 static enum print_line_t
trace_user_stack_print(struct trace_iterator
*iter
,
1267 int flags
, struct trace_event
*event
)
1269 struct trace_array
*tr
= iter
->tr
;
1270 struct userstack_entry
*field
;
1271 struct trace_seq
*s
= &iter
->seq
;
1272 struct mm_struct
*mm
= NULL
;
1275 trace_assign_type(field
, iter
->ent
);
1277 trace_seq_puts(s
, "<user stack trace>\n");
1279 if (tr
->trace_flags
& TRACE_ITER_SYM_USEROBJ
) {
1280 struct task_struct
*task
;
1282 * we do the lookup on the thread group leader,
1283 * since individual threads might have already quit!
1286 task
= find_task_by_vpid(field
->tgid
);
1288 mm
= get_task_mm(task
);
1292 for (i
= 0; i
< FTRACE_STACK_ENTRIES
; i
++) {
1293 unsigned long ip
= field
->caller
[i
];
1295 if (!ip
|| trace_seq_has_overflowed(s
))
1298 trace_seq_puts(s
, " => ");
1299 seq_print_user_ip(s
, mm
, ip
, flags
);
1300 trace_seq_putc(s
, '\n');
1306 return trace_handle_return(s
);
1309 static struct trace_event_functions trace_user_stack_funcs
= {
1310 .trace
= trace_user_stack_print
,
1313 static struct trace_event trace_user_stack_event
= {
1314 .type
= TRACE_USER_STACK
,
1315 .funcs
= &trace_user_stack_funcs
,
1319 static enum print_line_t
1320 trace_hwlat_print(struct trace_iterator
*iter
, int flags
,
1321 struct trace_event
*event
)
1323 struct trace_entry
*entry
= iter
->ent
;
1324 struct trace_seq
*s
= &iter
->seq
;
1325 struct hwlat_entry
*field
;
1327 trace_assign_type(field
, entry
);
1329 trace_seq_printf(s
, "#%-5u inner/outer(us): %4llu/%-5llu ts:%lld.%09ld count:%d",
1332 field
->outer_duration
,
1333 (long long)field
->timestamp
.tv_sec
,
1334 field
->timestamp
.tv_nsec
, field
->count
);
1336 if (field
->nmi_count
) {
1338 * The generic sched_clock() is not NMI safe, thus
1339 * we only record the count and not the time.
1341 if (!IS_ENABLED(CONFIG_GENERIC_SCHED_CLOCK
))
1342 trace_seq_printf(s
, " nmi-total:%llu",
1343 field
->nmi_total_ts
);
1344 trace_seq_printf(s
, " nmi-count:%u",
1348 trace_seq_putc(s
, '\n');
1350 return trace_handle_return(s
);
1353 static enum print_line_t
1354 trace_hwlat_raw(struct trace_iterator
*iter
, int flags
,
1355 struct trace_event
*event
)
1357 struct hwlat_entry
*field
;
1358 struct trace_seq
*s
= &iter
->seq
;
1360 trace_assign_type(field
, iter
->ent
);
1362 trace_seq_printf(s
, "%llu %lld %lld %09ld %u\n",
1364 field
->outer_duration
,
1365 (long long)field
->timestamp
.tv_sec
,
1366 field
->timestamp
.tv_nsec
,
1369 return trace_handle_return(s
);
1372 static struct trace_event_functions trace_hwlat_funcs
= {
1373 .trace
= trace_hwlat_print
,
1374 .raw
= trace_hwlat_raw
,
1377 static struct trace_event trace_hwlat_event
= {
1378 .type
= TRACE_HWLAT
,
1379 .funcs
= &trace_hwlat_funcs
,
1383 static enum print_line_t
1384 trace_osnoise_print(struct trace_iterator
*iter
, int flags
,
1385 struct trace_event
*event
)
1387 struct trace_entry
*entry
= iter
->ent
;
1388 struct trace_seq
*s
= &iter
->seq
;
1389 struct osnoise_entry
*field
;
1390 u64 ratio
, ratio_dec
;
1393 trace_assign_type(field
, entry
);
1396 * compute the available % of cpu time.
1398 net_runtime
= field
->runtime
- field
->noise
;
1399 ratio
= net_runtime
* 10000000;
1400 do_div(ratio
, field
->runtime
);
1401 ratio_dec
= do_div(ratio
, 100000);
1403 trace_seq_printf(s
, "%llu %10llu %3llu.%05llu %7llu",
1409 trace_seq_printf(s
, " %6u", field
->hw_count
);
1410 trace_seq_printf(s
, " %6u", field
->nmi_count
);
1411 trace_seq_printf(s
, " %6u", field
->irq_count
);
1412 trace_seq_printf(s
, " %6u", field
->softirq_count
);
1413 trace_seq_printf(s
, " %6u", field
->thread_count
);
1415 trace_seq_putc(s
, '\n');
1417 return trace_handle_return(s
);
1420 static enum print_line_t
1421 trace_osnoise_raw(struct trace_iterator
*iter
, int flags
,
1422 struct trace_event
*event
)
1424 struct osnoise_entry
*field
;
1425 struct trace_seq
*s
= &iter
->seq
;
1427 trace_assign_type(field
, iter
->ent
);
1429 trace_seq_printf(s
, "%lld %llu %llu %u %u %u %u %u\n",
1436 field
->softirq_count
,
1437 field
->thread_count
);
1439 return trace_handle_return(s
);
1442 static struct trace_event_functions trace_osnoise_funcs
= {
1443 .trace
= trace_osnoise_print
,
1444 .raw
= trace_osnoise_raw
,
1447 static struct trace_event trace_osnoise_event
= {
1448 .type
= TRACE_OSNOISE
,
1449 .funcs
= &trace_osnoise_funcs
,
1452 /* TRACE_TIMERLAT */
1454 static char *timerlat_lat_context
[] = {"irq", "thread", "user-ret"};
1455 static enum print_line_t
1456 trace_timerlat_print(struct trace_iterator
*iter
, int flags
,
1457 struct trace_event
*event
)
1459 struct trace_entry
*entry
= iter
->ent
;
1460 struct trace_seq
*s
= &iter
->seq
;
1461 struct timerlat_entry
*field
;
1463 trace_assign_type(field
, entry
);
1465 trace_seq_printf(s
, "#%-5u context %6s timer_latency %9llu ns\n",
1467 timerlat_lat_context
[field
->context
],
1468 field
->timer_latency
);
1470 return trace_handle_return(s
);
1473 static enum print_line_t
1474 trace_timerlat_raw(struct trace_iterator
*iter
, int flags
,
1475 struct trace_event
*event
)
1477 struct timerlat_entry
*field
;
1478 struct trace_seq
*s
= &iter
->seq
;
1480 trace_assign_type(field
, iter
->ent
);
1482 trace_seq_printf(s
, "%u %d %llu\n",
1485 field
->timer_latency
);
1487 return trace_handle_return(s
);
1490 static struct trace_event_functions trace_timerlat_funcs
= {
1491 .trace
= trace_timerlat_print
,
1492 .raw
= trace_timerlat_raw
,
1495 static struct trace_event trace_timerlat_event
= {
1496 .type
= TRACE_TIMERLAT
,
1497 .funcs
= &trace_timerlat_funcs
,
1501 static enum print_line_t
1502 trace_bputs_print(struct trace_iterator
*iter
, int flags
,
1503 struct trace_event
*event
)
1505 struct trace_entry
*entry
= iter
->ent
;
1506 struct trace_seq
*s
= &iter
->seq
;
1507 struct bputs_entry
*field
;
1509 trace_assign_type(field
, entry
);
1511 seq_print_ip_sym(s
, field
->ip
, flags
);
1512 trace_seq_puts(s
, ": ");
1513 trace_seq_puts(s
, field
->str
);
1515 return trace_handle_return(s
);
1519 static enum print_line_t
1520 trace_bputs_raw(struct trace_iterator
*iter
, int flags
,
1521 struct trace_event
*event
)
1523 struct bputs_entry
*field
;
1524 struct trace_seq
*s
= &iter
->seq
;
1526 trace_assign_type(field
, iter
->ent
);
1528 trace_seq_printf(s
, ": %lx : ", field
->ip
);
1529 trace_seq_puts(s
, field
->str
);
1531 return trace_handle_return(s
);
1534 static struct trace_event_functions trace_bputs_funcs
= {
1535 .trace
= trace_bputs_print
,
1536 .raw
= trace_bputs_raw
,
1539 static struct trace_event trace_bputs_event
= {
1540 .type
= TRACE_BPUTS
,
1541 .funcs
= &trace_bputs_funcs
,
1545 static enum print_line_t
1546 trace_bprint_print(struct trace_iterator
*iter
, int flags
,
1547 struct trace_event
*event
)
1549 struct trace_entry
*entry
= iter
->ent
;
1550 struct trace_seq
*s
= &iter
->seq
;
1551 struct bprint_entry
*field
;
1553 trace_assign_type(field
, entry
);
1555 seq_print_ip_sym(s
, field
->ip
, flags
);
1556 trace_seq_puts(s
, ": ");
1557 trace_seq_bprintf(s
, field
->fmt
, field
->buf
);
1559 return trace_handle_return(s
);
1563 static enum print_line_t
1564 trace_bprint_raw(struct trace_iterator
*iter
, int flags
,
1565 struct trace_event
*event
)
1567 struct bprint_entry
*field
;
1568 struct trace_seq
*s
= &iter
->seq
;
1570 trace_assign_type(field
, iter
->ent
);
1572 trace_seq_printf(s
, ": %lx : ", field
->ip
);
1573 trace_seq_bprintf(s
, field
->fmt
, field
->buf
);
1575 return trace_handle_return(s
);
1578 static struct trace_event_functions trace_bprint_funcs
= {
1579 .trace
= trace_bprint_print
,
1580 .raw
= trace_bprint_raw
,
1583 static struct trace_event trace_bprint_event
= {
1584 .type
= TRACE_BPRINT
,
1585 .funcs
= &trace_bprint_funcs
,
1589 static enum print_line_t
trace_print_print(struct trace_iterator
*iter
,
1590 int flags
, struct trace_event
*event
)
1592 struct print_entry
*field
;
1593 struct trace_seq
*s
= &iter
->seq
;
1596 trace_assign_type(field
, iter
->ent
);
1598 ip
= field
->ip
+ iter
->tr
->text_delta
;
1600 seq_print_ip_sym(s
, ip
, flags
);
1601 trace_seq_printf(s
, ": %s", field
->buf
);
1603 return trace_handle_return(s
);
1606 static enum print_line_t
trace_print_raw(struct trace_iterator
*iter
, int flags
,
1607 struct trace_event
*event
)
1609 struct print_entry
*field
;
1611 trace_assign_type(field
, iter
->ent
);
1613 trace_seq_printf(&iter
->seq
, "# %lx %s", field
->ip
, field
->buf
);
1615 return trace_handle_return(&iter
->seq
);
1618 static struct trace_event_functions trace_print_funcs
= {
1619 .trace
= trace_print_print
,
1620 .raw
= trace_print_raw
,
1623 static struct trace_event trace_print_event
= {
1624 .type
= TRACE_PRINT
,
1625 .funcs
= &trace_print_funcs
,
1628 static enum print_line_t
trace_raw_data(struct trace_iterator
*iter
, int flags
,
1629 struct trace_event
*event
)
1631 struct raw_data_entry
*field
;
1634 trace_assign_type(field
, iter
->ent
);
1636 trace_seq_printf(&iter
->seq
, "# %x buf:", field
->id
);
1638 for (i
= 0; i
< iter
->ent_size
- offsetof(struct raw_data_entry
, buf
); i
++)
1639 trace_seq_printf(&iter
->seq
, " %02x",
1640 (unsigned char)field
->buf
[i
]);
1642 trace_seq_putc(&iter
->seq
, '\n');
1644 return trace_handle_return(&iter
->seq
);
1647 static struct trace_event_functions trace_raw_data_funcs
= {
1648 .trace
= trace_raw_data
,
1649 .raw
= trace_raw_data
,
1652 static struct trace_event trace_raw_data_event
= {
1653 .type
= TRACE_RAW_DATA
,
1654 .funcs
= &trace_raw_data_funcs
,
1657 static enum print_line_t
1658 trace_func_repeats_raw(struct trace_iterator
*iter
, int flags
,
1659 struct trace_event
*event
)
1661 struct func_repeats_entry
*field
;
1662 struct trace_seq
*s
= &iter
->seq
;
1664 trace_assign_type(field
, iter
->ent
);
1666 trace_seq_printf(s
, "%lu %lu %u %llu\n",
1670 FUNC_REPEATS_GET_DELTA_TS(field
));
1672 return trace_handle_return(s
);
1675 static enum print_line_t
1676 trace_func_repeats_print(struct trace_iterator
*iter
, int flags
,
1677 struct trace_event
*event
)
1679 struct func_repeats_entry
*field
;
1680 struct trace_seq
*s
= &iter
->seq
;
1682 trace_assign_type(field
, iter
->ent
);
1684 print_fn_trace(s
, field
->ip
, field
->parent_ip
, iter
->tr
->text_delta
, flags
);
1685 trace_seq_printf(s
, " (repeats: %u, last_ts:", field
->count
);
1686 trace_print_time(s
, iter
,
1687 iter
->ts
- FUNC_REPEATS_GET_DELTA_TS(field
));
1688 trace_seq_puts(s
, ")\n");
1690 return trace_handle_return(s
);
1693 static struct trace_event_functions trace_func_repeats_funcs
= {
1694 .trace
= trace_func_repeats_print
,
1695 .raw
= trace_func_repeats_raw
,
1698 static struct trace_event trace_func_repeats_event
= {
1699 .type
= TRACE_FUNC_REPEATS
,
1700 .funcs
= &trace_func_repeats_funcs
,
1703 static struct trace_event
*events
[] __initdata
= {
1708 &trace_user_stack_event
,
1710 &trace_bprint_event
,
1713 &trace_osnoise_event
,
1714 &trace_timerlat_event
,
1715 &trace_raw_data_event
,
1716 &trace_func_repeats_event
,
1720 __init
int init_events(void)
1722 struct trace_event
*event
;
1725 for (i
= 0; events
[i
]; i
++) {
1727 ret
= register_trace_event(event
);
1728 WARN_ONCE(!ret
, "event %d failed to register", event
->type
);