output

arrakis:edd> doas btrace trace.bt 44773
tracing pid 44773
[1671642769235478231 +1671642769235478231]		 tid=313009	 >>>20
[1671642769235482014 +3783]		 tid=313009	 >>>46
[1671642769235516315 +34301]		 tid=313009	 >>>86
[1671642769235464974 +1671642769235464974]		 tid=313009	 <<<4
[1671642769235478799 +13825]		 tid=313009	 <<<20
[1671642769235509958 +31159]		 tid=313009	 <<<46
[1671642769235539757 +23442]		 tid=313009	 >>>119
[1671642769235541846 +2089]		 tid=313009	 >>>103
[1671642769235544112 +2266]		 tid=313009	 >>>6
[1671642769235546097 +1985]		 tid=313009	 >>>6
[1671642769235567050 +20953]		 tid=313009	 >>>11
[1671642769235538489 +28531]		 tid=313009	 <<<86
[1671642769235541017 +2528]		 tid=313009	 <<<119
[1671642769235542646 +1629]		 tid=313009	 <<<103
[1671642769235545278 +2632]		 tid=313009	 <<<6
[1671642769235565207 +19929]		 tid=313009	 <<<6
[1671642769236313960 +746910]		 tid=313009	 >>>46
[1671642769236315571 +1611]		 tid=313009	 >>>119
[1671642769236316913 +1342]		 tid=313009	 >>>103
[1671642769236311057 +745850]		 tid=313009	 <<<11
[1671642769236314683 +3626]		 tid=313009	 <<<46
[1671642769236316100 +1417]		 tid=313009	 <<<119
[1671642769236318092 +1992]		 tid=313009	 <<<103

script

BEGIN { printf("tracing pid %s\n", $1); $last = nsecs; }

tracepoint:raw_syscalls:sys_enter / pid==$1 / {
    printf("[%s +%s]\t\t tid=%s\t >>>%s\n", nsecs, nsecs - $last, tid, arg0);
    $last = nsecs;
}

tracepoint:raw_syscalls:sys_exit / pid==$1 / {
    printf("[%s +%s]\t\t tid=%s\t <<<%s\n", nsecs, nsecs - $last, tid, arg0);
    $last = nsecs;
}