preemption latency trace v1.0.3 ------------------------------- latency: 129 us, entries: 114 (114) ----------------- | task: ksoftirqd/0/2, uid:0 nice:-10 policy:0 rt_prio:0 ----------------- => started at: netif_receive_skb+0x82/0x280 => ended at: netif_receive_skb+0x1d7/0x280 =======> 00000001 0.000ms (+0.000ms): netif_receive_skb (e100_poll) 00000001 0.001ms (+0.001ms): ip_rcv (netif_receive_skb) 00000001 0.003ms (+0.001ms): ip_route_input (ip_rcv) 00000001 0.003ms (+0.000ms): rt_hash_code (ip_route_input) 00000001 0.004ms (+0.001ms): ip_route_input_slow (ip_rcv) 00000001 0.006ms (+0.001ms): rt_hash_code (ip_route_input_slow) 00000001 0.007ms (+0.001ms): fib_lookup (ip_route_input_slow) 00000002 0.009ms (+0.002ms): fn_hash_lookup (fib_lookup) 00000003 0.011ms (+0.002ms): fib_semantic_match (fn_hash_lookup) 00000001 0.014ms (+0.003ms): fib_validate_source (ip_route_input_slow) 00000001 0.015ms (+0.001ms): fib_lookup (fib_validate_source) 00000002 0.016ms (+0.000ms): fn_hash_lookup (fib_lookup) 00000002 0.017ms (+0.001ms): fn_hash_lookup (fib_lookup) 00000003 0.018ms (+0.001ms): fib_semantic_match (fn_hash_lookup) 00000001 0.020ms (+0.001ms): __fib_res_prefsrc (fib_validate_source) 00000001 0.020ms (+0.000ms): inet_select_addr (__fib_res_prefsrc) 00000001 0.022ms (+0.002ms): fib_rules_tclass (fib_validate_source) 00000001 0.023ms (+0.000ms): fib_rule_put (fib_validate_source) 00000001 0.024ms (+0.001ms): dst_alloc (ip_route_input_slow) 00000001 0.025ms (+0.000ms): kmem_cache_alloc (dst_alloc) 00000001 0.026ms (+0.000ms): cache_alloc_refill (kmem_cache_alloc) 00000001 0.030ms (+0.004ms): rt_intern_hash (ip_route_input_slow) 00000101 0.032ms (+0.001ms): local_bh_enable (rt_intern_hash) 00000001 0.033ms (+0.001ms): fib_rule_put (ip_route_input_slow) 00000001 0.035ms (+0.001ms): ip_local_deliver (ip_rcv) 00000001 0.035ms (+0.000ms): nf_hook_slow (ip_local_deliver) 00000002 0.036ms (+0.000ms): nf_iterate (nf_hook_slow) 00000002 0.037ms (+0.000ms): ipt_hook (nf_iterate) 00000002 0.038ms (+0.000ms): ipt_do_table (ipt_hook) 00000102 0.040ms (+0.002ms): local_bh_enable (ipt_do_table) 00000002 0.041ms (+0.001ms): ip_local_deliver_finish (nf_hook_slow) 00000003 0.043ms (+0.001ms): icmp_rcv (ip_local_deliver_finish) 00000003 0.044ms (+0.001ms): skb_checksum (icmp_rcv) 00000003 0.046ms (+0.002ms): icmp_echo (icmp_rcv) 00000003 0.047ms (+0.000ms): icmp_reply (icmp_echo) 00000003 0.048ms (+0.000ms): ip_options_echo (icmp_reply) 00000104 0.049ms (+0.001ms): icmp_out_count (icmp_reply) 00000104 0.051ms (+0.001ms): ip_route_output_key (icmp_reply) 00000104 0.052ms (+0.000ms): __ip_route_output_key (ip_route_output_key) 00000104 0.052ms (+0.000ms): rt_hash_code (__ip_route_output_key) 00000204 0.053ms (+0.000ms): local_bh_enable (__ip_route_output_key) 00000104 0.054ms (+0.000ms): ip_route_output_slow (ip_route_output_key) 00000104 0.056ms (+0.001ms): ip_dev_find (ip_route_output_slow) 00000104 0.056ms (+0.000ms): fn_hash_lookup (ip_dev_find) 00000105 0.057ms (+0.000ms): fib_semantic_match (fn_hash_lookup) 00000104 0.058ms (+0.001ms): fib_lookup (ip_route_output_slow) 00000105 0.059ms (+0.000ms): fn_hash_lookup (fib_lookup) 00000105 0.059ms (+0.000ms): fn_hash_lookup (fib_lookup) 00000106 0.060ms (+0.000ms): fib_semantic_match (fn_hash_lookup) 00000104 0.062ms (+0.002ms): dst_alloc (ip_route_output_slow) 00000104 0.062ms (+0.000ms): kmem_cache_alloc (dst_alloc) 00000104 0.065ms (+0.002ms): rt_set_nexthop (ip_route_output_slow) 00000104 0.066ms (+0.000ms): memcpy (rt_set_nexthop) 00000104 0.067ms (+0.001ms): fib_rules_tclass (rt_set_nexthop) 00000104 0.067ms (+0.000ms): set_class_tag (rt_set_nexthop) 00000104 0.068ms (+0.000ms): set_class_tag (rt_set_nexthop) 00000104 0.068ms (+0.000ms): rt_hash_code (ip_route_output_slow) 00000104 0.068ms (+0.000ms): rt_intern_hash (ip_route_output_slow) 00000205 0.069ms (+0.000ms): arp_bind_neighbour (rt_intern_hash) 00000205 0.070ms (+0.000ms): neigh_lookup (arp_bind_neighbour) 00000205 0.071ms (+0.001ms): arp_hash (neigh_lookup) 00000305 0.072ms (+0.001ms): local_bh_enable (neigh_lookup) 00000204 0.073ms (+0.000ms): local_bh_enable (rt_intern_hash) 00000104 0.074ms (+0.000ms): fib_rule_put (ip_route_output_slow) 00000104 0.074ms (+0.000ms): xfrm_lookup (ip_route_output_key) 00000104 0.076ms (+0.001ms): icmp_push_reply (icmp_reply) 00000104 0.077ms (+0.000ms): ip_append_data (icmp_push_reply) 00000104 0.082ms (+0.004ms): sock_alloc_send_skb (ip_append_data) 00000104 0.082ms (+0.000ms): sock_alloc_send_pskb (sock_alloc_send_skb) 00000104 0.084ms (+0.001ms): alloc_skb (sock_alloc_send_pskb) 00000104 0.084ms (+0.000ms): kmem_cache_alloc (alloc_skb) 00000104 0.085ms (+0.000ms): __kmalloc (alloc_skb) 00000104 0.086ms (+0.000ms): cache_alloc_refill (__kmalloc) 00000104 0.090ms (+0.004ms): icmp_glue_bits (ip_append_data) 00000104 0.090ms (+0.000ms): skb_copy_and_csum_bits (icmp_glue_bits) 00000104 0.094ms (+0.003ms): ip_push_pending_frames (icmp_reply) 00000104 0.097ms (+0.002ms): __ip_select_ident (ip_push_pending_frames) 00000104 0.097ms (+0.000ms): rt_bind_peer (__ip_select_ident) 00000104 0.098ms (+0.000ms): inet_getpeer (rt_bind_peer) 00000204 0.099ms (+0.001ms): local_bh_enable (inet_getpeer) 00000104 0.100ms (+0.000ms): unlink_from_unused (inet_getpeer) 00000204 0.100ms (+0.000ms): local_bh_enable (inet_getpeer) 00000204 0.101ms (+0.000ms): local_bh_enable (rt_bind_peer) 00000204 0.102ms (+0.000ms): local_bh_enable (__ip_select_ident) 00000104 0.103ms (+0.001ms): nf_hook_slow (ip_push_pending_frames) 00000105 0.104ms (+0.000ms): nf_iterate (nf_hook_slow) 00000105 0.104ms (+0.000ms): ipt_local_out_hook (nf_iterate) 00000105 0.104ms (+0.000ms): ipt_do_table (ipt_local_out_hook) 00000205 0.106ms (+0.001ms): local_bh_enable (ipt_do_table) 00000105 0.106ms (+0.000ms): dst_output (nf_hook_slow) 00000105 0.107ms (+0.000ms): ip_output (dst_output) 00000105 0.108ms (+0.000ms): ip_finish_output (dst_output) 00000105 0.109ms (+0.000ms): neigh_resolve_output (ip_finish_output) 00000206 0.110ms (+0.001ms): neigh_hh_init (neigh_resolve_output) 00000206 0.112ms (+0.001ms): eth_header (neigh_resolve_output) 00000205 0.114ms (+0.001ms): local_bh_enable (neigh_resolve_output) 00000105 0.114ms (+0.000ms): dev_queue_xmit (neigh_resolve_output) 00000207 0.115ms (+0.000ms): pfifo_fast_enqueue (dev_queue_xmit) 00000207 0.116ms (+0.000ms): qdisc_restart (dev_queue_xmit) 00000207 0.117ms (+0.000ms): pfifo_fast_dequeue (qdisc_restart) 00000207 0.118ms (+0.001ms): e100_xmit_frame (qdisc_restart) 00000208 0.119ms (+0.000ms): e100_xmit_prepare (e100_xmit_frame) 00000207 0.121ms (+0.002ms): qdisc_restart (dev_queue_xmit) 00000207 0.121ms (+0.000ms): pfifo_fast_dequeue (qdisc_restart) 00000206 0.122ms (+0.000ms): local_bh_enable (dev_queue_xmit) 00000104 0.123ms (+0.001ms): icmp_xmit_unlock (icmp_reply) 00000103 0.123ms (+0.000ms): local_bh_enable (icmp_reply) 00000003 0.124ms (+0.000ms): __kfree_skb (icmp_rcv) 00000003 0.125ms (+0.001ms): kfree_skbmem (__kfree_skb) 00000003 0.126ms (+0.000ms): skb_release_data (kfree_skbmem) 00000003 0.126ms (+0.000ms): kfree (kfree_skbmem) 00000003 0.127ms (+0.000ms): kmem_cache_free (kfree_skbmem) 00000001 0.130ms (+0.002ms): sub_preempt_count (netif_receive_skb) 00000001 0.130ms (+0.000ms): update_max_trace (check_preempt_timing)