<div dir="ltr"><div class="gmail_quote"><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">The clock definitions for the timestamps are not right. I loaded the <br>
trace with Trace Compass and it shows always the same timestamp. For <br>
example in<br>
<br>
102-tracing-wget-critical-path/wget-first-call<br>
<br>
I get this:<br>
<br>
babeltrace . | grep sched | head<br>
[17:50:44.093074246] (+?.?????????) arnaud-desktop sched_wakeup: { <br>
cpu_id = 3 }, { comm = "lttng-consumerd", tid = 6854, prio = 20, <br>
target_cpu = 0 }<br>
[17:50:44.093076317] (+0.000002071) arnaud-desktop sched_switch: { <br>
cpu_id = 0 }, { prev_comm = "swapper/0", prev_tid = 0, prev_prio = 20, <br>
prev_state = 0, next_comm = "lttng-consumerd", next_tid = 6854, <br>
next_prio = 20 }<br>
[17:50:44.093115428] (+0.000017727) arnaud-desktop sched_wakeup: { <br>
cpu_id = 0 }, { comm = "code", tid = 3906, prio = 20, target_cpu = 2 }<br>
[17:50:44.093116515] (+0.000001087) arnaud-desktop sched_switch: { <br>
cpu_id = 2 }, { prev_comm = "swapper/2", prev_tid = 0, prev_prio = 20, <br>
prev_state = 0, next_comm = "code", next_tid = 3906, next_prio = 20 }<br>
[17:50:44.093167902] (+0.000002249) arnaud-desktop sched_switch: { <br>
cpu_id = 0 }, { prev_comm = "lttng-consumerd", prev_tid = 6854, <br>
prev_prio = 20, prev_state = 2, next_comm = "swapper/0", next_tid = 0, <br>
next_prio = 20 }<br>
[17:50:44.093256580] (+0.000003128) arnaud-desktop sched_wakeup: { <br>
cpu_id = 2 }, { comm = "code", tid = 3915, prio = 20, target_cpu = 0 }<br>
[17:50:44.093257484] (+0.000000267) arnaud-desktop sched_switch: { <br>
cpu_id = 0 }, { prev_comm = "swapper/0", prev_tid = 0, prev_prio = 20, <br>
prev_state = 0, next_comm = "code", next_tid = 3915, next_prio = 20 }<br>
[17:50:44.093271009] (+0.000001250) arnaud-desktop sched_switch: { <br>
cpu_id = 0 }, { prev_comm = "code", prev_tid = 3915, prev_prio = 20, <br>
prev_state = 1, next_comm = "swapper/0", next_tid = 0, next_prio = 20 }<br>
[17:50:44.093271610] (+0.000000601) arnaud-desktop sched_wakeup: { <br>
cpu_id = 2 }, { comm = "code", tid = 3916, prio = 20, target_cpu = 2 }<br>
[17:50:44.093272936] (+0.000000887) arnaud-desktop sched_switch: { <br>
cpu_id = 2 }, { prev_comm = "code", prev_tid = 3906, prev_prio = 20, <br>
prev_state = 0, next_comm = "code", next_tid = 3916, next_prio = 20 }<br>
<br>
In our trace output I get this:<br>
<br>
babeltrace . | head<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 986422, events = ( <br>
"RTEMS_RECORD_THREAD_STACK_CURRENT" : container = 209 ), data = 7392 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 986422, events = ( <br>
"RTEMS_RECORD_THREAD_SWITCH_IN" : container = 215 ), data = 167837739 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1006753, events = ( <br>
"RTEMS_RECORD_THREAD_SWITCH_OUT" : container = 216 ), data = 167837739 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1006753, events = ( <br>
"RTEMS_RECORD_THREAD_STACK_CURRENT" : container = 209 ), data = 5856 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1006753, events = ( <br>
"RTEMS_RECORD_THREAD_SWITCH_IN" : container = 215 ), data = 151060500 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1044799, events = ( <br>
"RTEMS_RECORD_THREAD_SWITCH_OUT" : container = 216 ), data = 151060500 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1044799, events = ( <br>
"RTEMS_RECORD_THREAD_STACK_CURRENT" : container = 209 ), data = 7392 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1044799, events = ( <br>
"RTEMS_RECORD_THREAD_SWITCH_IN" : container = 215 ), data = 167837739 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1064443, events = ( <br>
"RTEMS_RECORD_THREAD_SWITCH_OUT" : container = 216 ), data = 167837739 }<br>
0 RTEMS_RECORDING_EVENT: { cpu_id = 17 }, { ns = 1064443, events = ( <br>
"RTEMS_RECORD_THREAD_STACK_CURRENT" : container = 209 ), data = 5856 }<br></blockquote><div><br></div><div>I included both timestamps values in the binary stream file. Only the timestamp value is not shown in our output. The timestamp values were stored in nanoseconds.</div></div><br clear="all"><div><br></div>-- <br><div dir="ltr" class="gmail_signature"><div dir="ltr"><div><div dir="ltr"><font size="4"><b>Ravindra Kumar Meena</b></font>,<div><span style="color:rgb(102,102,102)">B. Tech. Computer Science and Engineering,<br></span></div><div><span style="color:rgb(102,102,102)"><a href="https://www.iitism.ac.in/" style="color:rgb(17,85,204)" target="_blank">Indian Institute of Technology (Indian School of Mines)</a>, Dhanbad</span></div></div></div></div></div></div>