Re: negative elapsed times in 10046 trace file for single block reads

  • From: "Paul Drake" <bdbafh@xxxxxxxxx>
  • To: oracle-l <oracle-l@xxxxxxxxxxxxx>
  • Date: Tue, 19 Dec 2006 16:45:50 -0500

On 12/19/06, Paul Drake <bdbafh@xxxxxxxxx> wrote:

10g R1 std ed 32 bit (10.1.0.4 with cpuoct2006 applied).
w2k3 R2 sp1 32 bit
CPUs: a pair of dual core AMD Opterons
datafile storage is on a NetApp Filer attached by a pair of non-TOE
enabled onboard gigabit ethernet adapters using an MS iSCSI driver.


Reproduced with direct attached storage SAS drives.
Guess I'll see if I can reproduce it against 10.2.0.x. while prepping an
iTAR.
How many more lines need to be added to pad this out so that it doesn't get
flagged for over--quoting?

Paul


I'm seeing negative values for elapsed time in a 10046 trace file (lots of
them, actually):

WAIT #27: nam='db file sequential read' ela= 6652 p1=24 p2=52089 p3=1
WAIT #27: nam='db file sequential read' ela= -365131103 p1=25 p2=58558
p3=1
WAIT #27: nam='db file sequential read' ela= 15075 p1=25 p2=58560 p3=1

A quick search of metalink returned only the reference doc
*Note:39817.1 * *Interpreting Raw SQL_TRACE and DBMS_SUPPORT.START_TRACE
output*

Has anyone else run across this before?

thanks,

Paul


Other related posts: