orcl899_ora_3600.trc / Oracle Session Profiler

Session Summary

Instance Name: orcl899
Session ID: 132.2
Total SQL Statements: 3 (3 user statements, 0 internal statements)
Total Transactions: 0 (0 rollback(s), 0 read-only)

Report Shortcuts

Session Flat Profile
Top 5 Statements per Event
Session Call Graph
Events Histograms
Statements

Session Flat Profile

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
SQL*Net message from client [idle]	48.5%	225.6026s	5	45.1205s	0.0002s	187.5339s
unaccounted-for time	39.8%	185.0971s
direct path read	11.4%	53.0213s	388	0.1367s	0.0000s	1.1906s
FETCH calls [CPU]	0.3%	1.5781s	23	0.0686s	0.0000s	0.5625s
SQL*Net message from client	0.0%	0.1050s	23	0.0045s	0.0001s	0.0056s
PARSE calls [CPU]	0.0%	0.0312s	6	0.0052s	0.0000s	0.0312s
reliable message	0.0%	0.0007s	4	0.0001s	0.0001s	0.0002s
enq: KO - fast object checkpoint	0.0%	0.0005s	4	0.0001s	0.0000s	0.0002s
SQL*Net message to client	0.0%	0.0001s	28	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	0.0%	0.0000s	6	0.0000s	0.0000s	0.0000s
Total	100.0%	465.4367s

Top 5 Statements per Event

SQL*Net message from client [idle]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1029988163	100.0%	225.5977s	3	75.1992s	12.9939s	187.5339s
1120317097	0.0%	0.0046s	1	0.0046s	0.0046s	0.0046s
Cursor #8	0.0%	0.0002s	1	0.0002s	0.0002s	0.0002s
Total	100.0%	225.6026s	5	45.1205s	0.0002s	187.5339s

direct path read

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s
Total	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s

FETCH calls [CPU]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	100.0%	1.5781s	23	0.0686s	0.0000s	0.5625s
Total	100.0%	1.5781s	23	0.0686s	0.0000s	0.5625s

SQL*Net message from client

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	100.0%	0.1050s	23	0.0045s	0.0001s	0.0056s
Total	100.0%	0.1050s	23	0.0045s	0.0001s	0.0056s

PARSE calls [CPU]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	100.0%	0.0312s	1	0.0312s	0.0312s	0.0312s
1029988163	0.0%	0.0000s	3	0.0000s	0.0000s	0.0000s
2217940283	0.0%	0.0000s	2	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0312s	6	0.0052s	0.0000s	0.0312s

reliable message

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	100.0%	0.0007s	4	0.0001s	0.0001s	0.0002s
Total	100.0%	0.0007s	4	0.0001s	0.0001s	0.0002s

enq: KO - fast object checkpoint

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	100.0%	0.0005s	4	0.0001s	0.0000s	0.0002s
Total	100.0%	0.0005s	4	0.0001s	0.0000s	0.0002s

SQL*Net message to client

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1120317097	81.5%	0.0001s	24	0.0000s	0.0000s	0.0000s
1029988163	13.9%	0.0000s	3	0.0000s	0.0000s	0.0000s
Cursor #8	4.6%	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0001s	28	0.0000s	0.0000s	0.0000s

EXEC calls [CPU]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1029988163	n/a %	0.0000s	3	0.0000s	0.0000s	0.0000s
1120317097	n/a %	0.0000s	1	0.0000s	0.0000s	0.0000s
2217940283	n/a %	0.0000s	2	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0000s	6	0.0000s	0.0000s	0.0000s

Session Call Graph

SQL Hash Value	Branch %	Total %	Self Seconds	Cumulative Seconds	Calls	Command Type
1120317097	100.0%	100.0%	55.2788s	55.2788s	25	SELECT
1029988163	100.0%	0.0%	0.0208s	0.0208s	6	PL/SQL EXECUTE
2217940283	100.0%	0.0%	0.0011s	0.0011s	4	ALTER SESSION

Events Histograms

SQL*Net message from client [idle]

value	- Distribution -	count
0.000128s		0
0.000256s		1
0.000512s		0
0.001024s		0
0.002048s		0
0.004096s		1
0.008192s		0
0.016384s		0
0.032768s		0
0.065536s		0
0.131072s		0
0.262144s		0
0.524288s		0
1.048576s		0
2.097152s		0
4.194304s		0
8.388608s		1
16.777216s		1
33.554432s		0
67.108864s		0
134.217728s		1
268.435456s		0

direct path read

value	- Distribution -	count
0.000002s		0
0.000004s		77
0.000008s		3
0.000016s		14
0.000032s		0
0.000064s		0
0.000128s		0
0.000256s		0
0.000512s		0
0.001024s		0
0.002048s		2
0.004096s		2
0.008192s		56
0.016384s		23
0.032768s		33
0.065536s		34
0.131072s		66
0.262144s		61
0.524288s		14
1.048576s		3
2.097152s		0

FETCH calls [CPU]

value	- Distribution -	count
0.000000s		19
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		0
0.001024s		0
0.002048s		0
0.004096s		0
0.008192s		0
0.016384s		0
0.032768s		0
0.065536s		1
0.131072s		0
0.262144s		2
0.524288s		1
1.048576s		0

SQL*Net message from client

value	- Distribution -	count
0.000064s		0
0.000128s		1
0.000256s		1
0.000512s		0
0.001024s		0
0.002048s		1
0.004096s		20
0.008192s		0

PARSE calls [CPU]

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		0
0.000128s		0
0.000256s		0
0.000512s		0
0.001024s		0
0.002048s		0
0.004096s		0
0.008192s		0
0.016384s		1
0.032768s		0

reliable message

value	- Distribution -	count
0.000064s		0
0.000128s		4
0.000256s		0

enq: KO - fast object checkpoint

value	- Distribution -	count
0.000004s		0
0.000008s		1
0.000016s		0
0.000032s		1
0.000064s		0
0.000128s		2
0.000256s		0

SQL*Net message to client

value	- Distribution -	count
0.000002s		0
0.000004s		27
0.000008s		1
0.000016s		0

EXEC calls [CPU]

value	- Distribution -	count
0.000000s		6

Session Read Statistics

Blocks Read per Call	% Time	Seconds	Calls	- Time per Call -
Blocks Read per Call	% Time	Seconds	Calls	Avg	Min	Max
64	97.6%	51.7691s	364	0.1422s	0.0000s	1.1906s
55	1.2%	0.6102s	8	0.0762s	0.0094s	0.1495s
49	1.1%	0.5581s	4	0.1395s	0.1045s	0.2049s
14	0.2%	0.0837s	4	0.0209s	0.0132s	0.0283s
19	0.0%	0.0000s	4	0.0000s	0.0000s	0.0000s
9	0.0%	0.0000s	4	0.0000s	0.0000s	0.0000s
Total	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s

Session Read Datafiles Statistics

Datafile #	% Time	Seconds	Calls	- Time per Call -
Datafile #	% Time	Seconds	Calls	Avg	Min	Max
1	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s
Total	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s

Session Enqueue Statistics

Enqueue Type Mode	% Time	Seconds	Calls	- Time per Call -
Enqueue Type Mode	% Time	Seconds	Calls	Avg	Min	Max
KO,6	100.0%	0.0005s	4	0.0001s	0.0000s	0.0002s
Total	100.0%	0.0005s	4	0.0001s	0.0000s	0.0002s

SQL Statements

SQL Hash Value: 1120317097 SQL Id: dn31b251cdbp9 uid: 5 depth: 0 optimizer mode: ALL_ROWS

Statement Text

SELECT o.owner, o.object_type, COUNT(*)
FROM all_my_objects o
GROUP BY o.object_type, o.owner
UNION ALL
SELECT NULL, o.object_type, COUNT(*)
FROM all_my_objects o
GROUP BY o.object_type
UNION ALL
SELECT o.owner, NULL, COUNT(*)
FROM all_my_objects o
GROUP BY o.owner
UNION ALL
SELECT NULL, NULL, COUNT(*)
FROM all_my_objects o

Statement Cumulative Statistics

Call	Cache Misses	Count	- Seconds -		Physical Reads	- Logical Reads -		Rows
Call	Cache Misses	Count	CPU	Elapsed	Physical Reads	Consistent	Current	Rows
Parse	1	1	0.0312s	0.0165s	0	0	0	0
Exec	0	1	0.0000s	0.0001s	0	0	0	0
Fetch		23	1.5781s	55.2621s	24,100	24,116	0	327
Total	1	25	1.6094s	55.2788s	24,100	24,116	0	327

Per Fch	0.0	1.1	0.0699s	2.4034s	1,047.8	1,048.5	0.0	14.2
Per Row	0.0	0.0	0.0049s	0.1690s	73.7	73.7	0.0	1.0

Statement Flat Profile

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
direct path read	96.9%	53.0213s	388	0.1367s	0.0000s	1.1906s
FETCH calls [CPU]	2.9%	1.5781s	23	0.0686s	0.0000s	0.5625s
SQL*Net message from client	0.2%	0.1050s	23	0.0045s	0.0001s	0.0056s
PARSE calls [CPU]	0.0%	0.0312s	1	0.0312s	0.0312s	0.0312s
SQL*Net message from client [idle]	0.0%	0.0046s	1	0.0046s	0.0046s	0.0046s
reliable message	0.0%	0.0007s	4	0.0001s	0.0001s	0.0002s
enq: KO - fast object checkpoint	0.0%	0.0005s	4	0.0001s	0.0000s	0.0002s
SQL*Net message to client	0.0%	0.0001s	24	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	0.0%	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	54.7416s

Statement Read Statistics

Blocks Read per Call	% Time	Seconds	Calls	- Time per Call -
Blocks Read per Call	% Time	Seconds	Calls	Avg	Min	Max
64	97.6%	51.7691s	364	0.1422s	0.0000s	1.1906s
55	1.2%	0.6102s	8	0.0762s	0.0094s	0.1495s
49	1.1%	0.5581s	4	0.1395s	0.1045s	0.2049s
14	0.2%	0.0837s	4	0.0209s	0.0132s	0.0283s
19	0.0%	0.0000s	4	0.0000s	0.0000s	0.0000s
9	0.0%	0.0000s	4	0.0000s	0.0000s	0.0000s
Total	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s

Statement Read Datafiles Statistics

Datafile #	% Time	Seconds	Calls	- Time per Call -
Datafile #	% Time	Seconds	Calls	Avg	Min	Max
1	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s
Total	100.0%	53.0213s	388	0.1367s	0.0000s	1.1906s

Statement Enqueue Statistics

Enqueue Type Mode	% Time	Seconds	Calls	- Time per Call -
Enqueue Type Mode	% Time	Seconds	Calls	Avg	Min	Max
KO,6	100.0%	0.0005s	4	0.0001s	0.0000s	0.0002s
Total	100.0%	0.0005s	4	0.0001s	0.0000s	0.0002s

Statement Plan

met 1 time

Rows	Row Source Operation	Object Id
327 258 411,120 38 411,120 30 411,120 1 411,120	UNION-ALL (cr=24,116 pr=24,100 pw=24,100 time=0.0000s) HASH GROUP BY (cr=6,029 pr=6,025 pw=6,025 time=0.0000s cost=1,657 size=12,105 card=807) TABLE ACCESS FULL ALL_MY_OBJECTS (cr=6,029 pr=6,025 pw=6,025 time=0.3749s cost=1,641 size=6,166,800 card=411,120) HASH GROUP BY (cr=6,029 pr=6,025 pw=6,025 time=0.0000s cost=1,657 size=342 card=38) TABLE ACCESS FULL ALL_MY_OBJECTS (cr=6,029 pr=6,025 pw=6,025 time=0.3091s cost=1,641 size=3,700,080 card=411,120) HASH GROUP BY (cr=6,029 pr=6,025 pw=6,025 time=0.0002s cost=1,655 size=180 card=30) TABLE ACCESS FULL ALL_MY_OBJECTS (cr=6,029 pr=6,025 pw=6,025 time=1.2125s cost=1,639 size=2,466,720 card=411,120) SORT AGGREGATE (cr=6,029 pr=6,025 pw=6,025 time=0.0000s) TABLE ACCESS FULL ALL_MY_OBJECTS (cr=6,029 pr=6,025 pw=6,025 time=0.3927s cost=1,639 size=0 card=411,120)	71632 71632 71632 71632

SQL Hash Value: 1029988163 SQL Id: 9babjv8yq8ru3 uid: 5 depth: 0 optimizer mode: ALL_ROWS

Statement Text

BEGIN DBMS_OUTPUT.GET_LINES(:LINES, :NUMLINES); END;

Statement Cumulative Statistics

Call	Cache Misses	Count	- Seconds -		Physical Reads	- Logical Reads -		Rows
Call	Cache Misses	Count	CPU	Elapsed	Physical Reads	Consistent	Current	Rows
Parse	0	3	0.0000s	0.0001s	0	0	0	0
Exec	0	3	0.0000s	0.0206s	0	0	0	3
Fetch		0	0.0000s	0.0000s	0	0	0	0
Total	0	6	0.0000s	0.0208s	0	0	0	3

Per Exe	0.0	2.0	0.0000s	0.0069s	0.0	0.0	0.0	1.0
Per Row	0.0	2.0	0.0000s	0.0069s	0.0	0.0	0.0	1.0

Statement Flat Profile

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
SQL*Net message from client [idle]	100.0%	225.5977s	3	75.1992s	12.9939s	187.5339s
SQL*Net message to client	0.0%	0.0000s	3	0.0000s	0.0000s	0.0000s
PARSE calls [CPU]	0.0%	0.0000s	3	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	0.0%	0.0000s	3	0.0000s	0.0000s	0.0000s
Total	100.0%	225.5977s

Statement Binds Statistics

total binds sets: 3 unique binds sets: 1

Sample Bind Set

met 3 times; elapsed time spent: 0.0208s

Name	Data Type	Value
LINES	UNKNOWN [123]	NULL
NUMLINES	NUMBER	###000010C1000000000000000000000000000000000

SQL Hash Value: 2217940283 SQL Id: 06nvwn223659v uid: 5 depth: 0 optimizer mode:

Statement Text

alter session set events '10046 trace name context off'

Statement Cumulative Statistics

Call	Cache Misses	Count	- Seconds -		Physical Reads	- Logical Reads -		Rows
Call	Cache Misses	Count	CPU	Elapsed	Physical Reads	Consistent	Current	Rows
Parse	0	2	0.0000s	0.0002s	0	0	0	0
Exec	0	2	0.0000s	0.0009s	0	0	0	0
Fetch		0	0.0000s	0.0000s	0	0	0	0
Total	0	4	0.0000s	0.0011s	0	0	0	0

Per Exe	0.0	2.0	0.0000s	0.0005s	0.0	0.0	0.0	0.0
Per Row	0.0	4.0	0.0000s	0.0011s	0.0	0.0	0.0	0.0

Statement Flat Profile

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
PARSE calls [CPU]	n/a %	0.0000s	2	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	n/a %	0.0000s	2	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0000s

SQL Hash Value: Cursor #8 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

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
SQL*Net message from client [idle]	97.6%	0.0002s	1	0.0002s	0.0002s	0.0002s
SQL*Net message to client	2.4%	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0002s

Generated by OraSRP v3.1.3 beta