vtaskstarttrace and interpreting traceCon

oahmad2 wrote on Wednesday, May 18, 2011:

Hi
I am trying to put together a trace environment and need help interpreting the results.  Basically I set up a project that calls vtaskstarttrace() function to start then creates a task with a predefined timeout which when it occurs stops the trace and then prints each byte of the trace buffer to the console window in my eclipse environment.  I’ve redirected my console to save to a file, so then I run the ensuring binary file through tracecon to get me the results.  I’m not sure what the results mean.  The results below are the .txt file output….Could some help interpret the results?

Here is the .txt file (after it ran through traceCon):
808858460.000000 12636
808858460.050000 12636
808858460.050000 12636
808858460.100000 12636
808858460.100000 12636
808858460.150000 12636
808858460.150000 12636
926363996.000000 12636
926363996.000000 13148
926363996.050000 13148
926363996.050000 13148
926363996.100000 13148
926363996.100000 13148
926363996.150000 13148
926363996.150000 13148
926363996.200000 13148
926363996.200000 13148
926363996.250000 13148
926363996.250000 13148
926363996.300000 13148
926363996.300000 13148
926363996.350000 13148
926363996.350000 13148
926363996.400000 13148
926363996.400000 13148
926363996.450000 13148
926363996.450000 13148
926363996.500000 13148
926363996.500000 13148
926363996.550000 13148
926363996.550000 13148
926363996.600000 13148
926363996.600000 13148
926363996.650000 13148
926363996.650000 13148
926363996.700000 13148
926363996.700000 12380
892482396.000000 12380
892482396.000000 23559
925981744.000000 23559
925981744.000000 23607
925981744.050000 23607
925981744.050000 23607
925981744.100000 23607
925981744.100000 23607
925981744.150000 23607
925981744.150000 23607
925981744.200000 23607
925981744.200000 23607
925981744.250000 23607
925981744.250000 23607
925981744.300000 23607
925981744.300000 23607
861669173.000000 23607
861669173.000000 12342
861681463.000000 12342
861681463.000000 12342
861681463.050000 12342
861681463.050000 12342
861681463.100000 12342
861681463.100000 12342
861681463.150000 12342
861681463.150000 12342
861681463.200000 12342
861681463.200000 12342
861681463.250000 12342
861681463.250000 12342
1543976759.000000 12342
1543976759.000000 13875
1547122481.000000 13875
1547122481.000000 13875
1547122481.050000 13875
1547122481.050000 13875
1547122481.100000 13875
1547122481.100000 14129
1546663475.000000 14129
1546663475.000000 14129
1546663475.050000 14129
1546663475.050000 14129
1546663475.100000 14129
1546663475.100000 14129
120926771.000000 14129
120926771.000000 13148
926363996.000000 13148
926363996.000000 13148
926363996.050000 13148
926363996.050000 13148
926363996.100000 13148
926363996.100000 12636
808858460.000000 12636
808858460.000000 12636
808858460.050000 12636
808858460.050000 12636
808858460.100000 12636
808858460.100000 12636
909335559.000000 12636
909335559.000000 23600
909335607.000000 23600
909335607.000000 23600
909335607.050000 23600
909335607.050000 23600
909335607.100000 23600
909335607.100000 3376
909335607.150000 3376
909335607.150000 23600
909335607.200000 23600
909335607.200000 23600
842226743.000000 23600
842226743.000000 1845
828125238.000000 1845
828125238.000000 14135
828125238.050000 14135
828125238.050000 14135
828125238.100000 14135
828125238.100000 14135
828125238.150000 14135
828125238.150000 14135
828125238.200000 14135
828125238.200000 14135
828125238.250000 14135
828125238.250000 14135
1546663475.000000 14135
1546663475.000000 14129
1546663475.050000 14129
1546663475.050000 14129
1546663475.100000 14129
1546663475.100000 14129
1546662448.000000 14129
1546662448.000000 12851
808858460.000000 12851
808858460.000000 12636
808858460.050000 12636
808858460.050000 12636
808858460.100000 12636
808858460.100000 12636
892482396.000000 12636
892482396.000000 23559
925981744.000000 23559
925981744.000000 23607
925981744.050000 23607
925981744.050000 23607

edwards3 wrote on Wednesday, May 18, 2011:

I think the first number is a time, and the second number the handle of the task that was running at that time. You can open it in a spread sheet and plot of graph.

But, that is a very old way of creating a trace. Look up the trace macros section on the FreeRTOS web site for a very flexible alternative that lets you trace nearly all kernel events.

oahmad2 wrote on Thursday, May 19, 2011:

I thought so too, until you look at the first column and realize that the numbers do not increase, they go up and down…

Is there a ready-made example that one can utilize to achieve the same results using the newer trace functions in the example files?