Session Summary
==================================================
Instance Name: ovmt1
Node Name: vmt1
Oracle Release: 9.2.0.6.0
Session ID: 55.5555
Total SQL Statements: 6 (2 user statements, 4 internal statements)
Total Transactions: 2 (1 rollback(s), 1 read-only)
Session Flat Profile
==================================================
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
unaccounted-for time 57.0% 3.6919s
SQL*Net message from client 33.7% 2.1842s 10,000 0.0002s 0.0000s 0.0055s
EXEC calls [CPU] 8.5% 0.5519s 10,018 0.0001s 0.0000s 0.0120s
SQL*Net message to client 0.5% 0.0327s 10,003 0.0000s 0.0000s 0.0033s
PARSE calls [CPU] 0.2% 0.0100s 17 0.0006s 0.0000s 0.0040s
SQL*Net message from client [idle] 0.1% 0.0062s 3 0.0021s 0.0003s 0.0052s
FETCH calls [CPU] 0.0% 0.0030s 11 0.0003s 0.0000s 0.0010s
log file sync 0.0% 0.0005s 1 0.0005s 0.0005s 0.0005s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 6.4804s
Top 5 Statements per Event
==================================================
SQL*Net message from client
---------------------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
2730263304 100.0% 2.1842s 10,000 0.0002s 0.0000s 0.0055s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 2.1842s 10,000 0.0002s 0.0000s 0.0055s
EXEC calls [CPU]
----------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
2730263304 94.4% 0.5209s 10,001 0.0001s 0.0000s 0.0120s
3948238198 3.1% 0.0170s 5 0.0034s 0.0020s 0.0040s
1380021873 2.4% 0.0130s 5 0.0026s 0.0010s 0.0070s
1254950678 0.2% 0.0010s 5 0.0002s 0.0000s 0.0010s
1346161232 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
1 others 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.5519s 10,018 0.0001s 0.0000s 0.0120s
SQL*Net message to client
-------------------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
2730263304 100.0% 0.0327s 10,002 0.0000s 0.0000s 0.0033s
Cursor #0 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0327s 10,003 0.0000s 0.0000s 0.0033s
PARSE calls [CPU]
-----------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
1380021873 50.0% 0.0050s 5 0.0010s 0.0000s 0.0040s
3948238198 30.0% 0.0030s 5 0.0006s 0.0000s 0.0020s
1254950678 10.0% 0.0010s 5 0.0002s 0.0000s 0.0010s
431456802 10.0% 0.0010s 1 0.0010s 0.0010s 0.0010s
2730263304 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0100s 17 0.0006s 0.0000s 0.0040s
SQL*Net message from client [idle]
----------------------------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
2730263304 94.8% 0.0059s 2 0.0029s 0.0007s 0.0052s
Cursor #0 5.2% 0.0003s 1 0.0003s 0.0003s 0.0003s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0062s 3 0.0021s 0.0003s 0.0052s
FETCH calls [CPU]
-----------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
1254950678 100.0% 0.0030s 10 0.0003s 0.0000s 0.0010s
431456802 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0030s 11 0.0003s 0.0000s 0.0010s
log file sync
-------------
----------- Time Per Call ---------
SQL Hash Value % Time Seconds Calls Avg Min Max
---------------- -------- ------------ --------- ----------- ----------- -----------
Cursor #0 100.0% 0.0005s 1 0.0005s 0.0005s 0.0005s
---------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0005s 1 0.0005s 0.0005s 0.0005s
Session Call Graph
==================================================
Self Cumulative
SQL Hash Value Branch % Total % Seconds Seconds Calls Similar Command Type
---------------------- -------- -------- ------------ ------------ --------- ------- ----------------
2730263304 98.5% 100.0% 2.9083s 2.9537s 10,002 0 INSERT
1380021873 0.7% 0.8% 0.0206s 0.0222s 10 0 UPDATE
431456802 0.1% 0.1% 0.0016s 0.0016s 3 0 SELECT
3948238198 0.7% 0.7% 0.0197s 0.0197s 10 0 UPDATE
1254950678 0.1% 0.1% 0.0035s 0.0035s 20 0 SELECT
- - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - -
1346161232 100.0% 0.0% 0.0002s 0.0002s 1 0 ALTER SESSION
---------------------- -------- -------- ------------ ------------ --------- ------- ----------------
Events Histograms
==================================================
SQL*Net message from client
---------------------------
value ------------- Distribution ------------- count
0.000000s | 5
0.000001s | 0
0.000002s | 0
0.000004s | 0
0.000008s | 0
0.000016s | 0
0.000032s | 0
0.000064s |@@@@@@@@@@@@@@@@@@@@ 5,095
0.000128s |@@@@@@@ 1,959
0.000256s |@@@@@@@@@ 2,273
0.000512s |@@ 628
0.001024s | 33
0.002048s | 6
0.004096s | 1
0.008192s | 0
EXEC calls [CPU]
----------------
value ------------- Distribution ------------- count
0.000000s |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 9,545
0.000001s | 0
0.000002s | 0
0.000004s | 0
0.000008s | 0
0.000016s | 0
0.000032s | 0
0.000064s | 0
0.000128s | 0
0.000256s | 0
0.000512s |@ 434
0.001024s | 24
0.002048s | 10
0.004096s | 4
0.008192s | 1
0.016384s | 0
SQL*Net message to client
-------------------------
value ------------- Distribution ------------- count
0.000000s |@@@@@@@@@@@@@@@ 3,789
0.000001s | 2
0.000002s |@@@@@@@@@@@@@@@@ 4,142
0.000004s |@@@@@@@@ 2,020
0.000008s | 8
0.000016s | 8
0.000032s | 4
0.000064s | 7
0.000128s | 1
0.000256s | 21
0.000512s | 0
0.001024s | 0
0.002048s | 1
0.004096s | 0
PARSE calls [CPU]
-----------------
value ------------- Distribution ------------- count
0.000000s |@@@@@@@@@@@@@@@@@@@@@@@@@ 11
0.000001s | 0
0.000002s | 0
0.000004s | 0
0.000008s | 0
0.000016s | 0
0.000032s | 0
0.000064s | 0
0.000128s | 0
0.000256s | 0
0.000512s |@@@@@@@@@ 4
0.001024s |@@ 1
0.002048s |@@ 1
0.004096s | 0
SQL*Net message from client [idle]
----------------------------------
value ------------- Distribution ------------- count
0.000128s | 0
0.000256s |@@@@@@@@@@@@@ 1
0.000512s |@@@@@@@@@@@@@ 1
0.001024s | 0
0.002048s | 0
0.004096s |@@@@@@@@@@@@@ 1
0.008192s | 0
FETCH calls [CPU]
-----------------
value ------------- Distribution ------------- count
0.000000s |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 8
0.000001s | 0
0.000002s | 0
0.000004s | 0
0.000008s | 0
0.000016s | 0
0.000032s | 0
0.000064s | 0
0.000128s | 0
0.000256s | 0
0.000512s |@@@@@@@@@@ 3
0.001024s | 0
log file sync
-------------
value ------------- Distribution ------------- count
0.000128s | 0
0.000256s |@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@ 1
0.000512s | 0
SQL Statements
==================================================
SQL Hash Value: 2730263304 uid: 42 depth: 0 optimizer mode: CHOOSE
Statement Text
--------------
insert into t3 values(:b1,lpad(:b2,20,'0'))
Statement Cumulative Statistics
-------------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 0 1 0.0000s 0.0002s 0 0 0 0
Exec 0 10,001 0.5649s 2.9535s 0 127 10,662 10,001
Fetch 0 0.0000s 0.0000s 0 0 0 0
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 0 10,002 0.5649s 2.9537s 0 127 10,662 10,001
Per Exe 0.0 1.0 0.0001s 0.0003s 0.0 0.0 1.1 1.0
Per Row 0.0 1.0 0.0001s 0.0003s 0.0 0.0 1.1 1.0
Statement Self Statistics
-------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 0 1 0.0000s 0.0002s 0 0 0 0
Exec 0 10,001 0.5209s 2.9081s 0 67 10,652 10,001
Fetch 0 0.0000s 0.0000s 0 0 0 0
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 0 10,002 0.5209s 2.9083s 0 67 10,652 10,001
Per Exe 0.0 1.0 0.0001s 0.0003s 0.0 0.0 1.1 1.0
Per Row 0.0 1.0 0.0001s 0.0003s 0.0 0.0 1.1 1.0
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
SQL*Net message from client 79.6% 2.1842s 10,000 0.0002s 0.0000s 0.0055s
EXEC calls [CPU] 19.0% 0.5209s 10,001 0.0001s 0.0000s 0.0120s
SQL*Net message to client 1.2% 0.0327s 10,002 0.0000s 0.0000s 0.0033s
SQL*Net message from client [idle] 0.2% 0.0059s 2 0.0029s 0.0007s 0.0052s
PARSE calls [CPU] 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 2.7437s
Statement Binds Statistics
--------------------------
total binds sets: 10001 unique binds sets: 10000
Sample Bind Set
---------------
met 1 time; elapsed time spent: 0.0334s
Name Data Type Value
-------------------- -------------------- --------------------
b1 NUMBER 1213
b2 VARCHAR2 "1213"
********************************************************************************
SQL Hash Value: 1380021873 uid: 0 depth: 1 optimizer mode: CHOOSE
Statement Text
--------------
update tsq$ set blocks=:3,maxblocks=:4,grantor#=:5,priv1=:6,priv2=:7,priv3=:8
where ts#=:1 and user#=:2
Statement Cumulative Statistics
-------------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 1 5 0.0050s 0.0042s 0 0 0 0
Exec 0 5 0.0140s 0.0180s 0 20 5 5
Fetch 0 0.0000s 0.0000s 0 0 0 0
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 1 10 0.0190s 0.0222s 0 20 5 5
Per Exe 0.2 2.0 0.0038s 0.0044s 0.0 4.0 1.0 1.0
Per Row 0.2 2.0 0.0038s 0.0044s 0.0 4.0 1.0 1.0
Statement Self Statistics
-------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 1 5 0.0050s 0.0042s 0 0 0 0
Exec 0 5 0.0130s 0.0164s 0 17 5 5
Fetch 0 0.0000s 0.0000s 0 0 0 0
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 1 10 0.0180s 0.0206s 0 17 5 5
Per Exe 0.2 2.0 0.0036s 0.0041s 0.0 3.4 1.0 1.0
Per Row 0.2 2.0 0.0036s 0.0041s 0.0 3.4 1.0 1.0
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
EXEC calls [CPU] 72.2% 0.0130s 5 0.0026s 0.0010s 0.0070s
PARSE calls [CPU] 27.8% 0.0050s 5 0.0010s 0.0000s 0.0040s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0180s
Statement Plan
--------------
met 5 times
Rows Row Source Operation [Object Id]
---------- --------------------------------
0 UPDATE
5 TABLE ACCESS CLUSTER TSQ$ [60]
5 INDEX UNIQUE SCAN I_USER# [11]
Statement Binds Statistics
--------------------------
total binds sets: 5 unique binds sets: 5
Sample Bind Set
---------------
met 1 time; elapsed time spent: 0.0145s
Name Data Type Value
-------------------- -------------------- --------------------
3 NUMBER 15784
4 NUMBER 25600
5 NUMBER 0
6 NUMBER 0
7 NUMBER 0
8 NUMBER 0
1 NUMBER 7
2 NUMBER 42
********************************************************************************
SQL Hash Value: 3948238198 uid: 0 depth: 1 optimizer mode: CHOOSE
Statement Text
--------------
update seg$ set
type#=:4,blocks=:5,extents=:6,minexts=:7,maxexts=:8,extsize=:9,extpct=:10,user#=:11,iniexts=:12,lists=decode(:13,
65535, NULL, :13),groups=decode(:14, 65535, NULL, :14), cachehint=:15,
hwmincr=:16, spare1=DECODE(:17,0,NULL,:17)
where ts#=:1 and file#=:2 and block#=:3
Statement Cumulative Statistics
-------------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 1 5 0.0030s 0.0022s 0 0 0 0
Exec 0 5 0.0170s 0.0175s 0 25 5 5
Fetch 0 0.0000s 0.0000s 0 0 0 0
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 1 10 0.0200s 0.0197s 0 25 5 5
Per Exe 0.2 2.0 0.0040s 0.0039s 0.0 5.0 1.0 1.0
Per Row 0.2 2.0 0.0040s 0.0039s 0.0 5.0 1.0 1.0
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
EXEC calls [CPU] 85.0% 0.0170s 5 0.0034s 0.0020s 0.0040s
PARSE calls [CPU] 15.0% 0.0030s 5 0.0006s 0.0000s 0.0020s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0200s
Statement Plan
--------------
met 5 times
Rows Row Source Operation [Object Id]
---------- --------------------------------
0 UPDATE
5 TABLE ACCESS CLUSTER SEG$ [14]
5 INDEX UNIQUE SCAN I_FILE#_BLOCK# [9]
Statement Binds Statistics
--------------------------
total binds sets: 5 unique binds sets: 5
Sample Bind Set
---------------
met 1 time; elapsed time spent: 0.0066s
Name Data Type Value
-------------------- -------------------- --------------------
4 NUMBER 5
5 NUMBER 16
6 NUMBER 2
7 NUMBER 1
8 NUMBER 2147483645
9 NUMBER 128
10 NUMBER 0
11 NUMBER 42
12 NUMBER 8
13 NUMBER 0
14 NUMBER 0
15 NUMBER 0
16 NUMBER 28867
17 NUMBER 131329
1 NUMBER 7
2 NUMBER 7
3 NUMBER 15779
********************************************************************************
SQL Hash Value: 1254950678 uid: 0 depth: 1 optimizer mode: CHOOSE
Statement Text
--------------
select file# from file$ where ts#=:1
Statement Cumulative Statistics
-------------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 0 5 0.0010s 0.0012s 0 0 0 0
Exec 0 5 0.0010s 0.0009s 0 0 0 0
Fetch 10 0.0030s 0.0014s 0 15 0 5
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 0 20 0.0050s 0.0035s 0 15 0 5
Per Fch 0.0 2.0 0.0005s 0.0003s 0.0 1.5 0.0 0.5
Per Row 0.0 4.0 0.0010s 0.0007s 0.0 3.0 0.0 1.0
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
FETCH calls [CPU] 60.0% 0.0030s 10 0.0003s 0.0000s 0.0010s
EXEC calls [CPU] 20.0% 0.0010s 5 0.0002s 0.0000s 0.0010s
PARSE calls [CPU] 20.0% 0.0010s 5 0.0002s 0.0000s 0.0010s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0050s
Statement Plan
--------------
met 5 times
Rows Row Source Operation [Object Id]
---------- --------------------------------
5 TABLE ACCESS BY INDEX ROWID FILE$ [17]
5 INDEX RANGE SCAN I_FILE2 [42]
Statement Binds Statistics
--------------------------
total binds sets: 5 unique binds sets: 1
Sample Bind Set
---------------
met 5 times; elapsed time spent: 0.0035s
Name Data Type Value
-------------------- -------------------- --------------------
1 NUMBER 7
********************************************************************************
SQL Hash Value: 431456802 uid: 0 depth: 2 optimizer mode: CHOOSE
Statement Text
--------------
select
o.owner#,o.name,o.namespace,o.remoteowner,o.linkname,o.subname,o.dataobj#,o.flags
from obj$ o
where o.obj#=:1
Statement Cumulative Statistics
-------------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 0 1 0.0010s 0.0010s 0 0 0 0
Exec 0 1 0.0000s 0.0002s 0 0 0 0
Fetch 1 0.0000s 0.0005s 0 3 0 1
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 0 3 0.0010s 0.0016s 0 3 0 1
Per Fch 0.0 3.0 0.0010s 0.0016s 0.0 3.0 0.0 1.0
Per Row 0.0 3.0 0.0010s 0.0016s 0.0 3.0 0.0 1.0
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
PARSE calls [CPU] 100.0% 0.0010s 1 0.0010s 0.0010s 0.0010s
EXEC calls [CPU] 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
FETCH calls [CPU] 0.0% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0010s
Statement Binds Statistics
--------------------------
total binds sets: 1 unique binds sets: 1
Sample Bind Set
---------------
met 1 time; elapsed time spent: 0.0016s
Name Data Type Value
-------------------- -------------------- --------------------
1 NUMBER 60
********************************************************************************
SQL Hash Value: 1346161232 uid: 42 depth: 0 optimizer mode: CHOOSE
Statement Text
--------------
alter session set events '10046 trace name context forever,level 12'
Statement Cumulative Statistics
-------------------------------
Cache --------- Seconds -------- Physical ---- Logical Reads -----
Call Misses Count CPU Elapsed Reads Consistent Current Rows
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Parse 0 0 0.0000s 0.0000s 0 0 0 0
Exec 0 1 0.0000s 0.0002s 0 0 0 0
Fetch 0 0.0000s 0.0000s 0 0 0 0
------- ------- --------- ------------ ------------ ----------- ----------- ----------- ---------
Total 0 1 0.0000s 0.0002s 0 0 0 0
Per Exe 0.0 1.0 0.0000s 0.0002s 0.0 0.0 0.0 0.0
Per Row 0.0 1.0 0.0000s 0.0002s 0.0 0.0 0.0 0.0
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
EXEC calls [CPU] n/a % 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0000s
********************************************************************************
SQL Hash Value: Cursor #0 uid: n/a
Statement Text
--------------
No statement text found in trace file
Statement Cumulative Statistics
-------------------------------
No cumulative stat found in trace file
Statement Flat Profile
----------------------
----------- Time Per Call ---------
Event Name % Time Seconds Calls Avg Min Max
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
log file sync 58.2% 0.0005s 1 0.0005s 0.0005s 0.0005s
SQL*Net message from client [idle] 41.1% 0.0003s 1 0.0003s 0.0003s 0.0003s
SQL*Net message to client 0.8% 0.0000s 1 0.0000s 0.0000s 0.0000s
---------------------------------------- -------- ------------ --------- ----------- ----------- -----------
Total 100.0% 0.0008s
********************************************************************************
Generated by OraSRP v4.1.0 beta http://oracledba.ru/orasrp