[ltt-dev] CONFIG_HAVE_TRACE_CLOCK_GENERIC timestamp problem on PXA255

[Mathieu Desnoyers]

* Steve Langstaff (steve.langstaff at pebblebay.com) wrote:
> > 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:
> 

This kind of time-stamping issue is usually within the trace clock
infrastructure.

The -e option enables the filter. The warnings below come from the
filter code.

Mathieu

> ** 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
> 
> 

-- 
Mathieu Desnoyers
OpenPGP key fingerprint: 8CD5 52C3 8E3C 4140 715F  BA06 3F25 A8FE 3BAE 9A68