preemption latency trace v1.0.7 on 2.6.9-rc4-mm1-VP-U3
-------------------------------------------------------
 latency: 207 us, entries: 5 (5)   |   [VP:1 KP:1 SP:1 HP:1 #CPUS:2]
    -----------------
    | task: X/3305, uid:0 nice:0 policy:0 rt_prio:0
    -----------------
 => started at: _spin_lock_irqsave+0x22/0x80
 => ended at:   _spin_unlock_irq+0x1b/0x40
=======>
00000001 0.000ms (+0.197ms): _spin_lock_irqsave (prepare_set)
00000001 0.197ms (+0.008ms): post_set (set_mtrr)
00000001 0.206ms (+0.002ms): _spin_unlock_irq (set_mtrr)
00000001 0.208ms (+0.000ms): sub_preempt_count (_spin_unlock_irq)
00000001 0.209ms (+0.000ms): update_max_trace (check_preempt_timing)