preemption latency trace v1.0.7 on 2.6.9-rc2-mm1-VP-S1
-------------------------------------------------------
 latency: 300 us, entries: 21 (21)   |   [VP:1 KP:1 SP:1 HP:1 #CPUS:2]
    -----------------
    | task: kswapd0/46, uid:0 nice:10 policy:0 rt_prio:0
    -----------------
 => started at: journal_try_to_free_buffers+0x6a/0x190
 => ended at:   journal_try_to_free_buffers+0xca/0x190
=======>
00000001 0.000ms (+0.000ms): journal_try_to_free_buffers (ext3_releasepage)
00000001 0.000ms (+0.000ms): __journal_try_to_free_buffer (journal_try_to_free_buffers)
00000001 0.000ms (+0.294ms): _spin_lock (__journal_try_to_free_buffer)
00000002 0.295ms (+0.000ms): __journal_unfile_buffer (__journal_try_to_free_buffer)
00000002 0.295ms (+0.000ms): journal_remove_journal_head (__journal_try_to_free_buffer)
00000003 0.296ms (+0.000ms): __journal_remove_journal_head (journal_remove_journal_head)
00000002 0.296ms (+0.000ms): __brelse (__journal_try_to_free_buffer)
00000002 0.296ms (+0.000ms): _spin_unlock (journal_try_to_free_buffers)
00000001 0.297ms (+0.000ms): journal_put_journal_head (journal_try_to_free_buffers)
00000002 0.297ms (+0.000ms): __journal_remove_journal_head (journal_put_journal_head)
00000002 0.297ms (+0.000ms): __brelse (__journal_remove_journal_head)
00000002 0.297ms (+0.000ms): journal_free_journal_head (journal_put_journal_head)
00000002 0.297ms (+0.000ms): kmem_cache_free (journal_free_journal_head)
00000002 0.298ms (+0.000ms): cache_flusharray (kmem_cache_free)
00000002 0.298ms (+0.001ms): _spin_lock (cache_flusharray)
00000003 0.299ms (+0.000ms): _spin_unlock (cache_flusharray)
00000002 0.300ms (+0.000ms): memmove (cache_flusharray)
00000002 0.300ms (+0.000ms): memcpy (memmove)
00000002 0.300ms (+0.000ms): __brelse (journal_put_journal_head)
00000001 0.301ms (+0.000ms): sub_preempt_count (journal_try_to_free_buffers)
00000001 0.301ms (+0.000ms): update_max_trace (check_preempt_timing)