PL/SQL Elapsed Time (microsecs) Analysis

2028645 microsecs (elapsed time) & 37 function calls

The PL/SQL Hierarchical Profiler produces a collection of reports that present information derived from the profiler's output log in a variety of formats. The following reports have been found to be the most generally useful as starting points for browsing:

In addition, the following reports are also available:

Function Elapsed Time (microsecs) Data sorted by Total Subtree Elapsed Time (microsecs) for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Total Subtree Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2028645 100% 19 0.0% 2028626 100% 2 5.4% __plsql_vm
2028626 100% 41 0.0% 2028585 100% 2 5.4% __anonymous_block
2028585 100% 926 0.0% 2027659 100% 1 2.7% __anonymous_block.P_MAIN (Line 6)
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% SYS.DBMS_LOCK.SLEEP (Line 197)
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% __anonymous_block.P_DOSOMETHING (Line 2)
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
7 0.0% 7 0.0% 0 0.0% 1 2.7% SYS.DBMS_LOCK.__pkg_init
0 0.0% 0 0.0% 0 0.0% 1 2.7% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs) for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Cum% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2023801 99.8% 2023801 99.8% 99.8% 0 0.0% 15 40.5% SYS.DBMS_LOCK.SLEEP (Line 197)
3791 0.2% 3791 0.2% 100% 0 0.0% 1 2.7% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
2028585 100% 926 0.0% 100% 2027659 100% 1 2.7% __anonymous_block.P_MAIN (Line 6)
1523488 75.1% 60 0.0% 100% 1523428 75.1% 14 37.8% __anonymous_block.P_DOSOMETHING (Line 2)
2028626 100% 41 0.0% 100% 2028585 100% 2 5.4% __anonymous_block
2028645 100% 19 0.0% 100% 2028626 100% 2 5.4% __plsql_vm
7 0.0% 7 0.0% 100% 0 0.0% 1 2.7% SYS.DBMS_LOCK.__pkg_init
0 0.0% 0 0.0% 100% 0 0.0% 1 2.7% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
SQL ID Elapsed Time (microsecs) Data sorted by SQL ID for 'hprof_intro'

SQL ID Elapsed Time (microsecs) Data sorted by SQL ID

2028645 microsecs (elapsed time) & 37 function calls

SQL ID SQL TEXT Function Ind% Calls Ind%
4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP0 0.0% 1 2.7%
Function Elapsed Time (microsecs) Data sorted by Function Name for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Function Name

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2028626 100% 41 0.0% 2028585 100% 2 5.4% __anonymous_block
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% __anonymous_block.P_DOSOMETHING (Line 2)
2028585 100% 926 0.0% 2027659 100% 1 2.7% __anonymous_block.P_MAIN (Line 6)
2028645 100% 19 0.0% 2028626 100% 2 5.4% __plsql_vm
0 0.0% 0 0.0% 0 0.0% 1 2.7% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% SYS.DBMS_LOCK.SLEEP (Line 197)
7 0.0% 7 0.0% 0 0.0% 1 2.7% SYS.DBMS_LOCK.__pkg_init
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
Function Elapsed Time (microsecs) Data sorted by Total Descendants Elapsed Time (microsecs) for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Total Descendants Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2028645 100% 19 0.0% 2028626 100% 2 5.4% __plsql_vm
2028626 100% 41 0.0% 2028585 100% 2 5.4% __anonymous_block
2028585 100% 926 0.0% 2027659 100% 1 2.7% __anonymous_block.P_MAIN (Line 6)
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% __anonymous_block.P_DOSOMETHING (Line 2)
0 0.0% 0 0.0% 0 0.0% 1 2.7% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
7 0.0% 7 0.0% 0 0.0% 1 2.7% SYS.DBMS_LOCK.__pkg_init
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% SYS.DBMS_LOCK.SLEEP (Line 197)
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
Function Elapsed Time (microsecs) Data sorted by Total Function Call Count for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Total Function Call Count

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Cum% Function Name SQL ID SQL TEXT
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% 40.5% SYS.DBMS_LOCK.SLEEP (Line 197)
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% 78.4% __anonymous_block.P_DOSOMETHING (Line 2)
2028626 100% 41 0.0% 2028585 100% 2 5.4% 83.8% __anonymous_block
2028645 100% 19 0.0% 2028626 100% 2 5.4% 89.2% __plsql_vm
0 0.0% 0 0.0% 0 0.0% 1 2.7% 91.9% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
2028585 100% 926 0.0% 2027659 100% 1 2.7% 94.6% __anonymous_block.P_MAIN (Line 6)
7 0.0% 7 0.0% 0 0.0% 1 2.7% 97.3% SYS.DBMS_LOCK.__pkg_init
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% 100% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
Function Elapsed Time (microsecs) Data sorted by Mean Subtree Elapsed Time (microsecs) for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Mean Subtree Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Mean Subtree Mean Function Mean Descendants Function Name SQL ID SQL TEXT
2028585 100% 926 0.0% 2027659 100% 1 2.7% 2028586 927 2027660 __anonymous_block.P_MAIN (Line 6)
2028645 100% 19 0.0% 2028626 100% 2 5.4% 1014323 10 1014313 __plsql_vm
2028626 100% 41 0.0% 2028585 100% 2 5.4% 1014313 21 1014293 __anonymous_block
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% 134920 134920 0 SYS.DBMS_LOCK.SLEEP (Line 197)
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% 108821 4 108816 __anonymous_block.P_DOSOMETHING (Line 2)
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% 3792 3792 0 __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
7 0.0% 7 0.0% 0 0.0% 1 2.7% 8 8 0 SYS.DBMS_LOCK.__pkg_init
0 0.0% 0 0.0% 0 0.0% 1 2.7% 0 0 0 SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Mean Function Elapsed Time (microsecs) for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Mean Function Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Mean Subtree Mean Function Mean Descendants Function Name SQL ID SQL TEXT
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% 134920 134920 0 SYS.DBMS_LOCK.SLEEP (Line 197)
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% 3792 3792 0 __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
2028585 100% 926 0.0% 2027659 100% 1 2.7% 2028586 927 2027660 __anonymous_block.P_MAIN (Line 6)
2028626 100% 41 0.0% 2028585 100% 2 5.4% 1014313 21 1014293 __anonymous_block
2028645 100% 19 0.0% 2028626 100% 2 5.4% 1014323 10 1014313 __plsql_vm
7 0.0% 7 0.0% 0 0.0% 1 2.7% 8 8 0 SYS.DBMS_LOCK.__pkg_init
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% 108821 4 108816 __anonymous_block.P_DOSOMETHING (Line 2)
0 0.0% 0 0.0% 0 0.0% 1 2.7% 0 0 0 SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Mean Descendants Elapsed Time (microsecs) for 'hprof_intro'

Function Elapsed Time (microsecs) Data sorted by Mean Descendants Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Mean Subtree Mean Function Mean Descendants Function Name SQL ID SQL TEXT
2028585 100% 926 0.0% 2027659 100% 1 2.7% 2028586 927 2027660 __anonymous_block.P_MAIN (Line 6)
2028645 100% 19 0.0% 2028626 100% 2 5.4% 1014323 10 1014313 __plsql_vm
2028626 100% 41 0.0% 2028585 100% 2 5.4% 1014313 21 1014293 __anonymous_block
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% 108821 4 108816 __anonymous_block.P_DOSOMETHING (Line 2)
7 0.0% 7 0.0% 0 0.0% 1 2.7% 8 8 0 SYS.DBMS_LOCK.__pkg_init
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% 3792 3792 0 __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% 134920 134920 0 SYS.DBMS_LOCK.SLEEP (Line 197)
0 0.0% 0 0.0% 0 0.0% 1 2.7% 0 0 0 SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Module Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs) for 'hprof_intro'

Module Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Module Ind% Cum% Calls Ind% Module Name
2023808 99.8% 99.8% 16 43.2% SYS.DBMS_LOCK
4837 0.2% 100% 20 54.1%
0 0.0% 100% 1 2.7% SYS.DBMS_HPROF
Module Elapsed Time (microsecs) Data sorted by Module Name for 'hprof_intro'

Module Elapsed Time (microsecs) Data sorted by Module Name

2028645 microsecs (elapsed time) & 37 function calls

Module Ind% Calls Ind% Module Name
4837 0.2% 20 54.1%
0 0.0% 1 2.7% SYS.DBMS_HPROF
2023808 99.8% 16 43.2% SYS.DBMS_LOCK
Module Elapsed Time (microsecs) Data sorted by Total Function Call Count for 'hprof_intro'

Module Elapsed Time (microsecs) Data sorted by Total Function Call Count

2028645 microsecs (elapsed time) & 37 function calls

Module Ind% Calls Ind% Cum% Module Name
4837 0.2% 20 54.1% 54.1%
2023808 99.8% 16 43.2% 97.3% SYS.DBMS_LOCK
0 0.0% 1 2.7% 100% SYS.DBMS_HPROF
Namespace Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs) for 'hprof_intro'

Namespace Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs)

2028645 microsecs (elapsed time) & 37 function calls

Function Ind% Cum% Calls Ind% Namespace
2024854 99.8% 99.8% 36 97.3% PLSQL
3791 0.2% 100% 1 2.7% SQL
Namespace Elapsed Time (microsecs) Data sorted by Namespace for 'hprof_intro'

Namespace Elapsed Time (microsecs) Data sorted by Namespace

2028645 microsecs (elapsed time) & 37 function calls

Function Ind% Calls Ind% Namespace
2024854 99.8% 36 97.3% PLSQL
3791 0.2% 1 2.7% SQL
Namespace Elapsed Time (microsecs) Data sorted by Total Function Call Count for 'hprof_intro'

Namespace Elapsed Time (microsecs) Data sorted by Total Function Call Count

2028645 microsecs (elapsed time) & 37 function calls

Function Ind% Calls Ind% Cum% Namespace
2024854 99.8% 36 97.3% 97.3% PLSQL
3791 0.2% 1 2.7% 100% SQL
Parents and Children Elapsed Time (microsecs) Data for 'hprof_intro'

Parents and Children Elapsed Time (microsecs) Data



__anonymous_block 

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2028626 100% 41 0.0% 2028585 100% 2 5.4% __anonymous_block
Parents:
2028626 100% 41 100% 2028585 100% 2 100% __plsql_vm
Children:
2028585 100% 926 100% 2027659 100% 1 100% __anonymous_block.P_MAIN (Line 6)
0 0.0% 0 N/A 0 N/A 1 100% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
__anonymous_block.P_DOSOMETHING (Line 2)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
1523488 75.1% 60 0.0% 1523428 75.1% 14 37.8% __anonymous_block.P_DOSOMETHING (Line 2)
Parents:
1523488 100% 60 100% 1523428 100% 14 100% __anonymous_block.P_MAIN (Line 6)
Children:
1523428 100% 1523428 75.3% 0 N/A 14 93.3% SYS.DBMS_LOCK.SLEEP (Line 197)
__anonymous_block.P_MAIN (Line 6)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2028585 100% 926 0.0% 2027659 100% 1 2.7% __anonymous_block.P_MAIN (Line 6)
Parents:
2028585 100% 926 100% 2027659 100% 1 100% __anonymous_block
Children:
1523488 75.1% 60 100% 1523428 100% 14 100% __anonymous_block.P_DOSOMETHING (Line 2)
500373 24.7% 500373 24.7% 0 N/A 1 6.7% SYS.DBMS_LOCK.SLEEP (Line 197)
3791 0.2% 3791 100% 0 N/A 1 100% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
7 0.0% 7 100% 0 N/A 1 100% SYS.DBMS_LOCK.__pkg_init
__plsql_vm
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2028645 100% 19 0.0% 2028626 100% 2 5.4% __plsql_vm
Parents:
2028645 100% 19 100% 2028626 100% 2 100% ORACLE.root
Children:
2028626 100% 41 100% 2028585 100% 2 100% __anonymous_block
SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
0 0.0% 0 0.0% 0 0.0% 1 2.7% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Parents:
0 N/A 0 N/A 0 N/A 1 100% __anonymous_block
SYS.DBMS_LOCK.SLEEP (Line 197)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
2023801 99.8% 2023801 99.8% 0 0.0% 15 40.5% SYS.DBMS_LOCK.SLEEP (Line 197)
Parents:
1523428 75.3% 1523428 75.3% 0 N/A 14 93.3% __anonymous_block.P_DOSOMETHING (Line 2)
500373 24.7% 500373 24.7% 0 N/A 1 6.7% __anonymous_block.P_MAIN (Line 6)
SYS.DBMS_LOCK.__pkg_init
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
7 0.0% 7 0.0% 0 0.0% 1 2.7% SYS.DBMS_LOCK.__pkg_init
Parents:
7 100% 7 100% 0 N/A 1 100% __anonymous_block.P_MAIN (Line 6)
__sql_fetch_line9 (Line 9)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
3791 0.2% 3791 0.2% 0 0.0% 1 2.7% __sql_fetch_line9 (Line 9)4ay6mhcbhvbf2SELECT * FROM SCOTT.EMP
Parents:
3791 100% 3791 100% 0 N/A 1 100% __anonymous_block.P_MAIN (Line 6)