)]}' { "commit": "352ad25aa4a189c667cb2af333948d34692a2d27", "tree": "83a11c626b3818711d3b88061964c9ea603f7b68", "parents": [ "35e8e302e5d6e32675df2fc1dd3a53dfa6630dc1" ], "author": { "name": "Steven Rostedt", "email": "srostedt@redhat.com", "time": "Mon May 12 21:20:42 2008 +0200" }, "committer": { "name": "Thomas Gleixner", "email": "tglx@linutronix.de", "time": "Fri May 23 20:32:36 2008 +0200" }, "message": "ftrace: tracer for scheduler wakeup latency\n\nThis patch adds the tracer that tracks the wakeup latency of the\nhighest priority waking task.\n\n \"wakeup\" is added to /debugfs/tracing/available_tracers\n\nAlso added to /debugfs/tracing\n\n tracing_max_latency\n holds the current max latency for the wakeup\n\n wakeup_thresh\n if set to other than zero, a log will be recorded\n for every wakeup that takes longer than the number\n entered in here (usecs for all counters)\n (deletes previous trace)\n\nExamples:\n\n (with ftrace_enabled \u003d 0)\n\n\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\npreemption latency trace v1.1.5 on 2.6.24-rc8\nSigned-off-by: Ingo Molnar \u003cmingo@elte.hu\u003e\n--------------------------------------------------------------------\n latency: 26 us, #2/2, CPU#1 | (M:rt VP:0, KP:0, SP:0 HP:0 #P:2)\n -----------------\n | task: migration/0-3 (uid:0 nice:-5 policy:1 rt_prio:99)\n -----------------\n\n _------\u003d\u003e CPU#\n / _-----\u003d\u003e irqs-off\n | / _----\u003d\u003e need-resched\n || / _---\u003d\u003e hardirq/softirq\n ||| / _--\u003d\u003e preempt-depth\n |||| /\n ||||| delay\n cmd pid ||||| time | caller\n \\ / ||||| \\ | /\n quilt-8551 0d..3 0us+: wake_up_process+0x15/0x17 \u003cffffffff80233e80\u003e (sched_exec+0xc9/0x100 \u003cffffffff80235343\u003e)\n quilt-8551 0d..4 26us : sched_switch_callback+0x73/0x81 \u003cffffffff80338d2f\u003e (schedule+0x483/0x6d5 \u003cffffffff8048b3ee\u003e)\n\nvim:ft\u003dhelp\n\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\n\n (with ftrace_enabled \u003d 1)\n\n\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\npreemption latency trace v1.1.5 on 2.6.24-rc8\n--------------------------------------------------------------------\n latency: 36 us, #45/45, CPU#0 | (M:rt VP:0, KP:0, SP:0 HP:0 #P:2)\n -----------------\n | task: migration/1-5 (uid:0 nice:-5 policy:1 rt_prio:99)\n -----------------\n\n _------\u003d\u003e CPU#\n / _-----\u003d\u003e irqs-off\n | / _----\u003d\u003e need-resched\n || / _---\u003d\u003e hardirq/softirq\n ||| / _--\u003d\u003e preempt-depth\n |||| /\n ||||| delay\n cmd pid ||||| time | caller\n \\ / ||||| \\ | /\n bash-10653 1d..3 0us : wake_up_process+0x15/0x17 \u003cffffffff80233e80\u003e (sched_exec+0xc9/0x100 \u003cffffffff80235343\u003e)\n bash-10653 1d..3 1us : try_to_wake_up+0x271/0x2e7 \u003cffffffff80233dcf\u003e (sub_preempt_count+0xc/0x7a \u003cffffffff8023309e\u003e)\n bash-10653 1d..2 2us : try_to_wake_up+0x296/0x2e7 \u003cffffffff80233df4\u003e (update_rq_clock+0x9/0x20 \u003cffffffff802303f3\u003e)\n bash-10653 1d..2 2us : update_rq_clock+0x1e/0x20 \u003cffffffff80230408\u003e (__update_rq_clock+0xc/0x90 \u003cffffffff80230366\u003e)\n bash-10653 1d..2 3us : __update_rq_clock+0x1b/0x90 \u003cffffffff80230375\u003e (sched_clock+0x9/0x29 \u003cffffffff80214529\u003e)\n bash-10653 1d..2 4us : try_to_wake_up+0x2a6/0x2e7 \u003cffffffff80233e04\u003e (activate_task+0xc/0x3f \u003cffffffff8022ffca\u003e)\n bash-10653 1d..2 4us : activate_task+0x2d/0x3f \u003cffffffff8022ffeb\u003e (enqueue_task+0xe/0x66 \u003cffffffff8022ff66\u003e)\n bash-10653 1d..2 5us : enqueue_task+0x5b/0x66 \u003cffffffff8022ffb3\u003e (enqueue_task_rt+0x9/0x3c \u003cffffffff80233351\u003e)\n bash-10653 1d..2 6us : try_to_wake_up+0x2ba/0x2e7 \u003cffffffff80233e18\u003e (check_preempt_wakeup+0x12/0x99 \u003cffffffff80234f84\u003e)\n[...]\n bash-10653 1d..5 33us : tracing_record_cmdline+0xcf/0xd4 \u003cffffffff80338aad\u003e (_spin_unlock+0x9/0x33 \u003cffffffff8048d3ec\u003e)\n bash-10653 1d..5 34us : _spin_unlock+0x19/0x33 \u003cffffffff8048d3fc\u003e (sub_preempt_count+0xc/0x7a \u003cffffffff8023309e\u003e)\n bash-10653 1d..4 35us : wakeup_sched_switch+0x65/0x2ff \u003cffffffff80339f66\u003e (_spin_lock_irqsave+0xc/0xa9 \u003cffffffff8048d08b\u003e)\n bash-10653 1d..4 35us : _spin_lock_irqsave+0x19/0xa9 \u003cffffffff8048d098\u003e (add_preempt_count+0xe/0x77 \u003cffffffff8023311a\u003e)\n bash-10653 1d..4 36us : sched_switch_callback+0x73/0x81 \u003cffffffff80338d2f\u003e (schedule+0x483/0x6d5 \u003cffffffff8048b3ee\u003e)\n\nvim:ft\u003dhelp\n\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\u003d\n\nThe [...] was added here to not waste your email box space.\n\nSigned-off-by: Steven Rostedt \u003csrostedt@redhat.com\u003e\nSigned-off-by: Ingo Molnar \u003cmingo@elte.hu\u003e\nSigned-off-by: Thomas Gleixner \u003ctglx@linutronix.de\u003e\n", "tree_diff": [ { "type": "modify", "old_id": "b96ef14c249a95638d77059bcfaa396be00622a6", "old_mode": 33188, "old_path": "include/linux/ftrace.h", "new_id": "db8a5e7abe4119f13ab5752f93b00f18ca48584f", "new_mode": 33188, "new_path": "include/linux/ftrace.h" }, { "type": "modify", "old_id": "5d6aa92866cd67bc4ecc226af668969d3963bc99", "old_mode": 33188, "old_path": "kernel/trace/Kconfig", "new_id": "892ecc94a82b62f2282523bfbf989c4e6d8f170b", "new_mode": 33188, "new_path": "kernel/trace/Kconfig" }, { "type": "modify", "old_id": "6b54ceb7f16e89cc9e8a43dd4851662df8ef9679", "old_mode": 33188, "old_path": "kernel/trace/Makefile", "new_id": "5508cdb19aea7595c9792f0691046e3203eaf9bc", "new_mode": 33188, "new_path": "kernel/trace/Makefile" }, { "type": "add", "old_id": "0000000000000000000000000000000000000000", "old_mode": 0, "old_path": "/dev/null", "new_id": "7c3ccefcf4c3ef2b8bb59b979adf2086e7510803", "new_mode": 33188, "new_path": "kernel/trace/trace_sched_wakeup.c" } ] }