RE: Help Interpreting TKProf

Barb,

What you're really after is called "forward attribution" in the book. You need to see the dbcall that immediately follows each "WAIT nam='SQL*Net message from client'" event in the trace file. This will tell you what database call's execution it was that ended the read().

Cary Millsap
Hotsos Enterprises, Ltd.
http://www.hotsos.com

Upcoming events:

- Performance Diagnosis 101: 11/19 Sydney, 12/16 Detroit
- SQL Optimization 101: 12/8 Dallas, 2/16 Dallas
- Hotsos Symposium 2004: March 7-10 Dallas
- Visit www.hotsos.com for schedule details...

-----Original Message-----
Daniel Fink
Sent: Wednesday, November 12, 2003 2:35 PM To: Multiple recipients of list ORACLE-L

Barbara,

I know how distracted you were with all the jokes being tossed about, but really...

It looks to me (with just this snippet of trace) that the cursor #3 did some communication and then waited for 10 seconds for a response. Without seeing the raw trace file and sequence of events, this is my best guess.

Daniel

Barbara Baker wrote:

> Daniel:
> Does this mean I was supposed to be paying attention
> in class??
>
> Here's everything I can find associated with sql*net
> message from client in that cursor. Can't figure out
> from this how it came up with 10.73 (although that one
> wait matches up with the max wait of 10.71)
>
> Am I being really dense?
> WAIT!! don't answer that!
>
> WAIT #3: nam='SQL*Net message from client' ela= 0
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 0
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 1

wait_time = 1 centisecond

>
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 0
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 0
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 0
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 1

wait_time = wait_time (1 cs) + 1 cs = 2 cs

>
> p1=1413697536 p2=1 p3=0
> WAIT #3: nam='SQL*Net message from client' ela= 1071
> p1=1413697536 p2=1 p3=0

wait_time = wait_time(2 cs) + 1071 cs = 1073 cs

-- 
Please see the official ORACLE-L FAQ: http://www.orafaq.net
-- 
Author: Daniel Fink
  INET: Daniel.Fink_at_Sun.COM

Fat City Network Services    -- 858-538-5051 http://www.fatcity.com
San Diego, California        -- Mailing list and web hosting services
---------------------------------------------------------------------
To REMOVE yourself from this mailing list, send an E-Mail message
to: ListGuru_at_fatcity.com (note EXACT spelling of 'ListGuru') and in
the message BODY, include a line containing: UNSUB ORACLE-L
(or the name of mailing list you want to be removed from).  You may
also send the HELP command for other information (like subscribing).

-- 
Please see the official ORACLE-L FAQ: http://www.orafaq.net
-- 
Author: Cary Millsap
  INET: cary.millsap_at_hotsos.com

Fat City Network Services    -- 858-538-5051 http://www.fatcity.com
San Diego, California        -- Mailing list and web hosting services
---------------------------------------------------------------------
To REMOVE yourself from this mailing list, send an E-Mail message
to: ListGuru_at_fatcity.com (note EXACT spelling of 'ListGuru') and in
the message BODY, include a line containing: UNSUB ORACLE-L
(or the name of mailing list you want to be removed from).  You may
also send the HELP command for other information (like subscribing).