sample_orasrp_trace.trc / Oracle Session Profiler

Session Summary

Instance Name: orcl
Node Name: moderne
Oracle Release: 10.2.0.4.0
Session ID: 129.11504
Total SQL Statements: 33 (5 user statements, 28 internal statements)
Total Transactions: 1 (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.0%	102.4680s	9	11.3853s	0.0009s	92.2635s
FETCH calls [CPU]	43.2%	92.0600s	2,074	0.0443s	0.0000s	91.7300s
unaccounted-for time	5.8%	12.4238s
SQL*Net message from client	2.4%	5.1759s	69	0.0750s	0.0010s	0.1691s
EXEC calls [CPU]	0.3%	0.6600s	1,282	0.0005s	-0.1300s	0.0500s
PARSE calls [CPU]	0.3%	0.5400s	148	0.0036s	0.0000s	0.3600s
log file sync	0.0%	0.0045s	1	0.0045s	0.0045s	0.0045s
latch: shared pool	0.0%	0.0020s	3	0.0006s	0.0001s	0.0015s
latch free	0.0%	0.0006s	1	0.0006s	0.0006s	0.0006s
SQL*Net message to client	0.0%	0.0005s	78	0.0000s	0.0000s	0.0000s
Total	100.0%	213.3355s

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
2280069326	90.0%	92.2635s	1	92.2635s	92.2635s	92.2635s
3150898423	5.8%	5.9592s	3	1.9864s	0.0009s	5.9498s
1029988163	4.0%	4.1063s	1	4.1063s	4.1063s	4.1063s
4175145872	0.1%	0.1153s	1	0.1153s	0.1153s	0.1153s
3159716790	0.0%	0.0142s	1	0.0142s	0.0142s	0.0142s
2 others	0.0%	0.0094s	2	0.0047s	0.0009s	0.0085s
Total	100.0%	102.4680s	9	11.3853s	0.0009s	92.2635s

FETCH calls [CPU]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
420206046	99.7%	91.7400s	35	2.6211s	0.0000s	91.7300s
3150898423	0.1%	0.1100s	516	0.0002s	0.0000s	0.0100s
4175145872	0.1%	0.0900s	35	0.0025s	0.0000s	0.0800s
864012087	0.0%	0.0300s	303	0.0000s	0.0000s	0.0100s
3159716790	0.0%	0.0300s	54	0.0005s	0.0000s	0.0100s
25 others	0.0%	0.0600s	1,131	0.0000s	0.0000s	0.0100s
Total	100.0%	92.0600s	2,074	0.0443s	0.0000s	91.7300s

SQL*Net message from client

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
4175145872	88.4%	4.5776s	34	0.1346s	0.0013s	0.1691s
420206046	11.6%	0.5983s	35	0.0170s	0.0010s	0.0273s
Total	100.0%	5.1759s	69	0.0750s	0.0010s	0.1691s

EXEC calls [CPU]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
3150898423	36.4%	0.2400s	516	0.0004s	0.0000s	0.0100s
4125641360	7.6%	0.0500s	34	0.0014s	0.0000s	0.0500s
3159716790	7.6%	0.0500s	25	0.0020s	0.0000s	0.0500s
1471956217	7.6%	0.0500s	14	0.0035s	0.0000s	0.0300s
2107929772	6.1%	0.0400s	14	0.0028s	0.0000s	0.0200s
28 others	34.8%	0.2300s	679	0.0003s	-0.1300s	0.0300s
Total	100.0%	0.6600s	1,282	0.0005s	-0.1300s	0.0500s

PARSE calls [CPU]

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
4175145872	66.7%	0.3600s	1	0.3600s	0.3600s	0.3600s
420206046	16.7%	0.0900s	1	0.0900s	0.0900s	0.0900s
1029988163	1.9%	0.0100s	5	0.0020s	0.0000s	0.0100s
2888850659	1.9%	0.0100s	28	0.0003s	0.0000s	0.0100s
4125641360	1.9%	0.0100s	5	0.0020s	0.0000s	0.0100s
28 others	11.1%	0.0600s	108	0.0005s	0.0000s	0.0100s
Total	100.0%	0.5400s	148	0.0036s	0.0000s	0.3600s

log file sync

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
Cursor #0	100.0%	0.0045s	1	0.0045s	0.0045s	0.0045s
Total	100.0%	0.0045s	1	0.0045s	0.0045s	0.0045s

latch: shared pool

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
1523794037	77.2%	0.0015s	1	0.0015s	0.0015s	0.0015s
2812844157	17.7%	0.0003s	1	0.0003s	0.0003s	0.0003s
1029988163	5.2%	0.0001s	1	0.0001s	0.0001s	0.0001s
Total	100.0%	0.0020s	3	0.0006s	0.0001s	0.0015s

latch free

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
420206046	100.0%	0.0006s	1	0.0006s	0.0006s	0.0006s
Total	100.0%	0.0006s	1	0.0006s	0.0006s	0.0006s

SQL*Net message to client

SQL Hash Value	% Time	Seconds	Calls	- Time per Call -
SQL Hash Value	% Time	Seconds	Calls	Avg	Min	Max
4175145872	50.6%	0.0002s	35	0.0000s	0.0000s	0.0000s
420206046	40.4%	0.0002s	36	0.0000s	0.0000s	0.0000s
1029988163	7.5%	0.0000s	5	0.0000s	0.0000s	0.0000s
3150898423	0.8%	0.0000s	1	0.0000s	0.0000s	0.0000s
864012087	0.8%	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0005s	78	0.0000s	0.0000s	0.0000s

Session Call Graph

SQL Hash Value	Branch %	Total %	Self Seconds	Cumulative Seconds	Calls	Similar	Command Type
420206046 2584065658 864012087 3150898423 3159716790 3150898423 864012087 4125641360 3150898423 864012087 2512561537 3150898423 864012087 3150898423 2888850659 3150898423 864012087 3607805727 864012087 3159716790 3150898423 2512561537 4125641360 2280069326 3849548163 3150898423 864012087 1173719687 3150898423 864012087 1471956217 3150898423 864012087 2107929772 3150898423 864012087 2280069326 3150898423 864012087 4139184264 854877822 864012087	99.3% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0%	99.0% 0.1% 0.0% 0.0% 0.1% 0.0% 0.0% 0.1% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0%	103.6707s 0.0792s 0.0477s 0.0219s 0.0636s 0.0408s 0.0156s 0.0495s 0.0530s 0.0074s 0.0215s 0.0189s 0.0032s 0.0430s 0.0204s 0.0126s 0.0053s 0.0219s 0.0081s 0.0017s 0.0012s 0.0011s 0.0005s 0.0003s 0.0237s 0.0067s 0.0035s 0.0178s 0.0040s 0.0028s 0.0120s 0.0342s 0.0033s 0.0143s 0.0070s 0.0057s 0.0096s 0.0034s 0.0020s 0.0069s 0.0062s 0.0054s	104.3411s 0.1489s 0.0477s 0.0219s 0.1201s 0.0408s 0.0156s 0.1100s 0.0530s 0.0074s 0.0437s 0.0189s 0.0032s 0.0430s 0.0384s 0.0126s 0.0053s 0.0351s 0.0081s 0.0017s 0.0012s 0.0011s 0.0005s 0.0003s 0.0283s 0.0067s 0.0035s 0.0246s 0.0040s 0.0028s 0.0239s 0.0342s 0.0033s 0.0203s 0.0070s 0.0057s 0.0151s 0.0034s 0.0020s 0.0069s 0.0062s 0.0054s	37 7 76 70 24 104 80 13 139 31 69 52 22 120 36 24 36 9 40 3 6 26 2 3 184 20 20 15 10 10 26 105 19 76 24 34 28 14 14 78 21 30	0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0	SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT
4175145872 1471956217 3150898423 864012087 3150898423 3849548163 3150898423 864012087 2107929772 3150898423 864012087 3159716790 3150898423 864012087 4125641360 3150898423 864012087 2888850659 3150898423 864012087 864012087 3607805727 864012087 3159716790 3150898423 2512561537 4125641360 2280069326 854877822 2512561537 3150898423 864012087 4139184264 2584065658 864012087 3150898423 2280069326 3150898423 864012087 1173719687 3150898423 864012087 1682066536	50.2% 4.3% 3.4% 0.3% 3.7% 2.4% 0.7% 0.4% 1.9% 0.7% 0.6% 1.4% 4.0% 1.5% 1.1% 5.3% 0.7% 0.8% 1.3% 0.5% 0.6% 0.5% 0.8% 0.2% 0.1% 0.1% 0.0% 0.0% 0.5% 0.3% 1.9% 0.3% 0.3% 0.3% 4.7% 2.2% 0.2% 0.3% 0.2% 0.2% 0.4% 0.3% 0.1%	0.7% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0%	0.5063s 0.0434s 0.0342s 0.0033s 0.0372s 0.0243s 0.0067s 0.0035s 0.0186s 0.0070s 0.0057s 0.0137s 0.0408s 0.0156s 0.0108s 0.0530s 0.0074s 0.0084s 0.0126s 0.0053s 0.0062s 0.0054s 0.0081s 0.0017s 0.0012s 0.0011s 0.0005s 0.0003s 0.0053s 0.0034s 0.0189s 0.0032s 0.0032s 0.0029s 0.0477s 0.0219s 0.0023s 0.0034s 0.0020s 0.0023s 0.0040s 0.0028s 0.0013s	0.7337s 0.0690s 0.0342s 0.0033s 0.0372s 0.0299s 0.0067s 0.0035s 0.0253s 0.0070s 0.0057s 0.0137s 0.0408s 0.0156s 0.0108s 0.0530s 0.0074s 0.0084s 0.0126s 0.0053s 0.0062s 0.0054s 0.0081s 0.0017s 0.0012s 0.0011s 0.0005s 0.0003s 0.0053s 0.0034s 0.0189s 0.0032s 0.0032s 0.0029s 0.0477s 0.0219s 0.0023s 0.0034s 0.0020s 0.0023s 0.0040s 0.0028s 0.0013s	37 26 105 19 120 184 20 20 76 24 34 24 104 80 15 139 31 48 24 36 38 9 40 3 6 26 2 3 27 69 52 22 78 15 76 70 28 14 14 15 10 10 3	0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0	SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT
1029988163 2812844157 3150898423 864012087 3159716790 854877822 2280069326 2512561537 4125641360 2584065658 1389591971 3150898423 864012087 854877822 3993603298 3150898423 864012087 3159716790 4125641360 2512561537 2584065658 854877822 2280069326 3275427458 3150898423 864012087 2328831744 864012087 3150898423 3159716790 4125641360 854877822 2584065658 2512561537 2280069326 4260389146 3150898423 864012087 3159716790 2512561537 4125641360 2584065658 2280069326 854877822 1115215392 864012087 3150898423 3159716790 4125641360 2512561537 2280069326 2584065658 854877822 1684122946 3150898423 864012087 3159716790 4125641360 2512561537 2280069326 2584065658 854877822 1343089354 864012087 3150898423 3159716790 4125641360 2512561537 2584065658 2280069326 854877822 1523794037 3159716790 864012087 4125641360 2512561537 2280069326 2334772408 864012087 3159716790 4125641360 3150898423 2512561537 2584065658 2280069326 854877822 2201826955 3159716790 864012087 4125641360 2584065658 2512561537 4125641360 3150898423 864012087 1682066536 854877822	-18.6% 13.3% 9.3% 0.8% 0.3% 0.2% 0.1% 0.1% 0.0% 0.0% 10.7% 1.5% 1.3% 0.2% 7.4% 2.4% 0.6% 0.6% 0.1% 0.1% 0.1% 0.1% 0.1% 4.5% 3.3% 1.1% 5.8% 0.9% 0.6% 0.4% 0.2% 0.1% 0.1% 0.1% 0.1% 5.9% 0.5% 0.5% 0.4% 0.4% 0.1% 0.1% 0.0% 0.0% 4.6% 1.3% 0.3% 0.2% 0.1% 0.0% 0.0% 0.0% 0.0% 4.4% 0.4% 0.4% 0.2% 0.1% 0.0% 0.0% 0.0% 0.0% 3.3% 0.7% 0.3% 0.2% 0.1% 0.0% 0.0% 0.0% 0.0% 2.2% 0.3% 0.2% 0.0% 0.0% 0.0% 1.9% 0.2% 0.2% 0.1% 0.1% 0.0% 0.0% 0.0% 0.0% 2.0% 0.2% 0.2% 0.1% 0.1% 0.1% 1.6% 13.4% 1.9% 0.7% 0.0%	0.3% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0% 0.0%	-0.0734s 0.0526s 0.0366s 0.0031s 0.0013s 0.0008s 0.0005s 0.0004s 0.0003s 0.0002s 0.0423s 0.0060s 0.0052s 0.0006s 0.0292s 0.0093s 0.0022s 0.0022s 0.0005s 0.0005s 0.0004s 0.0003s 0.0003s 0.0177s 0.0131s 0.0044s 0.0227s 0.0036s 0.0022s 0.0016s 0.0007s 0.0005s 0.0005s 0.0004s 0.0003s 0.0233s 0.0019s 0.0018s 0.0017s 0.0016s 0.0004s 0.0004s 0.0003s 0.0002s 0.0182s 0.0053s 0.0013s 0.0007s 0.0003s 0.0003s 0.0003s 0.0002s 0.0002s 0.0172s 0.0017s 0.0016s 0.0007s 0.0004s 0.0003s 0.0002s 0.0002s 0.0001s 0.0131s 0.0025s 0.0012s 0.0006s 0.0004s 0.0003s 0.0002s 0.0002s 0.0002s 0.0086s 0.0010s 0.0008s 0.0003s 0.0003s 0.0002s 0.0075s 0.0008s 0.0006s 0.0004s 0.0003s 0.0003s 0.0003s 0.0002s 0.0002s 0.0078s 0.0009s 0.0008s 0.0004s 0.0004s 0.0003s 0.0063s 0.0530s 0.0074s 0.0026s 0.0001s	0.3344s 0.0961s 0.0366s 0.0031s 0.0013s 0.0008s 0.0005s 0.0004s 0.0003s 0.0002s 0.0543s 0.0060s 0.0052s 0.0006s 0.0452s 0.0093s 0.0022s 0.0022s 0.0005s 0.0005s 0.0004s 0.0003s 0.0003s 0.0353s 0.0131s 0.0044s 0.0329s 0.0036s 0.0022s 0.0016s 0.0007s 0.0005s 0.0005s 0.0004s 0.0003s 0.0318s 0.0019s 0.0018s 0.0017s 0.0016s 0.0004s 0.0004s 0.0003s 0.0002s 0.0272s 0.0053s 0.0013s 0.0007s 0.0003s 0.0003s 0.0003s 0.0002s 0.0002s 0.0227s 0.0017s 0.0016s 0.0007s 0.0004s 0.0003s 0.0002s 0.0002s 0.0001s 0.0192s 0.0025s 0.0012s 0.0006s 0.0004s 0.0003s 0.0002s 0.0002s 0.0002s 0.0115s 0.0010s 0.0008s 0.0003s 0.0003s 0.0002s 0.0110s 0.0008s 0.0006s 0.0004s 0.0003s 0.0003s 0.0003s 0.0002s 0.0002s 0.0110s 0.0009s 0.0008s 0.0004s 0.0004s 0.0003s 0.0063s 0.0530s 0.0074s 0.0026s 0.0001s	10 35 104 22 4 6 9 11 2 2 20 21 25 4 28 28 8 3 2 6 2 2 4 3 42 30 43 13 7 4 3 3 3 9 7 39 8 12 3 8 2 2 6 2 34 12 6 3 2 8 6 2 2 62 8 12 3 2 8 6 2 2 6 8 2 3 2 6 2 3 2 12 3 6 2 6 3 3 6 3 2 2 5 2 4 2 3 2 6 2 2 5 22 139 31 5 2	0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0	PL/SQL EXECUTE SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT SELECT
1078465550	100.0%	0.0%	0.0138s	0.0138s	2	0	ALTER SESSION
74541589	100.0%	0.0%	0.0003s	0.0003s	2	0	ALTER SESSION

Events Histograms

SQL*Net message from client [idle]

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

FETCH calls [CPU]

value	- Distribution -	count
0.000000s		2,047
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		25
0.016384s		0
0.032768s		0
0.065536s		1
0.131072s		0
0.262144s		0
0.524288s		0
1.048576s		0
2.097152s		0
4.194304s		0
8.388608s		0
16.777216s		0
33.554432s		0
67.108864s		1
134.217728s		0

SQL*Net message from client

value	- Distribution -	count
0.000512s		0
0.001024s		3
0.002048s		0
0.004096s		0
0.008192s		0
0.016384s		33
0.032768s		0
0.065536s		4
0.131072s		29
0.262144s		0

EXEC calls [CPU]

value	- Distribution -	count
-0.131072s		0
-0.065536s		1
-0.032768s		0
-0.016384s		0
-0.008192s		0
-0.004096s		0
-0.002048s		0
-0.001024s		0
-0.000512s		0
-0.000256s		0
-0.000128s		0
-0.000064s		0
-0.000032s		0
-0.000016s		0
-0.000008s		0
-0.000004s		0
-0.000002s		0
-0.000001s		0
0.000000s		1,220
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		51
0.016384s		8
0.032768s		2
0.065536s		0

PARSE calls [CPU]

value	- Distribution -	count
0.000000s		137
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		9
0.016384s		0
0.032768s		0
0.065536s		1
0.131072s		0
0.262144s		1
0.524288s		0

log file sync

value	- Distribution -	count
0.002048s		0
0.004096s		1
0.008192s		0

latch: shared pool

value	- Distribution -	count
0.000032s		0
0.000064s		1
0.000128s		0
0.000256s		1
0.000512s		0
0.001024s		1
0.002048s		0

latch free

value	- Distribution -	count
0.000256s		0
0.000512s		1
0.001024s		0

SQL*Net message to client

value	- Distribution -	count
0.000001s		0
0.000002s		14
0.000004s		33
0.000008s		30
0.000016s		1
0.000032s		0

Session Latch Statistics

Latch #	% Time	Seconds	Calls	- Time per Call -
Latch #	% Time	Seconds	Calls	Avg	Min	Max
289	100.0%	0.0006s	1	0.0006s	0.0006s	0.0006s
Total	100.0%	0.0006s	1	0.0006s	0.0006s	0.0006s

SQL Statements

SQL Hash Value: 420206046 uid: 171 depth: 0 optimizer mode: ALL_ROWS

Statement Text

SELECT   grp_auth_id, grp_loc_nm location_name, grp_nm grp_name,
         resrc_type_nm resource_type
    FROM acme.grp_resource_trans INNER JOIN acme.grp_auth USING (grp_auth_id)
         INNER JOIN acme.grp_loc USING (grp_loc_id)
         INNER JOIN acme.resource_type USING (resrc_type_id)
   WHERE (grp_auth_id IN (
                SELECT DISTINCT (grp_auth_id)
                           FROM acme.grp_resource_trans
                       GROUP BY grp_auth_id
                         HAVING COUNT
                                   
                                     (grp_resource_trans.grp_resrc_actual_trans_id
                                   ) > 1)
         )
      OR (grp_auth_id IN (
             SELECT   raft.grp_auth_id
                 FROM resource_app_func_trans raft,
                      resource_app_func raf,
                      resource_app ra
                WHERE raft.resrc_app_func_id = raf.resrc_app_func_id
                  AND raf.resrc_app_id = ra.resrc_app_id
             GROUP BY raft.grp_auth_id
               HAVING (COUNT (DISTINCT (ra.app_nm)) > 1))
         )
ORDER BY grp_nm, grp_loc_nm

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.6300s	0.7946s	0	2,158	64	0
Exec	0	1	0.0000s	0.0002s	0	0	0	0
Fetch		35	91.7400s	103.5463s	0	182,175	0	506
Total	1	37	92.3700s	104.3411s	0	184,333	64	506

Per Fch	0.0	1.1	2.6391s	2.9812s	0.0	5,266.7	1.8	14.5
Per Row	0.0	0.0	0.1825s	0.2062s	0.0	364.3	0.1	1.0

Statement Self 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.0900s	0.1242s	0	0	0	0
Exec	0	1	0.0000s	0.0002s	0	0	0	0
Fetch		35	91.7400s	103.5463s	0	182,175	0	506
Total	1	37	91.8300s	103.6707s	0	182,175	0	506

Per Fch	0.0	1.1	2.6237s	2.9620s	0.0	5,205.0	0.0	14.5
Per Row	0.0	0.0	0.1815s	0.2049s	0.0	360.0	0.0	1.0

Statement Flat Profile

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
FETCH calls [CPU]	99.3%	91.7400s	35	2.6211s	0.0000s	91.7300s
SQL*Net message from client	0.6%	0.5983s	35	0.0170s	0.0010s	0.0273s
PARSE calls [CPU]	0.1%	0.0900s	1	0.0900s	0.0900s	0.0900s
latch free	0.0%	0.0006s	1	0.0006s	0.0006s	0.0006s
SQL*Net message to client	0.0%	0.0002s	36	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	0.0%	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	92.4292s

Statement Latch Statistics

Latch #	% Time	Seconds	Calls	- Time per Call -
Latch #	% Time	Seconds	Calls	Avg	Min	Max
289	100.0%	0.0006s	1	0.0006s	0.0006s	0.0006s
Total	100.0%	0.0006s	1	0.0006s	0.0006s	0.0006s

Statement Plan

met 1 time

Rows	Row Source Operation	Object Id
506 506 3,370 9 3,370 3,370 10 3,370 50,103 196 9,347,487 10,473,960 48 1,211,651 6,225,856 2,780,960 32,032 32,032 32,032 32,032 2,780,960 6,225,856	SORT ORDER BY (cr=182,175 pr=0 pw=0 time=103.5320s) FILTER (cr=182,175 pr=0 pw=0 time=103.5187s) HASH JOIN (cr=1,407 pr=0 pw=0 time=0.2251s) TABLE ACCESS FULL GRP_LOC (cr=7 pr=0 pw=0 time=0.0002s) HASH JOIN (cr=1,400 pr=0 pw=0 time=0.1903s) HASH JOIN (cr=23 pr=0 pw=0 time=0.0117s) TABLE ACCESS FULL RESOURCE_TYPE (cr=7 pr=0 pw=0 time=0.0001s) TABLE ACCESS FULL GRP_RESOURCE_TRANS (cr=16 pr=0 pw=0 time=0.0000s) TABLE ACCESS FULL GRP_AUTH (cr=1,377 pr=0 pw=0 time=0.0523s) FILTER (cr=49,728 pr=0 pw=0 time=38.4600s) HASH GROUP BY (cr=49,728 pr=0 pw=0 time=32.5669s) TABLE ACCESS FULL GRP_RESOURCE_TRANS (cr=49,728 pr=0 pw=0 time=0.3296s) FILTER (cr=131,040 pr=0 pw=0 time=64.6742s) SORT GROUP BY (cr=131,040 pr=0 pw=0 time=63.1180s) HASH JOIN (cr=131,040 pr=0 pw=0 time=33.7654s) HASH JOIN (cr=84,448 pr=0 pw=0 time=12.4894s) VIEW index$_join$_011 (cr=17,472 pr=0 pw=0 time=2.3456s) HASH JOIN (cr=17,472 pr=0 pw=0 time=2.3003s) INDEX FAST FULL SCAN APP_NM_X0 (cr=8,736 pr=0 pw=0 time=0.2468s) INDEX FAST FULL SCAN RESOURCE_APP_PK (cr=8,736 pr=0 pw=0 time=0.2312s) TABLE ACCESS FULL RESOURCE_APP_FUNC (cr=66,976 pr=0 pw=0 time=0.2075s) TABLE ACCESS FULL RESOURCE_APP_FUNC_TRANS (cr=46,592 pr=0 pw=0 time=0.3591s)	75141 75178 75143 75134 75143 75153 75152 75154 75156

SQL Hash Value: 4175145872 uid: 171 depth: 0 optimizer mode: ALL_ROWS

Statement Text

SELECT   grp_auth_id, grp_loc_nm location_name, grp_nm grp_name,
         resrc_type_nm resource_type
    FROM acme.grp_resource_trans INNER JOIN acme.grp_auth USING (grp_auth_id)
         INNER JOIN acme.grp_loc USING (grp_loc_id)
         INNER JOIN acme.resource_type USING (resrc_type_id)
   WHERE (grp_auth_id IN (
                SELECT DISTINCT (grp_auth_id)
                           FROM acme.grp_resource_trans
                       GROUP BY grp_auth_id
                         HAVING COUNT
                                   
                                     (grp_resource_trans.grp_resrc_actual_trans_id
                                   ) > 1)
         )
UNION
SELECT   grp_auth_id, grp_loc_nm location_name, grp_nm grp_name,
         resrc_type_nm resource_type
    FROM acme.grp_resource_trans INNER JOIN acme.grp_auth USING (grp_auth_id)
         INNER JOIN acme.grp_loc USING (grp_loc_id)
         INNER JOIN acme.resource_type USING (resrc_type_id)
    WHERE (grp_auth_id IN (
             SELECT   raft.grp_auth_id
                 FROM resource_app_func_trans raft,
                      resource_app_func raf,
                      resource_app ra
                WHERE raft.resrc_app_func_id = raf.resrc_app_func_id
                  AND raf.resrc_app_id = ra.resrc_app_id
             GROUP BY raft.grp_auth_id
               HAVING (COUNT (DISTINCT (ra.app_nm)) > 1))
         )
ORDER BY 1, 2

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.5700s	0.6536s	0	1,112	34	0
Exec	0	1	0.0000s	0.0007s	0	0	0	0
Fetch		35	0.0900s	0.0793s	0	699	0	506
Total	1	37	0.6600s	0.7337s	0	1,811	34	506

Per Fch	0.0	1.1	0.0188s	0.0209s	0.0	51.7	1.0	14.5
Per Row	0.0	0.0	0.0013s	0.0014s	0.0	3.6	0.0	1.0

Statement Self 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.3600s	0.4262s	0	0	0	0
Exec	0	1	0.0000s	0.0007s	0	0	0	0
Fetch		35	0.0900s	0.0793s	0	699	0	506
Total	1	37	0.4500s	0.5063s	0	699	0	506

Per Fch	0.0	1.1	0.0128s	0.0144s	0.0	20.0	0.0	14.5
Per Row	0.0	0.0	0.0008s	0.0010s	0.0	1.4	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	89.0%	4.5776s	34	0.1346s	0.0013s	0.1691s
PARSE calls [CPU]	7.0%	0.3600s	1	0.3600s	0.3600s	0.3600s
SQL*Net message from client [idle]	2.2%	0.1153s	1	0.1153s	0.1153s	0.1153s
FETCH calls [CPU]	1.7%	0.0900s	35	0.0025s	0.0000s	0.0800s
SQL*Net message to client	0.0%	0.0002s	35	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	0.0%	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	5.1432s

Statement Plan

met 1 time

Rows	Row Source Operation	Object Id
506 506 458 458 458 196 196 196 3,108 3,370 196 196 3,370 9 10 48 48 48 48 48 48 417 2,138 955 11 11 11 11 955 2,138 48 48 3,370 48 48 48 48	SORT UNIQUE (cr=699 pr=0 pw=0 time=0.0757s) UNION-ALL (cr=699 pr=0 pw=0 time=0.0729s) HASH JOIN (cr=440 pr=0 pw=0 time=0.0420s) HASH JOIN (cr=433 pr=0 pw=0 time=0.0395s) HASH JOIN (cr=426 pr=0 pw=0 time=0.0373s) NESTED LOOPS (cr=410 pr=0 pw=0 time=0.0217s) VIEW VW_NSO_1 (cr=16 pr=0 pw=0 time=0.0177s) FILTER (cr=16 pr=0 pw=0 time=0.0175s) HASH GROUP BY (cr=16 pr=0 pw=0 time=0.0162s) TABLE ACCESS FULL GRP_RESOURCE_TRANS (cr=16 pr=0 pw=0 time=0.0001s) TABLE ACCESS BY INDEX ROWID GRP_AUTH (cr=394 pr=0 pw=0 time=0.0036s) INDEX UNIQUE SCAN GRP_AUTH_PK (cr=198 pr=0 pw=0 time=0.0017s) TABLE ACCESS FULL GRP_RESOURCE_TRANS (cr=16 pr=0 pw=0 time=0.0001s) TABLE ACCESS FULL GRP_LOC (cr=7 pr=0 pw=0 time=0.0001s) TABLE ACCESS FULL RESOURCE_TYPE (cr=7 pr=0 pw=0 time=0.0000s) NESTED LOOPS (cr=259 pr=0 pw=0 time=0.0299s) NESTED LOOPS (cr=209 pr=0 pw=0 time=0.0295s) HASH JOIN (cr=159 pr=0 pw=0 time=0.0290s) NESTED LOOPS (cr=143 pr=0 pw=0 time=0.0221s) VIEW VW_NSO_2 (cr=45 pr=0 pw=0 time=0.0214s) FILTER (cr=45 pr=0 pw=0 time=0.0214s) SORT GROUP BY (cr=45 pr=0 pw=0 time=0.0209s) HASH JOIN (cr=45 pr=0 pw=0 time=0.0137s) HASH JOIN (cr=29 pr=0 pw=0 time=0.0041s) VIEW index$_join$_018 (cr=6 pr=0 pw=0 time=0.0017s) HASH JOIN (cr=6 pr=0 pw=0 time=0.0017s) INDEX FAST FULL SCAN APP_NM_X0 (cr=3 pr=0 pw=0 time=0.0001s) INDEX FAST FULL SCAN RESOURCE_APP_PK (cr=3 pr=0 pw=0 time=0.0000s) TABLE ACCESS FULL RESOURCE_APP_FUNC (cr=23 pr=0 pw=0 time=0.0000s) TABLE ACCESS FULL RESOURCE_APP_FUNC_TRANS (cr=16 pr=0 pw=0 time=0.0022s) TABLE ACCESS BY INDEX ROWID GRP_AUTH (cr=98 pr=0 pw=0 time=0.0006s) INDEX UNIQUE SCAN GRP_AUTH_PK (cr=50 pr=0 pw=0 time=0.0002s) TABLE ACCESS FULL GRP_RESOURCE_TRANS (cr=16 pr=0 pw=0 time=0.0000s) TABLE ACCESS BY INDEX ROWID GRP_LOC (cr=50 pr=0 pw=0 time=0.0003s) INDEX UNIQUE SCAN GRP_LOC_PK (cr=2 pr=0 pw=0 time=0.0001s) TABLE ACCESS BY INDEX ROWID RESOURCE_TYPE (cr=50 pr=0 pw=0 time=0.0003s) INDEX UNIQUE SCAN RESOURCE_TYPE_PK (cr=2 pr=0 pw=0 time=0.0001s)	75143 75134 75135 75143 75141 75178 75153 75152 75154 75156 75134 75135 75143 75141 75142 75178 75179

SQL Hash Value: 1078465550 uid: 171 depth: 0 optimizer mode:

Statement Text

alter session set statistics_level=all

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	1	0.0000s	0.0023s	0	0	0	0
Exec	0	1	0.0000s	0.0114s	0	0	0	0
Fetch		0	0.0000s	0.0000s	0	0	0	0
Total	0	2	0.0000s	0.0138s	0	0	0	0

Per Exe	0.0	2.0	0.0000s	0.0138s	0.0	0.0	0.0	0.0
Per Row	0.0	2.0	0.0000s	0.0138s	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	1	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	n/a %	0.0000s	1	0.0000s	0.0000s	0.0000s
Total	100.0%	0.0000s

SQL Hash Value: 74541589 uid: 171 depth: 0 optimizer mode:

Statement Text

alter session set max_dump_file_size=unlimited

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	1	0.0000s	0.0002s	0	0	0	0
Exec	0	1	0.0000s	0.0000s	0	0	0	0
Fetch		0	0.0000s	0.0000s	0	0	0	0
Total	0	2	0.0000s	0.0003s	0	0	0	0

Per Exe	0.0	2.0	0.0000s	0.0003s	0.0	0.0	0.0	0.0
Per Row	0.0	2.0	0.0000s	0.0003s	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	1	0.0000s	0.0000s	0.0000s
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

Event Name	% Time	Seconds	Calls	- Time per Call -
Event Name	% Time	Seconds	Calls	Avg	Min	Max
log file sync	100.0%	0.0045s	1	0.0045s	0.0045s	0.0045s
Total	100.0%	0.0045s

SQL Hash Value: 1029988163 uid: 171 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	2	5	0.1200s	0.1645s	0	448	2	0
Exec	2	5	0.1100s	0.1699s	0	421	12	5
Fetch		0	0.0000s	0.0000s	0	0	0	0
Total	4	10	0.2300s	0.3344s	0	869	14	5

Per Exe	0.8	2.0	0.0460s	0.0668s	0.0	173.8	2.8	1.0
Per Row	0.8	2.0	0.0460s	0.0668s	0.0	173.8	2.8	1.0

Statement Self Statistics

Call	Cache Misses	Count	- Seconds -		Physical Reads	- Logical Reads -		Rows
Call	Cache Misses	Count	CPU	Elapsed	Physical Reads	Consistent	Current	Rows
Parse	2	5	0.0100s	0.0257s	0	0	0	0
Exec	2	5	-0.1200s	-0.0992s	0	-482	-24	5
Fetch		0	0.0000s	0.0000s	0	0	0	0
Total	4	10	-0.1100s	-0.0734s	0	-482	-24	5

Per Exe	0.8	2.0	-0.0220s	-0.0146s	0.0	-96.4	-4.8	1.0
Per Row	0.8	2.0	-0.0220s	-0.0146s	0.0	-96.4	-4.8	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]	102.7%	4.1063s	1	4.1063s	4.1063s	4.1063s
PARSE calls [CPU]	0.3%	0.0100s	5	0.0020s	0.0000s	0.0100s
latch: shared pool	0.0%	0.0001s	1	0.0001s	0.0001s	0.0001s
SQL*Net message to client	0.0%	0.0000s	5	0.0000s	0.0000s	0.0000s
EXEC calls [CPU]	-3.0%	-0.1200s	5	-0.0240s	-0.1300s	0.0100s
Total	100.0%	3.9964s

Statement Binds Statistics

total binds sets: 6 unique binds sets: 2

Sample Bind Set

met 1 time; elapsed time spent: 0.0000s

Name	Data Type	Value
LINES	NUMBER	18
NUMLINES	NUMBER	4

Generated by OraSRP v3.2.0 beta