Elapsed Time (microsecs) Comparison for SYS.DBMS_HPROF.STOP_PROFILING (Line 53)
SYS.DBMS_HPROF.STOP_PROFILING (Line 53) | First Trace | Ind% | Second Trace | Ind% | Diff | Diff% |
---|---|---|---|---|---|---|
Function Calls | 1 | 0.1% | 1 | 0.0% | ||
TEST.DO_SOMETHING_1.DO_SOMETHING_1 (Line 1) | First Trace | Ind% | Second Trace | Ind% | Diff | Diff% |
---|---|---|---|---|---|---|
Function Elapsed Time (microsecs)s | 113 | 0.1% | 347 | 0.0% | 234 | +207% |
Descendants Elapsed Time (microsecs)s | 98402 | 99.9% | 775518 | 100% | 677116 | +688% |
Subtree Elapsed Time (microsecs)s | 98515 | 100% | 775865 | 100% | 677350 | +688% |
Function Calls | 1 | 0.1% | 1 | 0.0% | ||
Mean Function Elapsed Time (microsecs)s | 113.0 | 347.0 | 234.0 | +207% | ||
Mean Descendants Elapsed Time (microsecs)s | 98402.0 | 775518.0 | 677116.0 | +688% | ||
Mean Subtree Elapsed Time (microsecs)s | 98515.0 | 775865.0 | 677350.0 | +688% |
Subtree | Function | Descendants | Calls | Function Name |
---|---|---|---|---|
677350 | 234 | 677116 | 0 | TEST.DO_SOMETHING_1.DO_SOMETHING_1 (Line 1) |
Parents: | ||||
677350 | 234 | 677116 | 0 | ORACLE.root |
Children: | ||||
677116 | 2660 | 674456 | 10 | TEST.DO_SOMETHING_2.DO_SOMETHING_2 (Line 1) |
TEST.DO_SOMETHING_2.DO_SOMETHING_2 (Line 1) | First Trace | Ind% | Second Trace | Ind% | Diff | Diff% |
---|---|---|---|---|---|---|
Function Elapsed Time (microsecs)s | 959 | 1.0% | 3619 | 0.5% | 2660 | +277% |
Descendants Elapsed Time (microsecs)s | 97443 | 98.9% | 771899 | 99.5% | 674456 | +692% |
Subtree Elapsed Time (microsecs)s | 98402 | 99.9% | 775518 | 100% | 677116 | +688% |
Function Calls | 10 | 0.9% | 20 | 0.2% | 10 | +100% |
Mean Function Elapsed Time (microsecs)s | 95.9 | 180.9 | 85.0 | +88.7% | ||
Mean Descendants Elapsed Time (microsecs)s | 9744.3 | 38594.9 | 28850.6 | +296% | ||
Mean Subtree Elapsed Time (microsecs)s | 9840.2 | 38775.9 | 28935.7 | +294% |
Subtree | Function | Descendants | Calls | Function Name |
---|---|---|---|---|
677116 | 2660 | 674456 | 10 | TEST.DO_SOMETHING_2.DO_SOMETHING_2 (Line 1) |
Parents: | ||||
677116 | 2660 | 674456 | 10 | TEST.DO_SOMETHING_1.DO_SOMETHING_1 (Line 1) |
Children: | ||||
674456 | 69022 | 605434 | 300 | TEST.DO_SOMETHING_3.DO_SOMETHING_3 (Line 1) |
TEST.DO_SOMETHING_3.DO_SOMETHING_3 (Line 1) | First Trace | Ind% | Second Trace | Ind% | Diff | Diff% |
---|---|---|---|---|---|---|
Function Elapsed Time (microsecs)s | 10431 | 10.6% | 79453 | 10.2% | 69022 | +662% |
Descendants Elapsed Time (microsecs)s | 87012 | 88.3% | 692446 | 89.2% | 605434 | +696% |
Subtree Elapsed Time (microsecs)s | 97443 | 98.9% | 771899 | 99.5% | 674456 | +692% |
Function Calls | 100 | 9.0% | 400 | 4.7% | 300 | +300% |
Mean Function Elapsed Time (microsecs)s | 104.3 | 198.6 | 94.3 | +90.4% | ||
Mean Descendants Elapsed Time (microsecs)s | 870.1 | 1731.1 | 861.0 | +99.0% | ||
Mean Subtree Elapsed Time (microsecs)s | 974.4 | 1929.7 | 955.3 | +98.0% |
Subtree | Function | Descendants | Calls | Function Name |
---|---|---|---|---|
674456 | 69022 | 605434 | 300 | TEST.DO_SOMETHING_3.DO_SOMETHING_3 (Line 1) |
Parents: | ||||
674456 | 69022 | 605434 | 300 | TEST.DO_SOMETHING_2.DO_SOMETHING_2 (Line 1) |
Children: | ||||
605434 | 605434 | 0 | 7000 | TEST.DO_SOMETHING_3.__static_sql_exec_line5 (Line 5) |
TEST.DO_SOMETHING_3.__static_sql_exec_line5 (Line 5) | First Trace | Ind% | Second Trace | Ind% | Diff | Diff% |
---|---|---|---|---|---|---|
Function Elapsed Time (microsecs)s | 87012 | 88.3% | 692446 | 89.2% | 605434 | +696% |
Function Calls | 1000 | 89.9% | 8000 | 95.0% | 7000 | +700% |
Mean Function Elapsed Time (microsecs)s | 87.0 | 86.6 | -0.5 | -0.5% |
Subtree | Function | Descendants | Calls | Function Name |
---|---|---|---|---|
605434 | 605434 | 0 | 7000 | TEST.DO_SOMETHING_3.__static_sql_exec_line5 (Line 5) |
Parents: | ||||
605434 | 605434 | 0 | 7000 | TEST.DO_SOMETHING_3.DO_SOMETHING_3 (Line 1) |