Hello,
In 19c I 10046-traced a session that at some point shows in resulting trc file:
338 PARSING IN CURSOR #139803766057680 len=546 dep=0 uid=91 oct=3 lid=91 tim=4597454168373 hv=3312842177 ad='a0e55480' sqlid='bp9v0av2rbxf1'
339 SELECT A.BDG FROM X A [...]
340 END OF STMT
341 EXEC #139803766057680:c=28,e=28,p=0,cr=4294967296,cu=0,mis=0,r=0,dep=0,og=1,plh=1104270930,tim=4597454168373
342
343 *** 2021-11-26T14:33:57.355051+01:00
344 FETCH #139803766057680:c=7796964,e=7869045,p=0,cr=4296007886,cu=0,mis=0,r=0,dep=0,og=1,plh=1104270930,tim=4597462037441
345 WAIT #139803766057680: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462037555
346 WAIT #139803766057680: nam='SQL*Net message from client' ela= 705 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462038272
347 EXEC #139803765046984:c=52,e=52,p=0,cr=4294967296,cu=0,mis=0,r=0,dep=0,og=1,plh=1104270930,tim=4597462038341
348 FETCH #139803765046984:c=8,e=8,p=0,cr=4294967298,cu=0,mis=0,r=0,dep=0,og=1,plh=1104270930,tim=4597462038364
349 WAIT #139803765046984: nam='SQL*Net message to client' ela= 0 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462038387
350 WAIT #139803765046984: nam='SQL*Net message from client' ela= 331 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462038725
351 EXEC #139803772509456:c=14,e=14,p=0,cr=4294967296,cu=0,mis=0,r=0,dep=0,og=1,plh=1355878524,tim=4597462038761
352 FETCH #139803772509456:c=2,e=2,p=0,cr=4294967298,cu=0,mis=0,r=0,dep=0,og=1,plh=1355878524,tim=4597462038774
353 WAIT #139803772509456: nam='SQL*Net message to client' ela= 0 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462038789
354 WAIT #139803772509456: nam='SQL*Net message from client' ela= 268 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462039063
355 EXEC #139803772509456:c=7,e=7,p=0,cr=4294967296,cu=0,mis=0,r=0,dep=0,og=1,plh=1355878524,tim=4597462039078
356 FETCH #139803772509456:c=2,e=2,p=0,cr=4294967298,cu=0,mis=0,r=0,dep=0,og=1,plh=1355878524,tim=4597462039087
357 WAIT #139803772509456: nam='SQL*Net message to client' ela= 1 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462039098
358 WAIT #139803772509456: nam='SQL*Net message from client' ela= 345 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462039448
359 EXEC #139803766057680:c=24,e=24,p=0,cr=4294967296,cu=0,mis=0,r=0,dep=0,og=1,plh=1104270930,tim=4597462039482
360 FETCH #139803766057680:c=4,e=4,p=0,cr=4294967298,cu=0,mis=0,r=0,dep=0,og=1,plh=1104270930,tim=4597462039495
361 WAIT #139803766057680: nam='SQL*Net message to client' ela= 0 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462039510
362 WAIT #139803766057680: nam='SQL*Net message from client' ela= 361 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=4597462039877
This PARSING line with SQL ID bp9v0av2rbxf1 appears only once in .trc file.
When I process it with tkprof utility, the section related to this bp9v0av2rbxf1 shows the following:
SELECT A.BDG
FROM A ...
call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 0 0.00 0.00 0 0 0 0
Execute 1660 0.06 0.07 0 0 0 0
Fetch 1660 9138.71 9216.25 0 1227971532 0 0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 3320 9138.78 9216.32 0 1227971532 0 0
I have 2 questions:
- How come there are several 'EXEC' lines in .trc file ? They are 5, in the middle: what a 10046 .trc displays happens sequentially right ??
- Tkprof result shows for this bp9v0av2rbxf1 that it was executed 1660 times: any idea how this calculation is made based on the full 10046 .trc block I copied above ??
Thanks a lot.
Regards,
Sebino