PL/SQL Elapsed Time (microsecs) Analysis

508391 microsecs (elapsed time) & 100006 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 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508391 100% 14 0.0% 508377 100% 2 0.0% __plsql_vm
508377 100% 171 0.0% 508206 100% 2 0.0% __anonymous_block
508206 100% 328430 64.6% 179776 35.4% 1 0.0% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% __plsql_vm@1
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
0 0.0% 0 0.0% 0 0.0% 1 0.0% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Cum% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508206 100% 328430 64.6% 64.6% 179776 35.4% 1 0.0% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
113340 22.3% 113340 22.3% 86.9% 0 0.0% 50000 50.0% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
179776 35.4% 66436 13.1% 100% 113340 22.3% 50000 50.0% __plsql_vm@1
508377 100% 171 0.0% 100% 508206 100% 2 0.0% __anonymous_block
508391 100% 14 0.0% 100% 508377 100% 2 0.0% __plsql_vm
0 0.0% 0 0.0% 100% 0 0.0% 1 0.0% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
SQL ID Elapsed Time (microsecs) Data sorted by SQL ID for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

SQL ID SQL TEXT Function Ind% Calls Ind%
27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B0 0.0% 1 0.0%
Function Elapsed Time (microsecs) Data sorted by Function Name for 'case1'

Function Elapsed Time (microsecs) Data sorted by Function Name

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508377 100% 171 0.0% 508206 100% 2 0.0% __anonymous_block
508391 100% 14 0.0% 508377 100% 2 0.0% __plsql_vm
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% __plsql_vm@1
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
0 0.0% 0 0.0% 0 0.0% 1 0.0% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
508206 100% 328430 64.6% 179776 35.4% 1 0.0% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
Function Elapsed Time (microsecs) Data sorted by Total Descendants Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508391 100% 14 0.0% 508377 100% 2 0.0% __plsql_vm
508377 100% 171 0.0% 508206 100% 2 0.0% __anonymous_block
508206 100% 328430 64.6% 179776 35.4% 1 0.0% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% __plsql_vm@1
0 0.0% 0 0.0% 0 0.0% 1 0.0% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
Function Elapsed Time (microsecs) Data sorted by Total Function Call Count for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Cum% Function Name SQL ID SQL TEXT
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% 50.0% __plsql_vm@1
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% 100% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
508377 100% 171 0.0% 508206 100% 2 0.0% 100% __anonymous_block
508391 100% 14 0.0% 508377 100% 2 0.0% 100% __plsql_vm
508206 100% 328430 64.6% 179776 35.4% 1 0.0% 100% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
0 0.0% 0 0.0% 0 0.0% 1 0.0% 100% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Mean Subtree Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Mean Subtree Mean Function Mean Descendants Function Name SQL ID SQL TEXT
508206 100% 328430 64.6% 179776 35.4% 1 0.0% 508207 328431 179777 __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
508391 100% 14 0.0% 508377 100% 2 0.0% 254196 7 254189 __plsql_vm
508377 100% 171 0.0% 508206 100% 2 0.0% 254189 86 254103 __anonymous_block
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% 4 1 2 __plsql_vm@1
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% 2 2 0 SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
0 0.0% 0 0.0% 0 0.0% 1 0.0% 0 0 0 SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Mean Function Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Mean Subtree Mean Function Mean Descendants Function Name SQL ID SQL TEXT
508206 100% 328430 64.6% 179776 35.4% 1 0.0% 508207 328431 179777 __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
508377 100% 171 0.0% 508206 100% 2 0.0% 254189 86 254103 __anonymous_block
508391 100% 14 0.0% 508377 100% 2 0.0% 254196 7 254189 __plsql_vm
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% 2 2 0 SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% 4 1 2 __plsql_vm@1
0 0.0% 0 0.0% 0 0.0% 1 0.0% 0 0 0 SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Function Elapsed Time (microsecs) Data sorted by Mean Descendants Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Mean Subtree Mean Function Mean Descendants Function Name SQL ID SQL TEXT
508391 100% 14 0.0% 508377 100% 2 0.0% 254196 7 254189 __plsql_vm
508377 100% 171 0.0% 508206 100% 2 0.0% 254189 86 254103 __anonymous_block
508206 100% 328430 64.6% 179776 35.4% 1 0.0% 508207 328431 179777 __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% 4 1 2 __plsql_vm@1
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% 2 2 0 SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
0 0.0% 0 0.0% 0 0.0% 1 0.0% 0 0 0 SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Module Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Module Ind% Cum% Calls Ind% Module Name
395051 77.7% 77.7% 50005 50.0%
113340 22.3% 100% 50000 50.0% SCOTT.F_CHANGE_TX
0 0.0% 100% 1 0.0% SYS.DBMS_HPROF
Module Elapsed Time (microsecs) Data sorted by Module Name for 'case1'

Module Elapsed Time (microsecs) Data sorted by Module Name

508391 microsecs (elapsed time) & 100006 function calls

Module Ind% Calls Ind% Module Name
395051 77.7% 50005 50.0%
113340 22.3% 50000 50.0% SCOTT.F_CHANGE_TX
0 0.0% 1 0.0% SYS.DBMS_HPROF
Module Elapsed Time (microsecs) Data sorted by Total Function Call Count for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Module Ind% Calls Ind% Cum% Module Name
395051 77.7% 50005 50.0% 50.0%
113340 22.3% 50000 50.0% 100% SCOTT.F_CHANGE_TX
0 0.0% 1 0.0% 100% SYS.DBMS_HPROF
Namespace Elapsed Time (microsecs) Data sorted by Total Function Elapsed Time (microsecs) for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Function Ind% Cum% Calls Ind% Namespace
328430 64.6% 64.6% 1 0.0% SQL
179961 35.4% 100% 100005 100% PLSQL
Namespace Elapsed Time (microsecs) Data sorted by Namespace for 'case1'

Namespace Elapsed Time (microsecs) Data sorted by Namespace

508391 microsecs (elapsed time) & 100006 function calls

Function Ind% Calls Ind% Namespace
179961 35.4% 100005 100% PLSQL
328430 64.6% 1 0.0% SQL
Namespace Elapsed Time (microsecs) Data sorted by Total Function Call Count for 'case1'

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

508391 microsecs (elapsed time) & 100006 function calls

Function Ind% Calls Ind% Cum% Namespace
179961 35.4% 100005 100% 100% PLSQL
328430 64.6% 1 0.0% 100% SQL
Parents and Children Elapsed Time (microsecs) Data for 'case1'

Parents and Children Elapsed Time (microsecs) Data



__anonymous_block 

Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508377 100% 171 0.0% 508206 100% 2 0.0% __anonymous_block
Parents:
508377 100% 171 100% 508206 100% 2 100% __plsql_vm
Children:
508206 100% 328430 100% 179776 100% 1 100% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
0 0.0% 0 N/A 0 N/A 1 100% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
__plsql_vm
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508391 100% 14 0.0% 508377 100% 2 0.0% __plsql_vm
Parents:
508391 100% 14 100% 508377 100% 2 100% ORACLE.root
Children:
508377 100% 171 100% 508206 100% 2 100% __anonymous_block
__plsql_vm@1
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
179776 35.4% 66436 13.1% 113340 22.3% 50000 50.0% __plsql_vm@1
Parents:
179776 100% 66436 100% 113340 100% 50000 100% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
Children:
113340 100% 113340 100% 0 N/A 50000 100% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
113340 22.3% 113340 22.3% 0 0.0% 50000 50.0% SCOTT.F_CHANGE_TX.F_CHANGE_TX (Line 1)
Parents:
113340 100% 113340 100% 0 N/A 50000 100% __plsql_vm@1
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 0.0% SYS.DBMS_HPROF.STOP_PROFILING (Line 453)
Parents:
0 N/A 0 N/A 0 N/A 1 100% __anonymous_block
__static_sql_exec_line4 (Line 4)
Subtree Ind% Function Ind% Descendants Ind% Calls Ind% Function Name SQL ID SQL TEXT
508206 100% 328430 64.6% 179776 35.4% 1 0.0% __static_sql_exec_line4 (Line 4)27t27npwd3n0jSELECT LISTAGG(OWNER_TX,',') WITHIN GROUP (ORDER B
Parents:
508206 100% 328430 100% 179776 100% 1 100% __anonymous_block
Children:
179776 100% 66436 100% 113340 100% 50000 100% __plsql_vm@1