File Elapsed Time (microsecs) Data Comparison with Parents and Children



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%







Elapsed Time (microsecs) Comparison for TEST.DO_SOMETHING_1.DO_SOMETHING_1 (Line 1) (0.0% of total regression)

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%



TEST.DO_SOMETHING_1.DO_SOMETHING_1 (Line 1)

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)



Elapsed Time (microsecs) Comparison for TEST.DO_SOMETHING_2.DO_SOMETHING_2 (Line 1) (0.4% of total regression)

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%



TEST.DO_SOMETHING_2.DO_SOMETHING_2 (Line 1)

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)



Elapsed Time (microsecs) Comparison for TEST.DO_SOMETHING_3.DO_SOMETHING_3 (Line 1) (10.2% of total regression)

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%



TEST.DO_SOMETHING_3.DO_SOMETHING_3 (Line 1)

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)



Elapsed Time (microsecs) Comparison for TEST.DO_SOMETHING_3.__static_sql_exec_line5 (Line 5) (89.4% of total regression)

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%



TEST.DO_SOMETHING_3.__static_sql_exec_line5 (Line 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)

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%

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
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)