Help understand raw trace data.

From: Fred Tilly <ftilly_at_btinternet.com>
Date: Fri, 4 Sep 2009 13:47:08 +0000 (GMT)
Message-ID: <310792.83624.qm_at_web87104.mail.ird.yahoo.com>

Hi,

I have a stored procedure that runs a simple select statement and returns a series of parameters, the total size of the parameters returned in bytes should be 1032 bytes. However when I trace the procedure call I see the following in the trace file:

PARSING IN CURSOR #32 len=112 dep=0 uid=60 oct=47 lid=60 tim=2677334787 hv=2159500523 ad='2db0cc60' BEGIN ORA_INTERACTION(:1, :2, :3, :4, :5, :6, :7, :8, :9, :10, :11, :12, :13, :14, :15, :16, :17); END; END OF STMT
PARSE #32:c=0,e=24,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=2677334785 EXEC #18:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=2677334986 FETCH #18:c=0,e=35,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=1,tim=2677335035

WAIT #32: nam='SQL*Net message to client' ela= 1 driver id=1952673792 #bytes=1 p3=0 obj#=68676 tim=2677335092
WAIT #32: nam='SQL*Net more data to client' ela= 22 driver id=1952673792 #bytes=2134 p3=0 obj#=68676 tim=2677335132
WAIT #32: nam='SQL*Net more data to client' ela= 7 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677335150
WAIT #32: nam='SQL*Net more data to client' ela= 7 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677335166
WAIT #32: nam='SQL*Net more data to client' ela= 6 driver id=1952673792 #bytes=1792 p3=0 obj#=68676 tim=2677335181
WAIT #32: nam='SQL*Net more data to client' ela= 8 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677335198
WAIT #32: nam='SQL*Net more data to client' ela= 751 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677335959
WAIT #32: nam='SQL*Net more data to client' ela= 316 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677336290
WAIT #32: nam='SQL*Net more data to client' ela= 13 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677336319
WAIT #32: nam='SQL*Net more data to client' ela= 1286 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677337620
WAIT #32: nam='SQL*Net more data to client' ela= 8 driver id=1952673792 #bytes=1792 p3=0 obj#=68676 tim=2677337644
WAIT #32: nam='SQL*Net more data to client' ela= 574307 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677911966
WAIT #32: nam='SQL*Net more data to client' ela= 645 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677912636
WAIT #32: nam='SQL*Net more data to client' ela= 6 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677912658
WAIT #32: nam='SQL*Net more data to client' ela= 5 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2677912676
WAIT #32: nam='SQL*Net more data to client' ela= 655250 driver id=1952673792 #bytes=1792 p3=0 obj#=68676 tim=2678567941
WAIT #32: nam='SQL*Net more data to client' ela= 10 driver id=1952673792 #bytes=2048 p3=0 obj#=68676 tim=2678567982
EXEC #32:c=0,e=1233159,p=0,cr=4,cu=0,mis=0,r=1,dep=0,og=1,tim=2678568000
WAIT #32: nam='SQL*Net message from client' ela= 3385 driver id=1952673792 #bytes=1 p3=0 obj#=68676 tim=2678571444

XCTEND rlbk=0, rd_only=1
WAIT #0: nam='SQL*Net message to client' ela= 0 driver id=1952673792 #bytes=1 p3=0 obj#=68676 tim=2678571535 WAIT #0: nam='SQL*Net message from client' ela= 555 driver id=1952673792 #bytes=1 p3=0 obj#=68676 tim=2678572105 XCTEND rlbk=0, rd_only=1
WAIT #0: nam='SQL*Net message to client' ela= 0 driver id=1952673792 #bytes=1 p3=0 obj#=68676 tim=2678572143 WAIT #0: nam='SQL*Net message from client' ela= 2682513 driver id=1952673792 #bytes=1 p3=0 obj#=68676 tim=2681254673 *** 2009-09-04 11:32:44.610

My question is why so many calls to " SQL*Net more data to client" if I am only passing what I think is 1032 bytes.

Thanks

Fred

--
http://www.freelists.org/webpage/oracle-l

Received on Fri Sep 04 2009 - 08:47:08 CDT