[ltt-dev] CONFIG_HAVE_TRACE_CLOCK_GENERIC timestamp problem on PXA255

[Steve Langstaff]

> From: Mathieu Desnoyers [mailto:compudj at krystal.dyndns.org]

> * Steve Langstaff (steve.langstaff at pebblebay.com) wrote:
> > I am having trouble with the timestamps that are logged in the traces
> - they
> > don't seem to 'tick' at the right rate.

> Hrm, this should at least follow the correct tick rate.

> A few things to check :
> 
> - if your system is in NO_HZ
> - your HZ value
> - if the timer handler trace_clock_timer_fct is called (try adding a
>   printk in there)
> - If the timer frequency reported by trace_clock_frequency() is
> correct.

I don't have NO_HZ defined.
My HZ value is 100.
The trace_clock_timer_fct function is called.
The trace_clock_frequency() function returns 819200.

I wonder whether the problem I am seeing is not with the LTTng tracing, but
with the LTTV trace decoder...

When I run lttv 0.12.14 with the "-v -d -e" options it fails to parse the
trace, which is strange because the description for those options only
mentions changes to what it prints, and instead reports the following:

** INFO: Logging set to include INFO level messages
** INFO: Option debug encountered
** INFO: Logging set to include DEBUG level messages
** INFO: Option expression encountered
** (process:14316): DEBUG: Option expression hook called
** INFO: BatchAnalysis begin process traceset
** INFO: BatchAnalysis initialize context

** (process:14316): WARNING **: Error encountered in value assignation !
Field type = 19
** (process:14316): DEBUG: +++++++++++++++ BEGIN PRINT ++++++++++++++++

** (process:14316): DEBUG: node:0x8c45e28 lchild:(nil) rchild:0x8c45f98
depth:0

** (process:14316): DEBUG: logic operator: IDLE

** (process:14316): DEBUG: |-> left branch (nil) is a IDLE

** (process:14316): DEBUG: |-> right branch 0x8c45f98 is a NODE

** (process:14316): DEBUG: node:0x8c45f98 lchild:(nil) rchild:(nil) depth:1

** (process:14316): DEBUG: logic operator: IDLE

** (process:14316): DEBUG: |-> left branch (nil) is a IDLE

** (process:14316): DEBUG: |-> right branch 0x8c45f80 is a LEAF

** (process:14316): DEBUG: | |-> field type number: 19

** (process:14316): DEBUG: | |-> offset is: 0

** (process:14316): DEBUG: | |-> operator function is: (nil)

** (process:14316): DEBUG: +++++++++++++++ END PRINT ++++++++++++++++++

** INFO: TextDump traceset header
** INFO: BatchAnalysis process traceset
** INFO: TextDump traceset footer
** INFO: BatchAnalysis destroy context
** (process:14316): DEBUG: attribute_finalize()
** INFO: BatchAnalysis end process traceset
** INFO: Unload library builtin: module print used
** INFO: Module module: destroy()
** INFO: Destroy module.c
** INFO: Unload library builtin: locked loaded
** INFO: Unload library builtin: locked loaded
** INFO: Module textDump: destroy()
** INFO: Destroy textDump
** INFO: Remove option noevent
** INFO: Remove option output
** INFO: Remove option field_names
** INFO: Remove option process_state
** INFO: Remove option cpu_stats
** INFO: Remove option process_stats
** INFO: Remove option path_output
** INFO: Unload library builtin: locked loaded
** INFO: Module batchAnalysis: destroy()
** INFO: Destroy batchAnalysis.c
** INFO: Remove option trace
** INFO: Remove option stats
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: lttv_hooks_destroy()
** (process:14316): DEBUG: attribute_finalize()
** INFO: Unload library builtin: locked loaded
** INFO: Module stats: destroy()
** INFO: Module state: destroy()
** INFO: Unload library builtin: locked loaded
** INFO: Unload library builtin: locked loaded
** INFO: Unload library builtin: locked loaded
** INFO: Module textFilter: destroy()
** INFO: Destroy textFilter
** INFO: Remove option expression
** INFO: Remove option filename
** INFO: Remove option list
** INFO: Unload library builtin: locked loaded
** INFO: Unload library textFilter: close the GModule
** INFO: Unload library batchAnalysis: close the GModule
** INFO: Module option: destroy()
** INFO: Destroy option.c
** INFO: Unload library builtin: locked loaded
** INFO: Module print: destroy()
** INFO: Unload library builtin: locked loaded
** INFO: Unload library textDump: locked loaded
** INFO: Unload library textDump: close the GModule
** INFO: Unload library builtin: close the GModule
Trace set contains 0 traces

End trace set