preemption latency trace v1.0.7 on 2.6.9-rc1-bk12-VP-S0
-------------------------------------------------------
 latency: 305 us, entries: 5 (5)   |   [VP:1 KP:1 SP:1 HP:1 #CPUS:2]
    -----------------
    | task: ksoftirqd/1/5, uid:0 nice:-10 policy:0 rt_prio:0
    -----------------
 => started at: __spin_lock_irqsave+0x2b/0xb0
 => ended at:   _spin_unlock_irqrestore+0x1c/0x40
=======>
00000001 0.000ms (+0.000ms): __spin_lock_irqsave (tulip_mdio_read)
00000001 0.000ms (+0.304ms): __spin_lock_irqsave (<00000000>)
00000001 0.305ms (+0.000ms): _spin_unlock_irqrestore (tulip_mdio_read)
00000001 0.305ms (+0.000ms): sub_preempt_count (_spin_unlock_irqrestore)
00000001 0.306ms (+0.000ms): update_max_trace (check_preempt_timing)