trace module diagnostic file example

Edit online

The trace module's stat file contains debug and diagnostic data.

Header elements

  • Date
  • Hostname
  • aio is enable or not
  • Program name
  • MIO library version
  • Environment variables

Debug elements

  • List of all setting debug options
  • All modules definitions table if DEF debug option is set
  • Open request made to MIO_open64 if OPEN debug is set
  • Modules invoked if MODULES debug option is set

Elements specific to the trace module with layout

  • Time, if TIMESTAMP debug option is set
  • Trace on close or on intermediate interrupt
  • Trace module position in module_list
  • Processed file name
  • Rate: the amount of data divided by the total time; the cumulative amount of time spent beneath the trace module
  • Demand rate: the amount of data divided by the length of time the file is opened, including times when the file was opened and closed
  • The current (when tracing) file size and the max size of the file during this file processing
  • File system information: file type, sector size
  • File open mode and flags
  • For each function: number of times this function was called, and processing time for this function
  • For read or write function: more information, such as requested (requested size to read or write), total (real size read or write: returned by aix system call), min, and max
  • For seek: the average seek delta (total seek delta and seek count)
  • For a read or a write: suspend information, such as count , time and rate of transfer time including suspend and read and write time.
  • Number of fcntl page_info requests : page
date
Trace on close or intermediate  :  previous module or calling program <-> next module : file name : (total transferred bytes/total time)=rate 
    demand rate=rate/s=total transferred bytes/(close time-open time)
    current size=actual size of the file  max_size=max size of the file 
mode=file open mode FileSystemType=file system type given by fststat(stat_b.f_vfstype) sector size=Minimum direct i/o transfer size
oflags=file open flags
open    open count           open time
fcntl      fcntl count         fcntl time
read      read count         read time   requested size      total size      minimum      maximum
aread    aread count       aread time requested size      total size      minimum      maximum
   suspend  count             time            rate
write      write count      write time  requested size        total size      minimum      maximum
seek      seek count         seek time      average seek delta 
size
page    fcntl page_info count

Sample

MIO statistics file : Tue May 10 14:14:08 2005
hostname=host1  : with Legacy aio available
Program=/mio/example
MIO library libmio.a 3.0.0.60  AIX 32 bit addressing built Apr 19 2005 15:08:17
MIO_INSTALL_PATH=
MIO_STATS       =example.stats
MIO_DEBUG       =OPEN
MIO_FILES       = *.dat [ trace/stats ]
MIO_DEFAULTS    = trace/kbytes

MIO_DEBUG OPEN =T

Opening file file.dat
   modules[11]=trace/stats
============================================================================

Trace close : program <-> aix : file.dat : (4800/0.04)=111538.02 kbytes/s
     demand rate=42280.91 kbytes/s=4800/(0.12-0.01))
     current size=0   max_size=1600
  mode =0640  FileSystemType=JFS sector size=4096
  oflags =0x302=RDWR  CREAT  TRUNC
  open              1     0.00
  write           100     0.02       1600       1600      16384      16384
  read            200     0.02       3200       3200      16384      16384
  seek            101     0.01 average seek delta=-48503
  fcntl             1     0.00
  trunc             1     0.01
  close             1     0.00
  size            100
============================================================================