|
Ftracing one single wget operation, and hooking on the nf_*
API: #!/bin/bash echo 0 >/debug/tracing/tracing_enabled echo "nf_*" > /debug/tracing/set_ftrace_filter echo function >/debug/tracing/current_tracer echo 1 >/debug/tracing/tracing_enabled wget http://www.ebay.com/ cat /debug/tracing/trace | tee ftrace/nf_ftrace$$.log echo 0 >/debug/tracing/tracing_enabled wget-7942 [000] 7229.505154: nf_hook_slow <-__ip_local_out wget-7942 [000] 7229.505157: nf_iterate <-nf_hook_slow wget-7942 [000] 7229.505159: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [000] 7229.505162: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [000] 7229.505163: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [000] 7229.505165: nf_ct_invert_tuple <-nf_conntrack_in wget-7942 [000] 7229.505166: nf_conntrack_alloc <-nf_conntrack_in wget-7942 [000] 7229.505170: nf_ct_find_expectation <-nf_conntrack_in wget-7942 [000] 7229.505173: nf_nat_local_fn <-nf_iterate wget-7942 [000] 7229.505173: nf_nat_fn <-nf_nat_local_fn wget-7942 [000] 7229.505174: nf_nat_rule_find <-nf_nat_fn wget-7942 [000] 7229.505177: nf_nat_setup_info <-alloc_null_binding wget-7942 [000] 7229.505177: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [000] 7229.505178: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [000] 7229.505179: nf_nat_used_tuple <-nf_nat_setup_info wget-7942 [000] 7229.505179: nf_ct_invert_tuplepr <-nf_nat_used_tuple wget-7942 [000] 7229.505180: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [000] 7229.505180: nf_conntrack_tuple_taken <-nf_nat_used_tuple wget-7942 [000] 7229.505182: nf_nat_packet <-nf_nat_fn wget-7942 [000] 7229.505185: nf_hook_slow <-ip_output wget-7942 [000] 7229.505185: nf_iterate <-nf_hook_slow wget-7942 [000] 7229.505185: nf_nat_out <-nf_iterate wget-7942 [000] 7229.505186: nf_nat_fn <-nf_nat_out wget-7942 [000] 7229.505186: nf_nat_rule_find <-nf_nat_fn wget-7942 [000] 7229.505187: nf_nat_setup_info <-alloc_null_binding wget-7942 [000] 7229.505188: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [000] 7229.505188: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [000] 7229.505189: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [000] 7229.505189: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [000] 7229.505190: nf_nat_used_tuple <-nf_nat_setup_info wget-7942 [000] 7229.505190: nf_ct_invert_tuplepr <-nf_nat_used_tuple wget-7942 [000] 7229.505191: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [000] 7229.505191: nf_conntrack_tuple_taken <-nf_nat_used_tuple wget-7942 [000] 7229.505192: nf_nat_packet <-nf_nat_fn wget-7942 [000] 7229.505195: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7229.518372: nf_hook_slow <-__ip_local_out wget-7942 [001] 7229.518373: nf_iterate <-nf_hook_slow wget-7942 [001] 7229.518373: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7229.518374: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7229.518374: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7229.518375: nf_ct_invert_tuple <-nf_conntrack_in wget-7942 [001] 7229.518376: nf_conntrack_alloc <-nf_conntrack_in wget-7942 [001] 7229.518379: nf_ct_find_expectation <-nf_conntrack_in wget-7942 [001] 7229.518381: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7229.518381: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7229.518382: nf_nat_rule_find <-nf_nat_fn wget-7942 [001] 7229.518383: nf_nat_setup_info <-alloc_null_binding wget-7942 [001] 7229.518384: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [001] 7229.518384: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.518385: nf_nat_used_tuple <-nf_nat_setup_info wget-7942 [001] 7229.518385: nf_ct_invert_tuplepr <-nf_nat_used_tuple wget-7942 [001] 7229.518385: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.518386: nf_conntrack_tuple_taken <-nf_nat_used_tuple wget-7942 [001] 7229.518387: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7229.518388: nf_hook_slow <-ip_output wget-7942 [001] 7229.518388: nf_iterate <-nf_hook_slow wget-7942 [001] 7229.518389: nf_nat_out <-nf_iterate wget-7942 [001] 7229.518389: nf_nat_fn <-nf_nat_out wget-7942 [001] 7229.518389: nf_nat_rule_find <-nf_nat_fn wget-7942 [001] 7229.518390: nf_nat_setup_info <-alloc_null_binding wget-7942 [001] 7229.518390: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [001] 7229.518391: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.518391: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [001] 7229.518391: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.518392: nf_nat_used_tuple <-nf_nat_setup_info wget-7942 [001] 7229.518392: nf_ct_invert_tuplepr <-nf_nat_used_tuple wget-7942 [001] 7229.518392: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.518393: nf_conntrack_tuple_taken <-nf_nat_used_tuple wget-7942 [001] 7229.518393: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7229.518396: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7229.950832: nf_hook_slow <-__ip_local_out wget-7942 [001] 7229.950833: nf_iterate <-nf_hook_slow wget-7942 [001] 7229.950834: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7229.950835: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7229.950836: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7229.950837: nf_ct_invert_tuple <-nf_conntrack_in wget-7942 [001] 7229.950838: nf_conntrack_alloc <-nf_conntrack_in wget-7942 [001] 7229.950843: nf_ct_find_expectation <-nf_conntrack_in wget-7942 [001] 7229.950846: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7229.950847: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7229.950848: nf_nat_rule_find <-nf_nat_fn wget-7942 [001] 7229.950849: nf_nat_setup_info <-alloc_null_binding wget-7942 [001] 7229.950850: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [001] 7229.950850: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.950851: nf_nat_used_tuple <-nf_nat_setup_info wget-7942 [001] 7229.950852: nf_ct_invert_tuplepr <-nf_nat_used_tuple wget-7942 [001] 7229.950852: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.950853: nf_conntrack_tuple_taken <-nf_nat_used_tuple wget-7942 [001] 7229.950854: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7229.950856: nf_hook_slow <-ip_output wget-7942 [001] 7229.950856: nf_iterate <-nf_hook_slow wget-7942 [001] 7229.950857: nf_nat_out <-nf_iterate wget-7942 [001] 7229.950857: nf_nat_fn <-nf_nat_out wget-7942 [001] 7229.950857: nf_nat_rule_find <-nf_nat_fn wget-7942 [001] 7229.950858: nf_nat_setup_info <-alloc_null_binding wget-7942 [001] 7229.950859: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [001] 7229.950859: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.950860: nf_ct_invert_tuplepr <-nf_nat_setup_info wget-7942 [001] 7229.950860: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.950861: nf_nat_used_tuple <-nf_nat_setup_info wget-7942 [001] 7229.950861: nf_ct_invert_tuplepr <-nf_nat_used_tuple wget-7942 [001] 7229.950861: nf_ct_invert_tuple <-nf_ct_invert_tuplepr wget-7942 [001] 7229.950862: nf_conntrack_tuple_taken <-nf_nat_used_tuple wget-7942 [001] 7229.950863: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7229.950865: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7230.178021: nf_hook_slow <-__ip_local_out wget-7942 [001] 7230.178022: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.178022: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7230.178023: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7230.178024: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7230.178026: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7230.178027: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7230.178027: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.178029: nf_hook_slow <-ip_output wget-7942 [001] 7230.178029: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.178030: nf_nat_out <-nf_iterate wget-7942 [001] 7230.178030: nf_nat_fn <-nf_nat_out wget-7942 [001] 7230.178030: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.178031: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7230.709088: nf_hook_slow <-ip_rcv wget-7942 [001] 7230.709089: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.709090: nf_conntrack_in <-ipv4_conntrack_in wget-7942 [001] 7230.709091: nf_ip_checksum <-tcp_error wget-7942 [001] 7230.709091: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7230.709092: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7230.709094: nf_nat_in <-nf_iterate wget-7942 [001] 7230.709095: nf_nat_fn <-nf_nat_in wget-7942 [001] 7230.709095: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.709096: nf_hook_slow <-ip_local_deliver wget-7942 [001] 7230.709097: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.709098: nf_nat_fn <-nf_iterate wget-7942 [001] 7230.709098: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.709099: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7230.709104: nf_hook_slow <-__ip_local_out wget-7942 [001] 7230.709105: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.709105: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7230.709106: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7230.709107: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7230.709108: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7230.709109: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7230.709109: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.709110: nf_hook_slow <-ip_output wget-7942 [001] 7230.709111: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.709111: nf_nat_out <-nf_iterate wget-7942 [001] 7230.709111: nf_nat_fn <-nf_nat_out wget-7942 [001] 7230.709112: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.709112: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7230.713190: nf_hook_slow <-__ip_local_out wget-7942 [001] 7230.713191: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.713192: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7230.713193: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7230.713193: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7230.713196: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7230.713196: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7230.713196: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.713198: nf_hook_slow <-ip_output wget-7942 [001] 7230.713198: nf_iterate <-nf_hook_slow wget-7942 [001] 7230.713198: nf_nat_out <-nf_iterate wget-7942 [001] 7230.713199: nf_nat_fn <-nf_nat_out wget-7942 [001] 7230.713199: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7230.713200: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7231.222469: nf_hook_slow <-__ip_local_out wget-7942 [001] 7231.222470: nf_iterate <-nf_hook_slow wget-7942 [001] 7231.222471: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7231.222471: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7231.222472: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7231.222474: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7231.222474: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7231.222475: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7231.222476: nf_hook_slow <-ip_output wget-7942 [001] 7231.222476: nf_iterate <-nf_hook_slow wget-7942 [001] 7231.222477: nf_nat_out <-nf_iterate wget-7942 [001] 7231.222477: nf_nat_fn <-nf_nat_out wget-7942 [001] 7231.222478: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7231.222478: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7231.225829: nf_hook_slow <-__ip_local_out wget-7942 [001] 7231.225829: nf_iterate <-nf_hook_slow wget-7942 [001] 7231.225830: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7231.225831: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7231.225831: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7231.225833: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7231.225834: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7231.225834: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7231.225835: nf_hook_slow <-ip_output wget-7942 [001] 7231.225836: nf_iterate <-nf_hook_slow wget-7942 [001] 7231.225836: nf_nat_out <-nf_iterate wget-7942 [001] 7231.225836: nf_nat_fn <-nf_nat_out wget-7942 [001] 7231.225837: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7231.225837: nf_ct_deliver_cached_events <-ipv4_confirm wget-7942 [001] 7231.226451: nf_hook_slow <-__ip_local_out wget-7942 [001] 7231.226452: nf_iterate <-nf_hook_slow wget-7942 [001] 7231.226452: nf_conntrack_in <-ipv4_conntrack_local wget-7942 [001] 7231.226453: nf_ct_get_tuple <-nf_conntrack_in wget-7942 [001] 7231.226454: nf_conntrack_find_get <-nf_conntrack_in wget-7942 [001] 7231.226457: nf_nat_local_fn <-nf_iterate wget-7942 [001] 7231.226457: nf_nat_fn <-nf_nat_local_fn wget-7942 [001] 7231.226458: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7231.226459: nf_hook_slow <-ip_output wget-7942 [001] 7231.226459: nf_iterate <-nf_hook_slow wget-7942 [001] 7231.226460: nf_nat_out <-nf_iterate wget-7942 [001] 7231.226460: nf_nat_fn <-nf_nat_out wget-7942 [001] 7231.226461: nf_nat_packet <-nf_nat_fn wget-7942 [001] 7231.226461: nf_ct_deliver_cached_events <-ipv4_confirm |
