trace_output.c 28 KB

12345678910111213141516171819202122232425262728293031323334353637383940414243444546474849505152535455565758596061626364656667686970717273747576777879808182838485868788899091929394959697989910010110210310410510610710810911011111211311411511611711811912012112212312412512612712812913013113213313413513613713813914014114214314414514614714814915015115215315415515615715815916016116216316416516616716816917017117217317417517617717817918018118218318418518618718818919019119219319419519619719819920020120220320420520620720820921021121221321421521621721821922022122222322422522622722822923023123223323423523623723823924024124224324424524624724824925025125225325425525625725825926026126226326426526626726826927027127227327427527627727827928028128228328428528628728828929029129229329429529629729829930030130230330430530630730830931031131231331431531631731831932032132232332432532632732832933033133233333433533633733833934034134234334434534634734834935035135235335435535635735835936036136236336436536636736836937037137237337437537637737837938038138238338438538638738838939039139239339439539639739839940040140240340440540640740840941041141241341441541641741841942042142242342442542642742842943043143243343443543643743843944044144244344444544644744844945045145245345445545645745845946046146246346446546646746846947047147247347447547647747847948048148248348448548648748848949049149249349449549649749849950050150250350450550650750850951051151251351451551651751851952052152252352452552652752852953053153253353453553653753853954054154254354454554654754854955055155255355455555655755855956056156256356456556656756856957057157257357457557657757857958058158258358458558658758858959059159259359459559659759859960060160260360460560660760860961061161261361461561661761861962062162262362462562662762862963063163263363463563663763863964064164264364464564664764864965065165265365465565665765865966066166266366466566666766866967067167267367467567667767867968068168268368468568668768868969069169269369469569669769869970070170270370470570670770870971071171271371471571671771871972072172272372472572672772872973073173273373473573673773873974074174274374474574674774874975075175275375475575675775875976076176276376476576676776876977077177277377477577677777877978078178278378478578678778878979079179279379479579679779879980080180280380480580680780880981081181281381481581681781881982082182282382482582682782882983083183283383483583683783883984084184284384484584684784884985085185285385485585685785885986086186286386486586686786886987087187287387487587687787887988088188288388488588688788888989089189289389489589689789889990090190290390490590690790890991091191291391491591691791891992092192292392492592692792892993093193293393493593693793893994094194294394494594694794894995095195295395495595695795895996096196296396496596696796896997097197297397497597697797897998098198298398498598698798898999099199299399499599699799899910001001100210031004100510061007100810091010101110121013101410151016101710181019102010211022102310241025102610271028102910301031103210331034103510361037103810391040104110421043104410451046104710481049105010511052105310541055105610571058105910601061106210631064106510661067106810691070107110721073107410751076107710781079108010811082108310841085108610871088108910901091109210931094109510961097109810991100110111021103110411051106110711081109111011111112111311141115111611171118111911201121112211231124112511261127112811291130113111321133113411351136113711381139114011411142114311441145114611471148114911501151115211531154115511561157115811591160116111621163116411651166116711681169117011711172117311741175117611771178117911801181118211831184118511861187118811891190119111921193119411951196119711981199120012011202120312041205120612071208120912101211121212131214121512161217121812191220122112221223122412251226122712281229123012311232123312341235123612371238123912401241124212431244124512461247124812491250125112521253125412551256125712581259126012611262126312641265126612671268126912701271127212731274127512761277127812791280128112821283128412851286128712881289129012911292129312941295129612971298129913001301130213031304130513061307130813091310131113121313131413151316131713181319132013211322132313241325132613271328132913301331133213331334133513361337133813391340134113421343134413451346134713481349135013511352135313541355
  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. unsigned key;
  599. key = type & (EVENT_HASHSIZE - 1);
  600. hlist_for_each_entry(event, &event_hash[key], node) {
  601. if (event->type == type)
  602. return event;
  603. }
  604. return NULL;
  605. }
  606. static LIST_HEAD(ftrace_event_list);
  607. static int trace_search_list(struct list_head **list)
  608. {
  609. struct trace_event *e;
  610. int last = __TRACE_LAST_TYPE;
  611. if (list_empty(&ftrace_event_list)) {
  612. *list = &ftrace_event_list;
  613. return last + 1;
  614. }
  615. /*
  616. * We used up all possible max events,
  617. * lets see if somebody freed one.
  618. */
  619. list_for_each_entry(e, &ftrace_event_list, list) {
  620. if (e->type != last + 1)
  621. break;
  622. last++;
  623. }
  624. /* Did we used up all 65 thousand events??? */
  625. if ((last + 1) > FTRACE_MAX_EVENT)
  626. return 0;
  627. *list = &e->list;
  628. return last + 1;
  629. }
  630. void trace_event_read_lock(void)
  631. {
  632. down_read(&trace_event_mutex);
  633. }
  634. void trace_event_read_unlock(void)
  635. {
  636. up_read(&trace_event_mutex);
  637. }
  638. /**
  639. * register_ftrace_event - register output for an event type
  640. * @event: the event type to register
  641. *
  642. * Event types are stored in a hash and this hash is used to
  643. * find a way to print an event. If the @event->type is set
  644. * then it will use that type, otherwise it will assign a
  645. * type to use.
  646. *
  647. * If you assign your own type, please make sure it is added
  648. * to the trace_type enum in trace.h, to avoid collisions
  649. * with the dynamic types.
  650. *
  651. * Returns the event type number or zero on error.
  652. */
  653. int register_ftrace_event(struct trace_event *event)
  654. {
  655. unsigned key;
  656. int ret = 0;
  657. down_write(&trace_event_mutex);
  658. if (WARN_ON(!event))
  659. goto out;
  660. if (WARN_ON(!event->funcs))
  661. goto out;
  662. INIT_LIST_HEAD(&event->list);
  663. if (!event->type) {
  664. struct list_head *list = NULL;
  665. if (next_event_type > FTRACE_MAX_EVENT) {
  666. event->type = trace_search_list(&list);
  667. if (!event->type)
  668. goto out;
  669. } else {
  670. event->type = next_event_type++;
  671. list = &ftrace_event_list;
  672. }
  673. if (WARN_ON(ftrace_find_event(event->type)))
  674. goto out;
  675. list_add_tail(&event->list, list);
  676. } else if (event->type > __TRACE_LAST_TYPE) {
  677. printk(KERN_WARNING "Need to add type to trace.h\n");
  678. WARN_ON(1);
  679. goto out;
  680. } else {
  681. /* Is this event already used */
  682. if (ftrace_find_event(event->type))
  683. goto out;
  684. }
  685. if (event->funcs->trace == NULL)
  686. event->funcs->trace = trace_nop_print;
  687. if (event->funcs->raw == NULL)
  688. event->funcs->raw = trace_nop_print;
  689. if (event->funcs->hex == NULL)
  690. event->funcs->hex = trace_nop_print;
  691. if (event->funcs->binary == NULL)
  692. event->funcs->binary = trace_nop_print;
  693. key = event->type & (EVENT_HASHSIZE - 1);
  694. hlist_add_head(&event->node, &event_hash[key]);
  695. ret = event->type;
  696. out:
  697. up_write(&trace_event_mutex);
  698. return ret;
  699. }
  700. EXPORT_SYMBOL_GPL(register_ftrace_event);
  701. /*
  702. * Used by module code with the trace_event_mutex held for write.
  703. */
  704. int __unregister_ftrace_event(struct trace_event *event)
  705. {
  706. hlist_del(&event->node);
  707. list_del(&event->list);
  708. return 0;
  709. }
  710. /**
  711. * unregister_ftrace_event - remove a no longer used event
  712. * @event: the event to remove
  713. */
  714. int unregister_ftrace_event(struct trace_event *event)
  715. {
  716. down_write(&trace_event_mutex);
  717. __unregister_ftrace_event(event);
  718. up_write(&trace_event_mutex);
  719. return 0;
  720. }
  721. EXPORT_SYMBOL_GPL(unregister_ftrace_event);
  722. /*
  723. * Standard events
  724. */
  725. enum print_line_t trace_nop_print(struct trace_iterator *iter, int flags,
  726. struct trace_event *event)
  727. {
  728. if (!trace_seq_printf(&iter->seq, "type: %d\n", iter->ent->type))
  729. return TRACE_TYPE_PARTIAL_LINE;
  730. return TRACE_TYPE_HANDLED;
  731. }
  732. /* TRACE_FN */
  733. static enum print_line_t trace_fn_trace(struct trace_iterator *iter, int flags,
  734. struct trace_event *event)
  735. {
  736. struct ftrace_entry *field;
  737. struct trace_seq *s = &iter->seq;
  738. trace_assign_type(field, iter->ent);
  739. if (!seq_print_ip_sym(s, field->ip, flags))
  740. goto partial;
  741. if ((flags & TRACE_ITER_PRINT_PARENT) && field->parent_ip) {
  742. if (!trace_seq_printf(s, " <-"))
  743. goto partial;
  744. if (!seq_print_ip_sym(s,
  745. field->parent_ip,
  746. flags))
  747. goto partial;
  748. }
  749. if (!trace_seq_printf(s, "\n"))
  750. goto partial;
  751. return TRACE_TYPE_HANDLED;
  752. partial:
  753. return TRACE_TYPE_PARTIAL_LINE;
  754. }
  755. static enum print_line_t trace_fn_raw(struct trace_iterator *iter, int flags,
  756. struct trace_event *event)
  757. {
  758. struct ftrace_entry *field;
  759. trace_assign_type(field, iter->ent);
  760. if (!trace_seq_printf(&iter->seq, "%lx %lx\n",
  761. field->ip,
  762. field->parent_ip))
  763. return TRACE_TYPE_PARTIAL_LINE;
  764. return TRACE_TYPE_HANDLED;
  765. }
  766. static enum print_line_t trace_fn_hex(struct trace_iterator *iter, int flags,
  767. struct trace_event *event)
  768. {
  769. struct ftrace_entry *field;
  770. struct trace_seq *s = &iter->seq;
  771. trace_assign_type(field, iter->ent);
  772. SEQ_PUT_HEX_FIELD_RET(s, field->ip);
  773. SEQ_PUT_HEX_FIELD_RET(s, field->parent_ip);
  774. return TRACE_TYPE_HANDLED;
  775. }
  776. static enum print_line_t trace_fn_bin(struct trace_iterator *iter, int flags,
  777. struct trace_event *event)
  778. {
  779. struct ftrace_entry *field;
  780. struct trace_seq *s = &iter->seq;
  781. trace_assign_type(field, iter->ent);
  782. SEQ_PUT_FIELD_RET(s, field->ip);
  783. SEQ_PUT_FIELD_RET(s, field->parent_ip);
  784. return TRACE_TYPE_HANDLED;
  785. }
  786. static struct trace_event_functions trace_fn_funcs = {
  787. .trace = trace_fn_trace,
  788. .raw = trace_fn_raw,
  789. .hex = trace_fn_hex,
  790. .binary = trace_fn_bin,
  791. };
  792. static struct trace_event trace_fn_event = {
  793. .type = TRACE_FN,
  794. .funcs = &trace_fn_funcs,
  795. };
  796. /* TRACE_CTX an TRACE_WAKE */
  797. static enum print_line_t trace_ctxwake_print(struct trace_iterator *iter,
  798. char *delim)
  799. {
  800. struct ctx_switch_entry *field;
  801. char comm[TASK_COMM_LEN];
  802. int S, T;
  803. trace_assign_type(field, iter->ent);
  804. T = task_state_char(field->next_state);
  805. S = task_state_char(field->prev_state);
  806. trace_find_cmdline(field->next_pid, comm);
  807. if (!trace_seq_printf(&iter->seq,
  808. " %5d:%3d:%c %s [%03d] %5d:%3d:%c %s\n",
  809. field->prev_pid,
  810. field->prev_prio,
  811. S, delim,
  812. field->next_cpu,
  813. field->next_pid,
  814. field->next_prio,
  815. T, comm))
  816. return TRACE_TYPE_PARTIAL_LINE;
  817. return TRACE_TYPE_HANDLED;
  818. }
  819. static enum print_line_t trace_ctx_print(struct trace_iterator *iter, int flags,
  820. struct trace_event *event)
  821. {
  822. return trace_ctxwake_print(iter, "==>");
  823. }
  824. static enum print_line_t trace_wake_print(struct trace_iterator *iter,
  825. int flags, struct trace_event *event)
  826. {
  827. return trace_ctxwake_print(iter, " +");
  828. }
  829. static int trace_ctxwake_raw(struct trace_iterator *iter, char S)
  830. {
  831. struct ctx_switch_entry *field;
  832. int T;
  833. trace_assign_type(field, iter->ent);
  834. if (!S)
  835. S = task_state_char(field->prev_state);
  836. T = task_state_char(field->next_state);
  837. if (!trace_seq_printf(&iter->seq, "%d %d %c %d %d %d %c\n",
  838. field->prev_pid,
  839. field->prev_prio,
  840. S,
  841. field->next_cpu,
  842. field->next_pid,
  843. field->next_prio,
  844. T))
  845. return TRACE_TYPE_PARTIAL_LINE;
  846. return TRACE_TYPE_HANDLED;
  847. }
  848. static enum print_line_t trace_ctx_raw(struct trace_iterator *iter, int flags,
  849. struct trace_event *event)
  850. {
  851. return trace_ctxwake_raw(iter, 0);
  852. }
  853. static enum print_line_t trace_wake_raw(struct trace_iterator *iter, int flags,
  854. struct trace_event *event)
  855. {
  856. return trace_ctxwake_raw(iter, '+');
  857. }
  858. static int trace_ctxwake_hex(struct trace_iterator *iter, char S)
  859. {
  860. struct ctx_switch_entry *field;
  861. struct trace_seq *s = &iter->seq;
  862. int T;
  863. trace_assign_type(field, iter->ent);
  864. if (!S)
  865. S = task_state_char(field->prev_state);
  866. T = task_state_char(field->next_state);
  867. SEQ_PUT_HEX_FIELD_RET(s, field->prev_pid);
  868. SEQ_PUT_HEX_FIELD_RET(s, field->prev_prio);
  869. SEQ_PUT_HEX_FIELD_RET(s, S);
  870. SEQ_PUT_HEX_FIELD_RET(s, field->next_cpu);
  871. SEQ_PUT_HEX_FIELD_RET(s, field->next_pid);
  872. SEQ_PUT_HEX_FIELD_RET(s, field->next_prio);
  873. SEQ_PUT_HEX_FIELD_RET(s, T);
  874. return TRACE_TYPE_HANDLED;
  875. }
  876. static enum print_line_t trace_ctx_hex(struct trace_iterator *iter, int flags,
  877. struct trace_event *event)
  878. {
  879. return trace_ctxwake_hex(iter, 0);
  880. }
  881. static enum print_line_t trace_wake_hex(struct trace_iterator *iter, int flags,
  882. struct trace_event *event)
  883. {
  884. return trace_ctxwake_hex(iter, '+');
  885. }
  886. static enum print_line_t trace_ctxwake_bin(struct trace_iterator *iter,
  887. int flags, struct trace_event *event)
  888. {
  889. struct ctx_switch_entry *field;
  890. struct trace_seq *s = &iter->seq;
  891. trace_assign_type(field, iter->ent);
  892. SEQ_PUT_FIELD_RET(s, field->prev_pid);
  893. SEQ_PUT_FIELD_RET(s, field->prev_prio);
  894. SEQ_PUT_FIELD_RET(s, field->prev_state);
  895. SEQ_PUT_FIELD_RET(s, field->next_pid);
  896. SEQ_PUT_FIELD_RET(s, field->next_prio);
  897. SEQ_PUT_FIELD_RET(s, field->next_state);
  898. return TRACE_TYPE_HANDLED;
  899. }
  900. static struct trace_event_functions trace_ctx_funcs = {
  901. .trace = trace_ctx_print,
  902. .raw = trace_ctx_raw,
  903. .hex = trace_ctx_hex,
  904. .binary = trace_ctxwake_bin,
  905. };
  906. static struct trace_event trace_ctx_event = {
  907. .type = TRACE_CTX,
  908. .funcs = &trace_ctx_funcs,
  909. };
  910. static struct trace_event_functions trace_wake_funcs = {
  911. .trace = trace_wake_print,
  912. .raw = trace_wake_raw,
  913. .hex = trace_wake_hex,
  914. .binary = trace_ctxwake_bin,
  915. };
  916. static struct trace_event trace_wake_event = {
  917. .type = TRACE_WAKE,
  918. .funcs = &trace_wake_funcs,
  919. };
  920. /* TRACE_STACK */
  921. static enum print_line_t trace_stack_print(struct trace_iterator *iter,
  922. int flags, struct trace_event *event)
  923. {
  924. struct stack_entry *field;
  925. struct trace_seq *s = &iter->seq;
  926. unsigned long *p;
  927. unsigned long *end;
  928. trace_assign_type(field, iter->ent);
  929. end = (unsigned long *)((long)iter->ent + iter->ent_size);
  930. if (!trace_seq_puts(s, "<stack trace>\n"))
  931. goto partial;
  932. for (p = field->caller; p && *p != ULONG_MAX && p < end; p++) {
  933. if (!trace_seq_puts(s, " => "))
  934. goto partial;
  935. if (!seq_print_ip_sym(s, *p, flags))
  936. goto partial;
  937. if (!trace_seq_puts(s, "\n"))
  938. goto partial;
  939. }
  940. return TRACE_TYPE_HANDLED;
  941. partial:
  942. return TRACE_TYPE_PARTIAL_LINE;
  943. }
  944. static struct trace_event_functions trace_stack_funcs = {
  945. .trace = trace_stack_print,
  946. };
  947. static struct trace_event trace_stack_event = {
  948. .type = TRACE_STACK,
  949. .funcs = &trace_stack_funcs,
  950. };
  951. /* TRACE_USER_STACK */
  952. static enum print_line_t trace_user_stack_print(struct trace_iterator *iter,
  953. int flags, struct trace_event *event)
  954. {
  955. struct userstack_entry *field;
  956. struct trace_seq *s = &iter->seq;
  957. trace_assign_type(field, iter->ent);
  958. if (!trace_seq_puts(s, "<user stack trace>\n"))
  959. goto partial;
  960. if (!seq_print_userip_objs(field, s, flags))
  961. goto partial;
  962. return TRACE_TYPE_HANDLED;
  963. partial:
  964. return TRACE_TYPE_PARTIAL_LINE;
  965. }
  966. static struct trace_event_functions trace_user_stack_funcs = {
  967. .trace = trace_user_stack_print,
  968. };
  969. static struct trace_event trace_user_stack_event = {
  970. .type = TRACE_USER_STACK,
  971. .funcs = &trace_user_stack_funcs,
  972. };
  973. /* TRACE_BPRINT */
  974. static enum print_line_t
  975. trace_bprint_print(struct trace_iterator *iter, int flags,
  976. struct trace_event *event)
  977. {
  978. struct trace_entry *entry = iter->ent;
  979. struct trace_seq *s = &iter->seq;
  980. struct bprint_entry *field;
  981. trace_assign_type(field, entry);
  982. if (!seq_print_ip_sym(s, field->ip, flags))
  983. goto partial;
  984. if (!trace_seq_puts(s, ": "))
  985. goto partial;
  986. if (!trace_seq_bprintf(s, field->fmt, field->buf))
  987. goto partial;
  988. return TRACE_TYPE_HANDLED;
  989. partial:
  990. return TRACE_TYPE_PARTIAL_LINE;
  991. }
  992. static enum print_line_t
  993. trace_bprint_raw(struct trace_iterator *iter, int flags,
  994. struct trace_event *event)
  995. {
  996. struct bprint_entry *field;
  997. struct trace_seq *s = &iter->seq;
  998. trace_assign_type(field, iter->ent);
  999. if (!trace_seq_printf(s, ": %lx : ", field->ip))
  1000. goto partial;
  1001. if (!trace_seq_bprintf(s, field->fmt, field->buf))
  1002. goto partial;
  1003. return TRACE_TYPE_HANDLED;
  1004. partial:
  1005. return TRACE_TYPE_PARTIAL_LINE;
  1006. }
  1007. static struct trace_event_functions trace_bprint_funcs = {
  1008. .trace = trace_bprint_print,
  1009. .raw = trace_bprint_raw,
  1010. };
  1011. static struct trace_event trace_bprint_event = {
  1012. .type = TRACE_BPRINT,
  1013. .funcs = &trace_bprint_funcs,
  1014. };
  1015. /* TRACE_PRINT */
  1016. static enum print_line_t trace_print_print(struct trace_iterator *iter,
  1017. int flags, struct trace_event *event)
  1018. {
  1019. struct print_entry *field;
  1020. struct trace_seq *s = &iter->seq;
  1021. trace_assign_type(field, iter->ent);
  1022. if (!seq_print_ip_sym(s, field->ip, flags))
  1023. goto partial;
  1024. if (!trace_seq_printf(s, ": %s", field->buf))
  1025. goto partial;
  1026. return TRACE_TYPE_HANDLED;
  1027. partial:
  1028. return TRACE_TYPE_PARTIAL_LINE;
  1029. }
  1030. static enum print_line_t trace_print_raw(struct trace_iterator *iter, int flags,
  1031. struct trace_event *event)
  1032. {
  1033. struct print_entry *field;
  1034. trace_assign_type(field, iter->ent);
  1035. if (!trace_seq_printf(&iter->seq, "# %lx %s", field->ip, field->buf))
  1036. goto partial;
  1037. return TRACE_TYPE_HANDLED;
  1038. partial:
  1039. return TRACE_TYPE_PARTIAL_LINE;
  1040. }
  1041. static struct trace_event_functions trace_print_funcs = {
  1042. .trace = trace_print_print,
  1043. .raw = trace_print_raw,
  1044. };
  1045. static struct trace_event trace_print_event = {
  1046. .type = TRACE_PRINT,
  1047. .funcs = &trace_print_funcs,
  1048. };
  1049. static struct trace_event *events[] __initdata = {
  1050. &trace_fn_event,
  1051. &trace_ctx_event,
  1052. &trace_wake_event,
  1053. &trace_stack_event,
  1054. &trace_user_stack_event,
  1055. &trace_bprint_event,
  1056. &trace_print_event,
  1057. NULL
  1058. };
  1059. __init static int init_events(void)
  1060. {
  1061. struct trace_event *event;
  1062. int i, ret;
  1063. for (i = 0; events[i]; i++) {
  1064. event = events[i];
  1065. ret = register_ftrace_event(event);
  1066. if (!ret) {
  1067. printk(KERN_WARNING "event %d failed to register\n",
  1068. event->type);
  1069. WARN_ON_ONCE(1);
  1070. }
  1071. }
  1072. return 0;
  1073. }
  1074. early_initcall(init_events);