trace_output.c 28 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560561562563564565566567568569570571572573574575576577578579580581582583584585586587588589590591592593594595596597598599600601602603604605606607608609610611612613614615616617618619620621622623624625626627628629630631632633634635636637638639640641642643644645646647648649650651652653654655656657658659660661662663664665666667668669670671672673674675676677678679680681682683684685686687688689690691692693694695696697698699700701702703704705706707708709710711712713714715716717718719720721722723724725726727728729730731732733734735736737738739740741742743744745746747748749750751752753754755756757758759760761762763764765766767768769770771772773774775776777778779780781782783784785786787788789790791792793794795796797798799800801802803804805806807808809810811812813814815816817818819820821822823824825826827828829830831832833834835836837838839840841842843844845846847848849850851852853854855856857858859860861862863864865866867868869870871872873874875876877878879880881882883884885886887888889890891892893894895896897898899900901902903904905906907908909910911912913914915916917918919920921922923924925926927928929930931932933934935936937938939940941942943944945946947948949950951952953954955956957958959960961962963964965966967968969970971972973974975976977978979980981982983984985986987988989990991992993994995996997998999100010011002100310041005100610071008100910101011101210131014101510161017101810191020102110221023102410251026102710281029103010311032103310341035103610371038103910401041104210431044104510461047104810491050105110521053105410551056105710581059106010611062106310641065106610671068106910701071107210731074107510761077107810791080108110821083108410851086108710881089109010911092109310941095109610971098109911001101110211031104110511061107110811091110111111121113111411151116111711181119112011211122112311241125112611271128112911301131113211331134113511361137113811391140114111421143114411451146114711481149115011511152115311541155115611571158115911601161116211631164116511661167116811691170117111721173117411751176117711781179118011811182118311841185118611871188118911901191119211931194119511961197119811991200120112021203120412051206120712081209121012111212121312141215121612171218121912201221122212231224122512261227122812291230123112321233123412351236123712381239124012411242124312441245124612471248124912501251125212531254125512561257125812591260126112621263126412651266126712681269127012711272127312741275127612771278127912801281128212831284128512861287128812891290129112921293129412951296129712981299130013011302130313041305130613071308130913101311131213131314131513161317131813191320132113221323132413251326132713281329133013311332133313341335133613371338133913401341134213431344134513461347134813491350135113521353135413551356
  1. /*
  2. * trace_output.c
  3. *
  4. * Copyright (C) 2008 Red Hat Inc, Steven Rostedt <srostedt@redhat.com>
  5. *
  6. */
  7. #include <linux/module.h>
  8. #include <linux/mutex.h>
  9. #include <linux/ftrace.h>
  10. #include "trace_output.h"
  11. /* must be a power of 2 */
  12. #define EVENT_HASHSIZE 128
  13. DECLARE_RWSEM(trace_event_mutex);
  14. static struct hlist_head event_hash[EVENT_HASHSIZE] __read_mostly;
  15. static int next_event_type = __TRACE_LAST_TYPE + 1;
  16. int trace_print_seq(struct seq_file *m, struct trace_seq *s)
  17. {
  18. int len = s->len >= PAGE_SIZE ? PAGE_SIZE - 1 : s->len;
  19. int ret;
  20. ret = seq_write(m, s->buffer, len);
  21. /*
  22. * Only reset this buffer if we successfully wrote to the
  23. * seq_file buffer.
  24. */
  25. if (!ret)
  26. trace_seq_init(s);
  27. return ret;
  28. }
  29. enum print_line_t trace_print_bprintk_msg_only(struct trace_iterator *iter)
  30. {
  31. struct trace_seq *s = &iter->seq;
  32. struct trace_entry *entry = iter->ent;
  33. struct bprint_entry *field;
  34. int ret;
  35. trace_assign_type(field, entry);
  36. ret = trace_seq_bprintf(s, field->fmt, field->buf);
  37. if (!ret)
  38. return TRACE_TYPE_PARTIAL_LINE;
  39. return TRACE_TYPE_HANDLED;
  40. }
  41. enum print_line_t trace_print_printk_msg_only(struct trace_iterator *iter)
  42. {
  43. struct trace_seq *s = &iter->seq;
  44. struct trace_entry *entry = iter->ent;
  45. struct print_entry *field;
  46. int ret;
  47. trace_assign_type(field, entry);
  48. ret = trace_seq_printf(s, "%s", field->buf);
  49. if (!ret)
  50. return TRACE_TYPE_PARTIAL_LINE;
  51. return TRACE_TYPE_HANDLED;
  52. }
  53. /**
  54. * trace_seq_printf - sequence printing of trace information
  55. * @s: trace sequence descriptor
  56. * @fmt: printf format string
  57. *
  58. * It returns 0 if the trace oversizes the buffer's free
  59. * space, 1 otherwise.
  60. *
  61. * The tracer may use either sequence operations or its own
  62. * copy to user routines. To simplify formating of a trace
  63. * trace_seq_printf is used to store strings into a special
  64. * buffer (@s). Then the output may be either used by
  65. * the sequencer or pulled into another buffer.
  66. */
  67. int
  68. trace_seq_printf(struct trace_seq *s, const char *fmt, ...)
  69. {
  70. int len = (PAGE_SIZE - 1) - s->len;
  71. va_list ap;
  72. int ret;
  73. if (s->full || !len)
  74. return 0;
  75. va_start(ap, fmt);
  76. ret = vsnprintf(s->buffer + s->len, len, fmt, ap);
  77. va_end(ap);
  78. /* If we can't write it all, don't bother writing anything */
  79. if (ret >= len) {
  80. s->full = 1;
  81. return 0;
  82. }
  83. s->len += ret;
  84. return 1;
  85. }
  86. EXPORT_SYMBOL_GPL(trace_seq_printf);
  87. /**
  88. * trace_seq_vprintf - sequence printing of trace information
  89. * @s: trace sequence descriptor
  90. * @fmt: printf format string
  91. *
  92. * The tracer may use either sequence operations or its own
  93. * copy to user routines. To simplify formating of a trace
  94. * trace_seq_printf is used to store strings into a special
  95. * buffer (@s). Then the output may be either used by
  96. * the sequencer or pulled into another buffer.
  97. */
  98. int
  99. trace_seq_vprintf(struct trace_seq *s, const char *fmt, va_list args)
  100. {
  101. int len = (PAGE_SIZE - 1) - s->len;
  102. int ret;
  103. if (s->full || !len)
  104. return 0;
  105. ret = vsnprintf(s->buffer + s->len, len, fmt, args);
  106. /* If we can't write it all, don't bother writing anything */
  107. if (ret >= len) {
  108. s->full = 1;
  109. return 0;
  110. }
  111. s->len += ret;
  112. return len;
  113. }
  114. EXPORT_SYMBOL_GPL(trace_seq_vprintf);
  115. int trace_seq_bprintf(struct trace_seq *s, const char *fmt, const u32 *binary)
  116. {
  117. int len = (PAGE_SIZE - 1) - s->len;
  118. int ret;
  119. if (s->full || !len)
  120. return 0;
  121. ret = bstr_printf(s->buffer + s->len, len, fmt, binary);
  122. /* If we can't write it all, don't bother writing anything */
  123. if (ret >= len) {
  124. s->full = 1;
  125. return 0;
  126. }
  127. s->len += ret;
  128. return len;
  129. }
  130. /**
  131. * trace_seq_puts - trace sequence printing of simple string
  132. * @s: trace sequence descriptor
  133. * @str: simple string to record
  134. *
  135. * The tracer may use either the sequence operations or its own
  136. * copy to user routines. This function records a simple string
  137. * into a special buffer (@s) for later retrieval by a sequencer
  138. * or other mechanism.
  139. */
  140. int trace_seq_puts(struct trace_seq *s, const char *str)
  141. {
  142. int len = strlen(str);
  143. if (s->full)
  144. return 0;
  145. if (len > ((PAGE_SIZE - 1) - s->len)) {
  146. s->full = 1;
  147. return 0;
  148. }
  149. memcpy(s->buffer + s->len, str, len);
  150. s->len += len;
  151. return len;
  152. }
  153. int trace_seq_putc(struct trace_seq *s, unsigned char c)
  154. {
  155. if (s->full)
  156. return 0;
  157. if (s->len >= (PAGE_SIZE - 1)) {
  158. s->full = 1;
  159. return 0;
  160. }
  161. s->buffer[s->len++] = c;
  162. return 1;
  163. }
  164. EXPORT_SYMBOL(trace_seq_putc);
  165. int trace_seq_putmem(struct trace_seq *s, const void *mem, size_t len)
  166. {
  167. if (s->full)
  168. return 0;
  169. if (len > ((PAGE_SIZE - 1) - s->len)) {
  170. s->full = 1;
  171. return 0;
  172. }
  173. memcpy(s->buffer + s->len, mem, len);
  174. s->len += len;
  175. return len;
  176. }
  177. int trace_seq_putmem_hex(struct trace_seq *s, const void *mem, size_t len)
  178. {
  179. unsigned char hex[HEX_CHARS];
  180. const unsigned char *data = mem;
  181. int i, j;
  182. if (s->full)
  183. return 0;
  184. #ifdef __BIG_ENDIAN
  185. for (i = 0, j = 0; i < len; i++) {
  186. #else
  187. for (i = len-1, j = 0; i >= 0; i--) {
  188. #endif
  189. hex[j++] = hex_asc_hi(data[i]);
  190. hex[j++] = hex_asc_lo(data[i]);
  191. }
  192. hex[j++] = ' ';
  193. return trace_seq_putmem(s, hex, j);
  194. }
  195. void *trace_seq_reserve(struct trace_seq *s, size_t len)
  196. {
  197. void *ret;
  198. if (s->full)
  199. return NULL;
  200. if (len > ((PAGE_SIZE - 1) - s->len)) {
  201. s->full = 1;
  202. return NULL;
  203. }
  204. ret = s->buffer + s->len;
  205. s->len += len;
  206. return ret;
  207. }
  208. int trace_seq_path(struct trace_seq *s, const struct path *path)
  209. {
  210. unsigned char *p;
  211. if (s->full)
  212. return 0;
  213. if (s->len >= (PAGE_SIZE - 1)) {
  214. s->full = 1;
  215. return 0;
  216. }
  217. p = d_path(path, s->buffer + s->len, PAGE_SIZE - s->len);
  218. if (!IS_ERR(p)) {
  219. p = mangle_path(s->buffer + s->len, p, "\n");
  220. if (p) {
  221. s->len = p - s->buffer;
  222. return 1;
  223. }
  224. } else {
  225. s->buffer[s->len++] = '?';
  226. return 1;
  227. }
  228. s->full = 1;
  229. return 0;
  230. }
  231. const char *
  232. ftrace_print_flags_seq(struct trace_seq *p, const char *delim,
  233. unsigned long flags,
  234. const struct trace_print_flags *flag_array)
  235. {
  236. unsigned long mask;
  237. const char *str;
  238. const char *ret = p->buffer + p->len;
  239. int i, first = 1;
  240. for (i = 0; flag_array[i].name && flags; i++) {
  241. mask = flag_array[i].mask;
  242. if ((flags & mask) != mask)
  243. continue;
  244. str = flag_array[i].name;
  245. flags &= ~mask;
  246. if (!first && delim)
  247. trace_seq_puts(p, delim);
  248. else
  249. first = 0;
  250. trace_seq_puts(p, str);
  251. }
  252. /* check for left over flags */
  253. if (flags) {
  254. if (!first && delim)
  255. trace_seq_puts(p, delim);
  256. trace_seq_printf(p, "0x%lx", flags);
  257. }
  258. trace_seq_putc(p, 0);
  259. return ret;
  260. }
  261. EXPORT_SYMBOL(ftrace_print_flags_seq);
  262. const char *
  263. ftrace_print_symbols_seq(struct trace_seq *p, unsigned long val,
  264. const struct trace_print_flags *symbol_array)
  265. {
  266. int i;
  267. const char *ret = p->buffer + p->len;
  268. for (i = 0; symbol_array[i].name; i++) {
  269. if (val != symbol_array[i].mask)
  270. continue;
  271. trace_seq_puts(p, symbol_array[i].name);
  272. break;
  273. }
  274. if (ret == (const char *)(p->buffer + p->len))
  275. trace_seq_printf(p, "0x%lx", val);
  276. trace_seq_putc(p, 0);
  277. return ret;
  278. }
  279. EXPORT_SYMBOL(ftrace_print_symbols_seq);
  280. #if BITS_PER_LONG == 32
  281. const char *
  282. ftrace_print_symbols_seq_u64(struct trace_seq *p, unsigned long long val,
  283. const struct trace_print_flags_u64 *symbol_array)
  284. {
  285. int i;
  286. const char *ret = p->buffer + p->len;
  287. for (i = 0; symbol_array[i].name; i++) {
  288. if (val != symbol_array[i].mask)
  289. continue;
  290. trace_seq_puts(p, symbol_array[i].name);
  291. break;
  292. }
  293. if (ret == (const char *)(p->buffer + p->len))
  294. trace_seq_printf(p, "0x%llx", val);
  295. trace_seq_putc(p, 0);
  296. return ret;
  297. }
  298. EXPORT_SYMBOL(ftrace_print_symbols_seq_u64);
  299. #endif
  300. const char *
  301. ftrace_print_hex_seq(struct trace_seq *p, const unsigned char *buf, int buf_len)
  302. {
  303. int i;
  304. const char *ret = p->buffer + p->len;
  305. for (i = 0; i < buf_len; i++)
  306. trace_seq_printf(p, "%s%2.2x", i == 0 ? "" : " ", buf[i]);
  307. trace_seq_putc(p, 0);
  308. return ret;
  309. }
  310. EXPORT_SYMBOL(ftrace_print_hex_seq);
  311. #ifdef CONFIG_KRETPROBES
  312. static inline const char *kretprobed(const char *name)
  313. {
  314. static const char tramp_name[] = "kretprobe_trampoline";
  315. int size = sizeof(tramp_name);
  316. if (strncmp(tramp_name, name, size) == 0)
  317. return "[unknown/kretprobe'd]";
  318. return name;
  319. }
  320. #else
  321. static inline const char *kretprobed(const char *name)
  322. {
  323. return name;
  324. }
  325. #endif /* CONFIG_KRETPROBES */
  326. static int
  327. seq_print_sym_short(struct trace_seq *s, const char *fmt, unsigned long address)
  328. {
  329. #ifdef CONFIG_KALLSYMS
  330. char str[KSYM_SYMBOL_LEN];
  331. const char *name;
  332. kallsyms_lookup(address, NULL, NULL, NULL, str);
  333. name = kretprobed(str);
  334. return trace_seq_printf(s, fmt, name);
  335. #endif
  336. return 1;
  337. }
  338. static int
  339. seq_print_sym_offset(struct trace_seq *s, const char *fmt,
  340. unsigned long address)
  341. {
  342. #ifdef CONFIG_KALLSYMS
  343. char str[KSYM_SYMBOL_LEN];
  344. const char *name;
  345. sprint_symbol(str, address);
  346. name = kretprobed(str);
  347. return trace_seq_printf(s, fmt, name);
  348. #endif
  349. return 1;
  350. }
  351. #ifndef CONFIG_64BIT
  352. # define IP_FMT "%08lx"
  353. #else
  354. # define IP_FMT "%016lx"
  355. #endif
  356. int seq_print_user_ip(struct trace_seq *s, struct mm_struct *mm,
  357. unsigned long ip, unsigned long sym_flags)
  358. {
  359. struct file *file = NULL;
  360. unsigned long vmstart = 0;
  361. int ret = 1;
  362. if (s->full)
  363. return 0;
  364. if (mm) {
  365. const struct vm_area_struct *vma;
  366. down_read(&mm->mmap_sem);
  367. vma = find_vma(mm, ip);
  368. if (vma) {
  369. file = vma->vm_file;
  370. vmstart = vma->vm_start;
  371. }
  372. if (file) {
  373. ret = trace_seq_path(s, &file->f_path);
  374. if (ret)
  375. ret = trace_seq_printf(s, "[+0x%lx]",
  376. ip - vmstart);
  377. }
  378. up_read(&mm->mmap_sem);
  379. }
  380. if (ret && ((sym_flags & TRACE_ITER_SYM_ADDR) || !file))
  381. ret = trace_seq_printf(s, " <" IP_FMT ">", ip);
  382. return ret;
  383. }
  384. int
  385. seq_print_userip_objs(const struct userstack_entry *entry, struct trace_seq *s,
  386. unsigned long sym_flags)
  387. {
  388. struct mm_struct *mm = NULL;
  389. int ret = 1;
  390. unsigned int i;
  391. if (trace_flags & TRACE_ITER_SYM_USEROBJ) {
  392. struct task_struct *task;
  393. /*
  394. * we do the lookup on the thread group leader,
  395. * since individual threads might have already quit!
  396. */
  397. rcu_read_lock();
  398. task = find_task_by_vpid(entry->tgid);
  399. if (task)
  400. mm = get_task_mm(task);
  401. rcu_read_unlock();
  402. }
  403. for (i = 0; i < FTRACE_STACK_ENTRIES; i++) {
  404. unsigned long ip = entry->caller[i];
  405. if (ip == ULONG_MAX || !ret)
  406. break;
  407. if (ret)
  408. ret = trace_seq_puts(s, " => ");
  409. if (!ip) {
  410. if (ret)
  411. ret = trace_seq_puts(s, "??");
  412. if (ret)
  413. ret = trace_seq_puts(s, "\n");
  414. continue;
  415. }
  416. if (!ret)
  417. break;
  418. if (ret)
  419. ret = seq_print_user_ip(s, mm, ip, sym_flags);
  420. ret = trace_seq_puts(s, "\n");
  421. }
  422. if (mm)
  423. mmput(mm);
  424. return ret;
  425. }
  426. int
  427. seq_print_ip_sym(struct trace_seq *s, unsigned long ip, unsigned long sym_flags)
  428. {
  429. int ret;
  430. if (!ip)
  431. return trace_seq_printf(s, "0");
  432. if (sym_flags & TRACE_ITER_SYM_OFFSET)
  433. ret = seq_print_sym_offset(s, "%s", ip);
  434. else
  435. ret = seq_print_sym_short(s, "%s", ip);
  436. if (!ret)
  437. return 0;
  438. if (sym_flags & TRACE_ITER_SYM_ADDR)
  439. ret = trace_seq_printf(s, " <" IP_FMT ">", ip);
  440. return ret;
  441. }
  442. /**
  443. * trace_print_lat_fmt - print the irq, preempt and lockdep fields
  444. * @s: trace seq struct to write to
  445. * @entry: The trace entry field from the ring buffer
  446. *
  447. * Prints the generic fields of irqs off, in hard or softirq, preempt
  448. * count.
  449. */
  450. int trace_print_lat_fmt(struct trace_seq *s, struct trace_entry *entry)
  451. {
  452. char hardsoft_irq;
  453. char need_resched;
  454. char irqs_off;
  455. int hardirq;
  456. int softirq;
  457. int ret;
  458. hardirq = entry->flags & TRACE_FLAG_HARDIRQ;
  459. softirq = entry->flags & TRACE_FLAG_SOFTIRQ;
  460. irqs_off =
  461. (entry->flags & TRACE_FLAG_IRQS_OFF) ? 'd' :
  462. (entry->flags & TRACE_FLAG_IRQS_NOSUPPORT) ? 'X' :
  463. '.';
  464. need_resched =
  465. (entry->flags & TRACE_FLAG_NEED_RESCHED) ? 'N' : '.';
  466. hardsoft_irq =
  467. (hardirq && softirq) ? 'H' :
  468. hardirq ? 'h' :
  469. softirq ? 's' :
  470. '.';
  471. if (!trace_seq_printf(s, "%c%c%c",
  472. irqs_off, need_resched, hardsoft_irq))
  473. return 0;
  474. if (entry->preempt_count)
  475. ret = trace_seq_printf(s, "%x", entry->preempt_count);
  476. else
  477. ret = trace_seq_putc(s, '.');
  478. return ret;
  479. }
  480. static int
  481. lat_print_generic(struct trace_seq *s, struct trace_entry *entry, int cpu)
  482. {
  483. char comm[TASK_COMM_LEN];
  484. trace_find_cmdline(entry->pid, comm);
  485. if (!trace_seq_printf(s, "%8.8s-%-5d %3d",
  486. comm, entry->pid, cpu))
  487. return 0;
  488. return trace_print_lat_fmt(s, entry);
  489. }
  490. static unsigned long preempt_mark_thresh_us = 100;
  491. static int
  492. lat_print_timestamp(struct trace_iterator *iter, u64 next_ts)
  493. {
  494. unsigned long verbose = trace_flags & TRACE_ITER_VERBOSE;
  495. unsigned long in_ns = iter->iter_flags & TRACE_FILE_TIME_IN_NS;
  496. unsigned long long abs_ts = iter->ts - iter->tr->time_start;
  497. unsigned long long rel_ts = next_ts - iter->ts;
  498. struct trace_seq *s = &iter->seq;
  499. if (in_ns) {
  500. abs_ts = ns2usecs(abs_ts);
  501. rel_ts = ns2usecs(rel_ts);
  502. }
  503. if (verbose && in_ns) {
  504. unsigned long abs_usec = do_div(abs_ts, USEC_PER_MSEC);
  505. unsigned long abs_msec = (unsigned long)abs_ts;
  506. unsigned long rel_usec = do_div(rel_ts, USEC_PER_MSEC);
  507. unsigned long rel_msec = (unsigned long)rel_ts;
  508. return trace_seq_printf(
  509. s, "[%08llx] %ld.%03ldms (+%ld.%03ldms): ",
  510. ns2usecs(iter->ts),
  511. abs_msec, abs_usec,
  512. rel_msec, rel_usec);
  513. } else if (verbose && !in_ns) {
  514. return trace_seq_printf(
  515. s, "[%016llx] %lld (+%lld): ",
  516. iter->ts, abs_ts, rel_ts);
  517. } else if (!verbose && in_ns) {
  518. return trace_seq_printf(
  519. s, " %4lldus%c: ",
  520. abs_ts,
  521. rel_ts > preempt_mark_thresh_us ? '!' :
  522. rel_ts > 1 ? '+' : ' ');
  523. } else { /* !verbose && !in_ns */
  524. return trace_seq_printf(s, " %4lld: ", abs_ts);
  525. }
  526. }
  527. int trace_print_context(struct trace_iterator *iter)
  528. {
  529. struct trace_seq *s = &iter->seq;
  530. struct trace_entry *entry = iter->ent;
  531. unsigned long long t;
  532. unsigned long secs, usec_rem;
  533. char comm[TASK_COMM_LEN];
  534. int ret;
  535. trace_find_cmdline(entry->pid, comm);
  536. ret = trace_seq_printf(s, "%16s-%-5d [%03d] ",
  537. comm, entry->pid, iter->cpu);
  538. if (!ret)
  539. return 0;
  540. if (trace_flags & TRACE_ITER_IRQ_INFO) {
  541. ret = trace_print_lat_fmt(s, entry);
  542. if (!ret)
  543. return 0;
  544. }
  545. if (iter->iter_flags & TRACE_FILE_TIME_IN_NS) {
  546. t = ns2usecs(iter->ts);
  547. usec_rem = do_div(t, USEC_PER_SEC);
  548. secs = (unsigned long)t;
  549. return trace_seq_printf(s, " %5lu.%06lu: ", secs, usec_rem);
  550. } else
  551. return trace_seq_printf(s, " %12llu: ", iter->ts);
  552. }
  553. int trace_print_lat_context(struct trace_iterator *iter)
  554. {
  555. u64 next_ts;
  556. int ret;
  557. /* trace_find_next_entry will reset ent_size */
  558. int ent_size = iter->ent_size;
  559. struct trace_seq *s = &iter->seq;
  560. struct trace_entry *entry = iter->ent,
  561. *next_entry = trace_find_next_entry(iter, NULL,
  562. &next_ts);
  563. unsigned long verbose = (trace_flags & TRACE_ITER_VERBOSE);
  564. /* Restore the original ent_size */
  565. iter->ent_size = ent_size;
  566. if (!next_entry)
  567. next_ts = iter->ts;
  568. if (verbose) {
  569. char comm[TASK_COMM_LEN];
  570. trace_find_cmdline(entry->pid, comm);
  571. ret = trace_seq_printf(
  572. s, "%16s %5d %3d %d %08x %08lx ",
  573. comm, entry->pid, iter->cpu, entry->flags,
  574. entry->preempt_count, iter->idx);
  575. } else {
  576. ret = lat_print_generic(s, entry, iter->cpu);
  577. }
  578. if (ret)
  579. ret = lat_print_timestamp(iter, next_ts);
  580. return ret;
  581. }
  582. static const char state_to_char[] = TASK_STATE_TO_CHAR_STR;
  583. static int task_state_char(unsigned long state)
  584. {
  585. int bit = state ? __ffs(state) + 1 : 0;
  586. return bit < sizeof(state_to_char) - 1 ? state_to_char[bit] : '?';
  587. }
  588. /**
  589. * ftrace_find_event - find a registered event
  590. * @type: the type of event to look for
  591. *
  592. * Returns an event of type @type otherwise NULL
  593. * Called with trace_event_read_lock() held.
  594. */
  595. struct trace_event *ftrace_find_event(int type)
  596. {
  597. struct trace_event *event;
  598. struct hlist_node *n;
  599. unsigned key;
  600. key = type & (EVENT_HASHSIZE - 1);
  601. hlist_for_each_entry(event, n, &event_hash[key], node) {
  602. if (event->type == type)
  603. return event;
  604. }
  605. return NULL;
  606. }
  607. static LIST_HEAD(ftrace_event_list);
  608. static int trace_search_list(struct list_head **list)
  609. {
  610. struct trace_event *e;
  611. int last = __TRACE_LAST_TYPE;
  612. if (list_empty(&ftrace_event_list)) {
  613. *list = &ftrace_event_list;
  614. return last + 1;
  615. }
  616. /*
  617. * We used up all possible max events,
  618. * lets see if somebody freed one.
  619. */
  620. list_for_each_entry(e, &ftrace_event_list, list) {
  621. if (e->type != last + 1)
  622. break;
  623. last++;
  624. }
  625. /* Did we used up all 65 thousand events??? */
  626. if ((last + 1) > FTRACE_MAX_EVENT)
  627. return 0;
  628. *list = &e->list;
  629. return last + 1;
  630. }
  631. void trace_event_read_lock(void)
  632. {
  633. down_read(&trace_event_mutex);
  634. }
  635. void trace_event_read_unlock(void)
  636. {
  637. up_read(&trace_event_mutex);
  638. }
  639. /**
  640. * register_ftrace_event - register output for an event type
  641. * @event: the event type to register
  642. *
  643. * Event types are stored in a hash and this hash is used to
  644. * find a way to print an event. If the @event->type is set
  645. * then it will use that type, otherwise it will assign a
  646. * type to use.
  647. *
  648. * If you assign your own type, please make sure it is added
  649. * to the trace_type enum in trace.h, to avoid collisions
  650. * with the dynamic types.
  651. *
  652. * Returns the event type number or zero on error.
  653. */
  654. int register_ftrace_event(struct trace_event *event)
  655. {
  656. unsigned key;
  657. int ret = 0;
  658. down_write(&trace_event_mutex);
  659. if (WARN_ON(!event))
  660. goto out;
  661. if (WARN_ON(!event->funcs))
  662. goto out;
  663. INIT_LIST_HEAD(&event->list);
  664. if (!event->type) {
  665. struct list_head *list = NULL;
  666. if (next_event_type > FTRACE_MAX_EVENT) {
  667. event->type = trace_search_list(&list);
  668. if (!event->type)
  669. goto out;
  670. } else {
  671. event->type = next_event_type++;
  672. list = &ftrace_event_list;
  673. }
  674. if (WARN_ON(ftrace_find_event(event->type)))
  675. goto out;
  676. list_add_tail(&event->list, list);
  677. } else if (event->type > __TRACE_LAST_TYPE) {
  678. printk(KERN_WARNING "Need to add type to trace.h\n");
  679. WARN_ON(1);
  680. goto out;
  681. } else {
  682. /* Is this event already used */
  683. if (ftrace_find_event(event->type))
  684. goto out;
  685. }
  686. if (event->funcs->trace == NULL)
  687. event->funcs->trace = trace_nop_print;
  688. if (event->funcs->raw == NULL)
  689. event->funcs->raw = trace_nop_print;
  690. if (event->funcs->hex == NULL)
  691. event->funcs->hex = trace_nop_print;
  692. if (event->funcs->binary == NULL)
  693. event->funcs->binary = trace_nop_print;
  694. key = event->type & (EVENT_HASHSIZE - 1);
  695. hlist_add_head(&event->node, &event_hash[key]);
  696. ret = event->type;
  697. out:
  698. up_write(&trace_event_mutex);
  699. return ret;
  700. }
  701. EXPORT_SYMBOL_GPL(register_ftrace_event);
  702. /*
  703. * Used by module code with the trace_event_mutex held for write.
  704. */
  705. int __unregister_ftrace_event(struct trace_event *event)
  706. {
  707. hlist_del(&event->node);
  708. list_del(&event->list);
  709. return 0;
  710. }
  711. /**
  712. * unregister_ftrace_event - remove a no longer used event
  713. * @event: the event to remove
  714. */
  715. int unregister_ftrace_event(struct trace_event *event)
  716. {
  717. down_write(&trace_event_mutex);
  718. __unregister_ftrace_event(event);
  719. up_write(&trace_event_mutex);
  720. return 0;
  721. }
  722. EXPORT_SYMBOL_GPL(unregister_ftrace_event);
  723. /*
  724. * Standard events
  725. */
  726. enum print_line_t trace_nop_print(struct trace_iterator *iter, int flags,
  727. struct trace_event *event)
  728. {
  729. if (!trace_seq_printf(&iter->seq, "type: %d\n", iter->ent->type))
  730. return TRACE_TYPE_PARTIAL_LINE;
  731. return TRACE_TYPE_HANDLED;
  732. }
  733. /* TRACE_FN */
  734. static enum print_line_t trace_fn_trace(struct trace_iterator *iter, int flags,
  735. struct trace_event *event)
  736. {
  737. struct ftrace_entry *field;
  738. struct trace_seq *s = &iter->seq;
  739. trace_assign_type(field, iter->ent);
  740. if (!seq_print_ip_sym(s, field->ip, flags))
  741. goto partial;
  742. if ((flags & TRACE_ITER_PRINT_PARENT) && field->parent_ip) {
  743. if (!trace_seq_printf(s, " <-"))
  744. goto partial;
  745. if (!seq_print_ip_sym(s,
  746. field->parent_ip,
  747. flags))
  748. goto partial;
  749. }
  750. if (!trace_seq_printf(s, "\n"))
  751. goto partial;
  752. return TRACE_TYPE_HANDLED;
  753. partial:
  754. return TRACE_TYPE_PARTIAL_LINE;
  755. }
  756. static enum print_line_t trace_fn_raw(struct trace_iterator *iter, int flags,
  757. struct trace_event *event)
  758. {
  759. struct ftrace_entry *field;
  760. trace_assign_type(field, iter->ent);
  761. if (!trace_seq_printf(&iter->seq, "%lx %lx\n",
  762. field->ip,
  763. field->parent_ip))
  764. return TRACE_TYPE_PARTIAL_LINE;
  765. return TRACE_TYPE_HANDLED;
  766. }
  767. static enum print_line_t trace_fn_hex(struct trace_iterator *iter, int flags,
  768. struct trace_event *event)
  769. {
  770. struct ftrace_entry *field;
  771. struct trace_seq *s = &iter->seq;
  772. trace_assign_type(field, iter->ent);
  773. SEQ_PUT_HEX_FIELD_RET(s, field->ip);
  774. SEQ_PUT_HEX_FIELD_RET(s, field->parent_ip);
  775. return TRACE_TYPE_HANDLED;
  776. }
  777. static enum print_line_t trace_fn_bin(struct trace_iterator *iter, int flags,
  778. struct trace_event *event)
  779. {
  780. struct ftrace_entry *field;
  781. struct trace_seq *s = &iter->seq;
  782. trace_assign_type(field, iter->ent);
  783. SEQ_PUT_FIELD_RET(s, field->ip);
  784. SEQ_PUT_FIELD_RET(s, field->parent_ip);
  785. return TRACE_TYPE_HANDLED;
  786. }
  787. static struct trace_event_functions trace_fn_funcs = {
  788. .trace = trace_fn_trace,
  789. .raw = trace_fn_raw,
  790. .hex = trace_fn_hex,
  791. .binary = trace_fn_bin,
  792. };
  793. static struct trace_event trace_fn_event = {
  794. .type = TRACE_FN,
  795. .funcs = &trace_fn_funcs,
  796. };
  797. /* TRACE_CTX an TRACE_WAKE */
  798. static enum print_line_t trace_ctxwake_print(struct trace_iterator *iter,
  799. char *delim)
  800. {
  801. struct ctx_switch_entry *field;
  802. char comm[TASK_COMM_LEN];
  803. int S, T;
  804. trace_assign_type(field, iter->ent);
  805. T = task_state_char(field->next_state);
  806. S = task_state_char(field->prev_state);
  807. trace_find_cmdline(field->next_pid, comm);
  808. if (!trace_seq_printf(&iter->seq,
  809. " %5d:%3d:%c %s [%03d] %5d:%3d:%c %s\n",
  810. field->prev_pid,
  811. field->prev_prio,
  812. S, delim,
  813. field->next_cpu,
  814. field->next_pid,
  815. field->next_prio,
  816. T, comm))
  817. return TRACE_TYPE_PARTIAL_LINE;
  818. return TRACE_TYPE_HANDLED;
  819. }
  820. static enum print_line_t trace_ctx_print(struct trace_iterator *iter, int flags,
  821. struct trace_event *event)
  822. {
  823. return trace_ctxwake_print(iter, "==>");
  824. }
  825. static enum print_line_t trace_wake_print(struct trace_iterator *iter,
  826. int flags, struct trace_event *event)
  827. {
  828. return trace_ctxwake_print(iter, " +");
  829. }
  830. static int trace_ctxwake_raw(struct trace_iterator *iter, char S)
  831. {
  832. struct ctx_switch_entry *field;
  833. int T;
  834. trace_assign_type(field, iter->ent);
  835. if (!S)
  836. S = task_state_char(field->prev_state);
  837. T = task_state_char(field->next_state);
  838. if (!trace_seq_printf(&iter->seq, "%d %d %c %d %d %d %c\n",
  839. field->prev_pid,
  840. field->prev_prio,
  841. S,
  842. field->next_cpu,
  843. field->next_pid,
  844. field->next_prio,
  845. T))
  846. return TRACE_TYPE_PARTIAL_LINE;
  847. return TRACE_TYPE_HANDLED;
  848. }
  849. static enum print_line_t trace_ctx_raw(struct trace_iterator *iter, int flags,
  850. struct trace_event *event)
  851. {
  852. return trace_ctxwake_raw(iter, 0);
  853. }
  854. static enum print_line_t trace_wake_raw(struct trace_iterator *iter, int flags,
  855. struct trace_event *event)
  856. {
  857. return trace_ctxwake_raw(iter, '+');
  858. }
  859. static int trace_ctxwake_hex(struct trace_iterator *iter, char S)
  860. {
  861. struct ctx_switch_entry *field;
  862. struct trace_seq *s = &iter->seq;
  863. int T;
  864. trace_assign_type(field, iter->ent);
  865. if (!S)
  866. S = task_state_char(field->prev_state);
  867. T = task_state_char(field->next_state);
  868. SEQ_PUT_HEX_FIELD_RET(s, field->prev_pid);
  869. SEQ_PUT_HEX_FIELD_RET(s, field->prev_prio);
  870. SEQ_PUT_HEX_FIELD_RET(s, S);
  871. SEQ_PUT_HEX_FIELD_RET(s, field->next_cpu);
  872. SEQ_PUT_HEX_FIELD_RET(s, field->next_pid);
  873. SEQ_PUT_HEX_FIELD_RET(s, field->next_prio);
  874. SEQ_PUT_HEX_FIELD_RET(s, T);
  875. return TRACE_TYPE_HANDLED;
  876. }
  877. static enum print_line_t trace_ctx_hex(struct trace_iterator *iter, int flags,
  878. struct trace_event *event)
  879. {
  880. return trace_ctxwake_hex(iter, 0);
  881. }
  882. static enum print_line_t trace_wake_hex(struct trace_iterator *iter, int flags,
  883. struct trace_event *event)
  884. {
  885. return trace_ctxwake_hex(iter, '+');
  886. }
  887. static enum print_line_t trace_ctxwake_bin(struct trace_iterator *iter,
  888. int flags, struct trace_event *event)
  889. {
  890. struct ctx_switch_entry *field;
  891. struct trace_seq *s = &iter->seq;
  892. trace_assign_type(field, iter->ent);
  893. SEQ_PUT_FIELD_RET(s, field->prev_pid);
  894. SEQ_PUT_FIELD_RET(s, field->prev_prio);
  895. SEQ_PUT_FIELD_RET(s, field->prev_state);
  896. SEQ_PUT_FIELD_RET(s, field->next_pid);
  897. SEQ_PUT_FIELD_RET(s, field->next_prio);
  898. SEQ_PUT_FIELD_RET(s, field->next_state);
  899. return TRACE_TYPE_HANDLED;
  900. }
  901. static struct trace_event_functions trace_ctx_funcs = {
  902. .trace = trace_ctx_print,
  903. .raw = trace_ctx_raw,
  904. .hex = trace_ctx_hex,
  905. .binary = trace_ctxwake_bin,
  906. };
  907. static struct trace_event trace_ctx_event = {
  908. .type = TRACE_CTX,
  909. .funcs = &trace_ctx_funcs,
  910. };
  911. static struct trace_event_functions trace_wake_funcs = {
  912. .trace = trace_wake_print,
  913. .raw = trace_wake_raw,
  914. .hex = trace_wake_hex,
  915. .binary = trace_ctxwake_bin,
  916. };
  917. static struct trace_event trace_wake_event = {
  918. .type = TRACE_WAKE,
  919. .funcs = &trace_wake_funcs,
  920. };
  921. /* TRACE_STACK */
  922. static enum print_line_t trace_stack_print(struct trace_iterator *iter,
  923. int flags, struct trace_event *event)
  924. {
  925. struct stack_entry *field;
  926. struct trace_seq *s = &iter->seq;
  927. unsigned long *p;
  928. unsigned long *end;
  929. trace_assign_type(field, iter->ent);
  930. end = (unsigned long *)((long)iter->ent + iter->ent_size);
  931. if (!trace_seq_puts(s, "<stack trace>\n"))
  932. goto partial;
  933. for (p = field->caller; p && *p != ULONG_MAX && p < end; p++) {
  934. if (!trace_seq_puts(s, " => "))
  935. goto partial;
  936. if (!seq_print_ip_sym(s, *p, flags))
  937. goto partial;
  938. if (!trace_seq_puts(s, "\n"))
  939. goto partial;
  940. }
  941. return TRACE_TYPE_HANDLED;
  942. partial:
  943. return TRACE_TYPE_PARTIAL_LINE;
  944. }
  945. static struct trace_event_functions trace_stack_funcs = {
  946. .trace = trace_stack_print,
  947. };
  948. static struct trace_event trace_stack_event = {
  949. .type = TRACE_STACK,
  950. .funcs = &trace_stack_funcs,
  951. };
  952. /* TRACE_USER_STACK */
  953. static enum print_line_t trace_user_stack_print(struct trace_iterator *iter,
  954. int flags, struct trace_event *event)
  955. {
  956. struct userstack_entry *field;
  957. struct trace_seq *s = &iter->seq;
  958. trace_assign_type(field, iter->ent);
  959. if (!trace_seq_puts(s, "<user stack trace>\n"))
  960. goto partial;
  961. if (!seq_print_userip_objs(field, s, flags))
  962. goto partial;
  963. return TRACE_TYPE_HANDLED;
  964. partial:
  965. return TRACE_TYPE_PARTIAL_LINE;
  966. }
  967. static struct trace_event_functions trace_user_stack_funcs = {
  968. .trace = trace_user_stack_print,
  969. };
  970. static struct trace_event trace_user_stack_event = {
  971. .type = TRACE_USER_STACK,
  972. .funcs = &trace_user_stack_funcs,
  973. };
  974. /* TRACE_BPRINT */
  975. static enum print_line_t
  976. trace_bprint_print(struct trace_iterator *iter, int flags,
  977. struct trace_event *event)
  978. {
  979. struct trace_entry *entry = iter->ent;
  980. struct trace_seq *s = &iter->seq;
  981. struct bprint_entry *field;
  982. trace_assign_type(field, entry);
  983. if (!seq_print_ip_sym(s, field->ip, flags))
  984. goto partial;
  985. if (!trace_seq_puts(s, ": "))
  986. goto partial;
  987. if (!trace_seq_bprintf(s, field->fmt, field->buf))
  988. goto partial;
  989. return TRACE_TYPE_HANDLED;
  990. partial:
  991. return TRACE_TYPE_PARTIAL_LINE;
  992. }
  993. static enum print_line_t
  994. trace_bprint_raw(struct trace_iterator *iter, int flags,
  995. struct trace_event *event)
  996. {
  997. struct bprint_entry *field;
  998. struct trace_seq *s = &iter->seq;
  999. trace_assign_type(field, iter->ent);
  1000. if (!trace_seq_printf(s, ": %lx : ", field->ip))
  1001. goto partial;
  1002. if (!trace_seq_bprintf(s, field->fmt, field->buf))
  1003. goto partial;
  1004. return TRACE_TYPE_HANDLED;
  1005. partial:
  1006. return TRACE_TYPE_PARTIAL_LINE;
  1007. }
  1008. static struct trace_event_functions trace_bprint_funcs = {
  1009. .trace = trace_bprint_print,
  1010. .raw = trace_bprint_raw,
  1011. };
  1012. static struct trace_event trace_bprint_event = {
  1013. .type = TRACE_BPRINT,
  1014. .funcs = &trace_bprint_funcs,
  1015. };
  1016. /* TRACE_PRINT */
  1017. static enum print_line_t trace_print_print(struct trace_iterator *iter,
  1018. int flags, struct trace_event *event)
  1019. {
  1020. struct print_entry *field;
  1021. struct trace_seq *s = &iter->seq;
  1022. trace_assign_type(field, iter->ent);
  1023. if (!seq_print_ip_sym(s, field->ip, flags))
  1024. goto partial;
  1025. if (!trace_seq_printf(s, ": %s", field->buf))
  1026. goto partial;
  1027. return TRACE_TYPE_HANDLED;
  1028. partial:
  1029. return TRACE_TYPE_PARTIAL_LINE;
  1030. }
  1031. static enum print_line_t trace_print_raw(struct trace_iterator *iter, int flags,
  1032. struct trace_event *event)
  1033. {
  1034. struct print_entry *field;
  1035. trace_assign_type(field, iter->ent);
  1036. if (!trace_seq_printf(&iter->seq, "# %lx %s", field->ip, field->buf))
  1037. goto partial;
  1038. return TRACE_TYPE_HANDLED;
  1039. partial:
  1040. return TRACE_TYPE_PARTIAL_LINE;
  1041. }
  1042. static struct trace_event_functions trace_print_funcs = {
  1043. .trace = trace_print_print,
  1044. .raw = trace_print_raw,
  1045. };
  1046. static struct trace_event trace_print_event = {
  1047. .type = TRACE_PRINT,
  1048. .funcs = &trace_print_funcs,
  1049. };
  1050. static struct trace_event *events[] __initdata = {
  1051. &trace_fn_event,
  1052. &trace_ctx_event,
  1053. &trace_wake_event,
  1054. &trace_stack_event,
  1055. &trace_user_stack_event,
  1056. &trace_bprint_event,
  1057. &trace_print_event,
  1058. NULL
  1059. };
  1060. __init static int init_events(void)
  1061. {
  1062. struct trace_event *event;
  1063. int i, ret;
  1064. for (i = 0; events[i]; i++) {
  1065. event = events[i];
  1066. ret = register_ftrace_event(event);
  1067. if (!ret) {
  1068. printk(KERN_WARNING "event %d failed to register\n",
  1069. event->type);
  1070. WARN_ON_ONCE(1);
  1071. }
  1072. }
  1073. return 0;
  1074. }
  1075. early_initcall(init_events);