trace_output.c 30 KB

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