I-pipe frozen back-tracing service on 2.6.25/ipipe-2.0-08 ------------------------------------------------------------ CPU: 0, Freeze: 231398316423 cycles, Trace Points: 100 (+10) Calibrated minimum trace-point overhead: 0.125 us +----- Hard IRQs ('|': locked) |+---- ||+--- |||+-- Xenomai ||||+- Linux ('*': domain stalled, '+': current, '#': current+stalled) ||||| +---------- Delay flag ('+': > 1 us, '!': > 10 us) ||||| | +- NMI noise ('N') ||||| | | Type User Val. Time Delay Function (Parent) :| # [ 0] -- -1 -302 0.506 xnpod_schedule+0xe2 (xnintr_clock_handler+0x108) :| # func -301 0.330 __switch_to+0xe (xnpod_schedule+0xa24) :| # [ 4836] samplin 99 -301 0.325 xnpod_schedule+0xb2e (xnpod_suspend_thread+0x18a) :| # func -301 0.150 xntimer_get_overruns+0xe (xnpod_wait_thread_period+0xe5) :| # func -300 0.130 __ipipe_restore_pipeline_head+0x9 (xnpod_wait_thread_period+0x145) :| + end 0x80000000 -300 0.205 __ipipe_restore_pipeline_head+0x94 (xnpod_wait_thread_period+0x145) :| + begin 0x80000001 -300 0.165 __ipipe_dispatch_event+0x109 (__ipipe_syscall_root+0x8d) :| + end 0x80000001 -300 0.626 __ipipe_dispatch_event+0x1db (__ipipe_syscall_root+0x8d) : + func -299 0.135 __ipipe_syscall_root+0xa (system_call+0x29) : + func -299 0.120 __ipipe_dispatch_event+0xe (__ipipe_syscall_root+0x8d) :| + begin 0x80000001 -299 0.170 __ipipe_dispatch_event+0x2c (__ipipe_syscall_root+0x8d) :| + end 0x80000001 -299 0.150 __ipipe_dispatch_event+0xd6 (__ipipe_syscall_root+0x8d) : + func -299 0.150 hisyscall_event+0xe (__ipipe_dispatch_event+0xe6) : + func -299 0.110 __rt_task_wait_period+0xd (hisyscall_event+0x13b) : + func -298 0.135 rt_task_wait_period+0x8 (__rt_task_wait_period+0x34) : + func -298 0.145 xnpod_wait_thread_period+0xe (rt_task_wait_period+0x42) :| + begin 0x80000000 -298 0.220 xnpod_wait_thread_period+0x35 (rt_task_wait_period+0x42) :| # func -298 0.160 xnpod_suspend_thread+0xe (xnpod_wait_thread_period+0xc4) :| # func -298 0.140 xnpod_schedule+0xe (xnpod_suspend_thread+0x18a) :| # [ 4836] samplin 99 -298 0.481 xnpod_schedule+0xe2 (xnpod_suspend_thread+0x18a) :| # func -297 0.260 __switch_to+0xe (xnpod_schedule+0xa24) :| # [ 0] -- -1 -297 0.400 xnpod_schedule+0xb2e (xnintr_clock_handler+0x108) :| +func -297 0.135 __ipipe_walk_pipeline+0xe (__ipipe_handle_irq+0x12b) :| #end 0xffffff16 -296 0.140 ipipe_ipi3+0x3d (default_idle+0x68) :| #func -296 0.130 __ipipe_unstall_iret_root+0x9 (restore_nocheck_notrace+0x0) :| +end 0x8000000d -296 0.260 __ipipe_unstall_iret_root+0x6f (restore_nocheck_notrace+0x0) : +func -296 0.170 ipipe_check_context+0x9 (default_idle+0x72) : #func -296 0.110 ktime_get+0xc (default_idle+0x8b) : #func -296 0.125 ktime_get_ts+0xa (ktime_get+0x16) : #func -295 0.130 getnstimeofday+0xe (ktime_get_ts+0x19) : #func -295 0.130 jiffies_read+0x8 (getnstimeofday+0x34) : #func -295 0.125 set_normalized_timespec+0x8 (ktime_get_ts+0x40) : #func -295 0.110 sched_clock_idle_wakeup_event+0xe (default_idle+0x94) : #func -295 0.130 native_sched_clock+0xe (sched_clock_idle_wakeup_event+0x2b) : #func -295 0.170 native_read_tsc+0x8 (native_sched_clock+0x4f) : #func -295 0.170 ipipe_check_context+0x9 (native_sched_clock+0x5d) : #func -294 0.160 __ipipe_restore_root+0x8 (native_sched_clock+0xc5) : #func -294 0.115 _spin_lock+0x9 (sched_clock_idle_wakeup_event+0x48) : #func -294 0.140 ipipe_check_context+0x9 (_spin_lock+0x15) : #func -294 0.110 _spin_unlock+0x8 (sched_clock_idle_wakeup_event+0x6d) : #func -294 0.120 ipipe_check_context+0x9 (_spin_unlock+0x14) : #func -294 0.120 __ipipe_unstall_root+0x8 (default_idle+0x99) :| #begin 0x80000000 -294 0.135 __ipipe_unstall_root+0x1a (default_idle+0x99) :| +end 0x80000000 -294 0.150 __ipipe_unstall_root+0x54 (default_idle+0x99) : +func -293 0.115 rcu_pending+0x9 (cpu_idle+0x38) : +func -293 0.125 __rcu_pending+0x8 (rcu_pending+0x21) : +func -293 0.120 __rcu_pending+0x8 (rcu_pending+0x3b) : +func -293 0.120 ipipe_suspend_domain+0xe (cpu_idle+0xb7) :| +begin 0x80000001 -293 0.195 ipipe_suspend_domain+0x2a (cpu_idle+0xb7) :| +end 0x80000001 -293 0.150 ipipe_suspend_domain+0xdf (cpu_idle+0xb7) : +func -293 0.125 default_idle+0xa (cpu_idle+0xb9) :| +begin 0x80000000 -292 0.150 default_idle+0x46 (cpu_idle+0xb9) :| +func -292 0.115 ktime_get+0xc (default_idle+0x58) :| +func -292 0.115 ktime_get_ts+0xa (ktime_get+0x16) :| +func -292 0.115 getnstimeofday+0xe (ktime_get_ts+0x19) :| +func -292 0.135 jiffies_read+0x8 (getnstimeofday+0x34) :| +func -292 0.115 set_normalized_timespec+0x8 (ktime_get_ts+0x40) :| +end 0x8000000e -292! 282.688 default_idle+0x66 (cpu_idle+0xb9) :| +begin 0xffffff16 -9 0.150 ipipe_ipi3+0x2e (default_idle+0x68) :| +func -9 0.115 __ipipe_handle_irq+0xe (ipipe_ipi3+0x33) :| +func -9 0.135 __ipipe_ack_apic+0x8 (__ipipe_handle_irq+0x76) :| +func -9 0.125 __ipipe_dispatch_wired+0xe (__ipipe_handle_irq+0x7f) :| # func -9 0.175 xnintr_clock_handler+0xe (__ipipe_dispatch_wired+0xe0) :| # func -8 0.185 xntimer_tick_aperiodic+0xe (xnintr_clock_handler+0x65) :| # func -8 0.115 xnthread_periodic_handler+0x8 (xntimer_tick_aperiodic+0x96) :| # func -8 0.135 xnpod_resume_thread+0xe (xnthread_periodic_handler+0x28) :| # [ 4836] samplin 99 -8 0.235 xnpod_resume_thread+0x99 (xnthread_periodic_handler+0x28) :| # func -8 0.250 xntimer_next_local_shot+0xe (xntimer_tick_aperiodic+0x1af) :| # func -7 0.150 xnpod_schedule+0xe (xnintr_clock_handler+0x108) :| # [ 0] -- -1 -7 0.716 xnpod_schedule+0xe2 (xnintr_clock_handler+0x108) :| # func -7 0.330 __switch_to+0xe (xnpod_schedule+0xa24) :| # [ 4836] samplin 99 -6 0.325 xnpod_schedule+0xb2e (xnpod_suspend_thread+0x18a) :| # func -6 0.260 xntimer_get_overruns+0xe (xnpod_wait_thread_period+0xe5) :| # func -6 0.130 __ipipe_restore_pipeline_head+0x9 (xnpod_wait_thread_period+0x145) :| + end 0x80000000 -6 0.225 __ipipe_restore_pipeline_head+0x94 (xnpod_wait_thread_period+0x145) :| + begin 0x80000001 -5 0.165 __ipipe_dispatch_event+0x109 (__ipipe_syscall_root+0x8d) :| + end 0x80000001 -5 0.771 __ipipe_dispatch_event+0x1db (__ipipe_syscall_root+0x8d) : + func -4 0.135 __ipipe_syscall_root+0xa (system_call+0x29) : + func -4 0.125 __ipipe_dispatch_event+0xe (__ipipe_syscall_root+0x8d) :| + begin 0x80000001 -4 0.170 __ipipe_dispatch_event+0x2c (__ipipe_syscall_root+0x8d) :| + end 0x80000001 -4 0.390 __ipipe_dispatch_event+0xd6 (__ipipe_syscall_root+0x8d) :| + begin 0xffffff16 -4 0.160 ipipe_ipi3+0x2e (__ipipe_dispatch_event+0xda) :| + func -3 0.155 __ipipe_handle_irq+0xe (ipipe_ipi3+0x33) :| + func -3 0.135 __ipipe_ack_apic+0x8 (__ipipe_handle_irq+0x76) :| + func -3 0.135 __ipipe_dispatch_wired+0xe (__ipipe_handle_irq+0x7f) :| # func -3 0.165 xnintr_clock_handler+0xe (__ipipe_dispatch_wired+0xe0) :| # func -3 0.155 xntimer_tick_aperiodic+0xe (xnintr_clock_handler+0x65) :| # func -3 0.215 xnthread_periodic_handler+0x8 (xntimer_tick_aperiodic+0x96) :| # func -2 0.395 xntimer_next_local_shot+0xe (xntimer_tick_aperiodic+0x1af) :| + func -2 0.170 __ipipe_walk_pipeline+0xe (__ipipe_handle_irq+0x12b) :| + end 0xffffff16 -2 0.275 ipipe_ipi3+0x3d (__ipipe_dispatch_event+0xda) : + func -2 0.215 hisyscall_event+0xe (__ipipe_dispatch_event+0xe6) : + func -1 0.160 xnshadow_sys_trace+0xb (hisyscall_event+0x13b) : + func -1 0.125 ipipe_trace_frozen_reset+0xa (xnshadow_sys_trace+0x62) : + func -1 0.125 __ipipe_global_path_lock+0xb (ipipe_trace_frozen_reset+0xf) : + func -1 0.180 __ipipe_spin_lock_irqsave+0xa (__ipipe_global_path_lock+0x15) :| + begin 0x80000001 -1 0.896 __ipipe_spin_lock_irqsave+0x21 (__ipipe_global_path_lock+0x15) :| # func 0 0.185 __ipipe_spin_unlock_irqcomplete+0xa (__ipipe_global_path_unlock+0x61) :| + end 0x80000001 0 0.175 __ipipe_spin_unlock_irqcomplete+0x49 (__ipipe_global_path_unlock+0x61) < + freeze 0x00030627 0 0.160 xnshadow_sys_trace+0x6b (hisyscall_event+0x13b) | + begin 0x80000001 0 0.175 __ipipe_dispatch_event+0x109 (__ipipe_syscall_root+0x8d) | + end 0x80000001 0 0.501 __ipipe_dispatch_event+0x1db (__ipipe_syscall_root+0x8d) + func 0 0.130 __ipipe_syscall_root+0xa (system_call+0x29) + func 0 0.120 __ipipe_dispatch_event+0xe (__ipipe_syscall_root+0x8d) | + begin 0x80000001 1 0.155 __ipipe_dispatch_event+0x2c (__ipipe_syscall_root+0x8d) | + end 0x80000001 1 0.175 __ipipe_dispatch_event+0xd6 (__ipipe_syscall_root+0x8d) + func 1 0.135 hisyscall_event+0xe (__ipipe_dispatch_event+0xe6) + func 1 0.110 __rt_task_wait_period+0xd (hisyscall_event+0x13b) + func 1 0.145 rt_task_wait_period+0x8 (__rt_task_wait_period+0x34) + func 1 0.000N xnpod_wait_thread_period+0xe (rt_task_wait_period+0x42)