function readOnly(count){ }
Sign Up ›
Starting November 20, the site will be set to read-only. On December 4, 2023,
forum discussions will move to the Trailblazer Community.
Learn More

Browse by Topic
ShowAll Questionssorted byDate Posted
+ Start a Discussion
YogeshAroraYogeshArora 

Time value in debug log

Below is a small portion of a debug log.

My question is:

The value shown in brackets in timestamp, shown as (48372000)  in case of 'EXECUTION_STARTED', represents the time elapsed in nanoseconds since the start of the request.

Shouldn't this be (00000000) when EXECUTION_STARTED, since this marks the start of the request ?

 

How come it is showing 48372000 nanoseconds passed before the EXECUTION_STARTED ?

 

24.0 APEX_CODE,DEBUG;CALLOUT,INFO;DB,INFO;SYSTEM,DEBUG;VALIDATION,INFO;VISUALFORCE,INFO;WORKFLOW,INFO
19:38:35.048 (48372000)|EXECUTION_STARTED
19:38:35.048 (48422000)|CODE_UNIT_STARTED|[EXTERNAL]|TRIGGERS
19:38:35.048 (48446000)|CODE_UNIT_STARTED|[EXTERNAL]|01q90000000VNgp|MyTrigger on Contact trigger event BeforeInsert for [new]
19:38:35.051 (51026000)|METHOD_ENTRY|[1]|01p90000000z2mt|Abc.Abc()
19:38:35.051 (51118000)|METHOD_EXIT|[1]|Abc
19:38:35.051 (51148000)|METHOD_ENTRY|[2]|01p90000000z2mt|Abc.xyz()
19:38:35.051 (51271000)|USER_DEBUG|[5]|DEBUG|This is a log entry.
19:38:35.051 (51292000)|METHOD_EXIT|[2]|01p90000000z2mt|Abc.xyz()
19:38:35.051 (51324000)|CODE_UNIT_FINISHED|MyTrigger on Contact trigger event BeforeInsert for [new]
19:38:35.197 (197357000)|CODE_UNIT_FINISHED|TRIGGERS
19:38:35.197 (197373000)|EXECUTION_FINISHED
Navatar_DbSupNavatar_DbSup

Hi,

 

Timestamp: consists of the time when the event occurred and a value between parentheses. The time is in the user's time zone and in the format HH:mm:ss.SSS. The value represents the time elapsed in nanoseconds since the start of the request. The elapsed time value is excluded from logs reviewed in the Developer Console.

 

For more detail follow the below link:

http://www.salesforce.com/us/developer/docs/apexcode/Content/apex_debugging_debug_log.htm

 

Did this answer your question? If not, let me know what didn't work, or if so, please mark it solved. 
YogeshAroraYogeshArora

Hello,

 

I am aware that the value represents the time elapsed in nanoseconds since the start of the request.

But my question is how come 48372000 nanoseconds have elapsed at EXECUTION_STARTED ? Shouldn't this be (00000000), because the EXECUTION_STARTED event marks the start of the request ?