Tracking the script execution time in PHP

Question

PHP must track the amount of CPU time a particular script has used in order to enforce the max execution time limit     Is there a way to get access to this inside of the script   I d like to include some logging with my tests about how much CPU was burnt in the actual PHP  the time is not incremented when the script is sitting and waiting for the database    I am using a Linux box

User · Answer

Shorter version of talal7860 s answer   lt  php    At start of script  time start   microtime true        Anywhere else in the script echo  Total execution time in seconds       microtime true  -  time start     As pointed out  this is  wallclock time  not  cpu time

User · Answer

If all you need is the wall-clock time  rather than the CPU execution time  then it is simple to calculate      place this before any script you want to calculate time  time start   microtime true       sample script for  i 0   i lt 1000   i        do anything     time end   microtime true      dividing with 60 will give the execution time in minutes otherwise seconds  execution time     time end -  time start  60     execution time of the script echo   lt b gt Total Execution Time  lt  b gt     execution time   Mins      if you get weird results  use number format  float   execution time  10     Note that this will include time that PHP is sat waiting for external resources such as disks or databases  which is not used for max execution time

User · Answer

return microtime true  -   SERVER  REQUEST TIME FLOAT

User · Answer

The cheapest and dirtiest way to do it is simply make microtime   calls at places in your code you want to benchmark   Do it right before and right after database queries and it s simple to remove those durations from the rest of your script execution time   A hint  your PHP execution time is rarely going to be the thing that makes your script timeout   If a script times out it s almost always going to be a call to an external resource   PHP microtime documentation  http   us php net microtime

User · Answer

To show minutes and seconds you can use        startTime   microtime true        endTime   microtime true        diff   round  endTime -  startTime        minutes   floor  diff   60     only minutes      seconds    diff   60   remaining seconds  using modulo operator     echo  script execution time  minutes  minutes  seconds  seconds     value in seconds

User · Answer

Gringod at developerfusion com gives this good answer    lt  -- put this at the top of the page -- gt    lt  php      mtime   microtime         mtime   explode      mtime        mtime    mtime 1     mtime 0        starttime    mtime      gt     lt  -- put other code and html in here -- gt     lt  -- put this code at the bottom of the page -- gt   lt  php      mtime   microtime         mtime   explode      mtime        mtime    mtime 1     mtime 0        endtime    mtime       totaltime     endtime -  starttime       echo  This page was created in    totaltime   seconds       gt    From  http   www developerfusion com code 2058 determine-execution-time-in-php

User · Answer

I wrote a function that check remaining execution time   Warning  Execution time counting is different on Windows and on Linux platform          Check if more that   miliseconds  ms remains    to error  PHP Fatal error   Maximum execution time exceeded          param int  miliseconds     return bool     function isRemainingMaxExecutionTimeBiggerThan  miliseconds   5000         max execution time   ini get  max execution time        if   max execution time     0               No script time limitation         return true            if  strtoupper substr PHP OS  0  3        WIN                On Windows  The real time is measured           spendMiliseconds    microtime true  -   SERVER  REQUEST TIME FLOAT      1000        else              On Linux  Any time spent on activity that happens outside the execution                      of the script such as system calls using system    stream operations                      database queries  etc  is not included                        see http   php net manual en function set-time-limit php          resourceUsages   getrusage             spendMiliseconds    resourceUsages  ru utime tv sec     1000    resourceUsages  ru utime tv usec     1000             remainingMiliseconds    max execution time   1000 -  spendMiliseconds      return   remainingMiliseconds  gt    miliseconds       Using   while  true           so something      if   isRemainingMaxExecutionTimeBiggerThan 5000                Time to die             Safely close DB and done the iteration

User · Answer

lt  php    Randomize sleeping time usleep mt rand 100  10000        As of PHP 5 4 0  REQUEST TIME FLOAT is available in the   SERVER superglobal array     It contains the timestamp of the start of the request with microsecond precision   time   microtime true  -   SERVER  REQUEST TIME FLOAT     echo  Did nothing in  time seconds n     gt

User · Answer

As an alternative you can just put this line in your code blocks and check php logs  for really slow functions it s pretty useful    trigger error  Task done at    strftime   H  m  S   time     E USER NOTICE      For serious debugging use XDebug   Cachegrind  see https   blog nexcess net 2011 01 29 diagnosing-slow-php-execution-with-xdebug-and-kcachegrind

User · Answer

Just to contribute to this conversation   what happens if the measurement targets two points A and B in different php files   what if we need different measurements like time based  code execution duration  external resource access duration   what if we need to organize our measurements in categories where every one has a different starting point    As you suspect we need some global variables to be accessed by a class object or a static method  I choose the 2nd approach and here it is  namespace g3   class Utils      public function   construct          public static  UtilsDtStart          public static  UtilsDtStats           public static function dt           global  UtilsDtStart   UtilsDtStats         obj   new  stdClass           obj- gt start   function int  ndx   0  use   amp  UtilsDtStart              UtilsDtStart  ndx     microtime true    1000                  obj- gt codeStart   function int  ndx   0  use   amp  UtilsDtStart              use    getrusage              UtilsDtStart  ndx      use  quot ru utime tv sec quot     1000      use  quot ru utime tv usec quot     1000                   obj- gt resourceStart   function int  ndx   0  use   amp  UtilsDtStart              use    getrusage              UtilsDtStart  ndx     use  quot ru stime tv usec quot     1000                  obj- gt end   function int  ndx   0  use   amp  UtilsDtStart   amp  UtilsDtStats              t     UtilsDtStart  ndx            if  t     null              return false            end    microtime true    1000            dt    end -  t            UtilsDtStats  ndx       dt           return  dt                  obj- gt codeEnd   function int  ndx   0  use   amp  UtilsDtStart   amp  UtilsDtStats              t     UtilsDtStart  ndx            if  t     null              return false            use    getrusage              dt     use  quot ru utime tv sec quot     1000      use  quot ru utime tv usec quot     1000  -  t            UtilsDtStats  ndx       dt           return  dt                  obj- gt resourceEnd   function int  ndx   0  use   amp  UtilsDtStart   amp  UtilsDtStats              t     UtilsDtStart  ndx            if  t     null              return false            use    getrusage              dt     use  quot ru stime tv usec quot     1000  -  t            UtilsDtStats  ndx       dt           return  dt                  obj- gt stats   function int  ndx   0  use   amp  UtilsDtStats              s     UtilsDtStats  ndx            if  s     null               s    array slice  s  0            else              s   false           return  s                  obj- gt statsLength   function   use   amp  UtilsDtStats             return  count  UtilsDtStats                  return  obj          Now all you have is to call the method that belongs to the specific category with the index that denotes it s unique group  File A ------  call user func array  g3 Utils  dt  - gt start   0         point A     File B ------  dt    call user func array  g3 Utils  dt  - gt end   0        point B  Value  dt contains the milliseconds of wall clock duration between points A and B  To estimate the time took for php code to run  File A ------  call user func array  g3 Utils  dt  - gt codeStart   1         point A     File B ------  dt    call user func array  g3 Utils  dt  - gt codeEnd   1        point B  Notice how we changed the index that we pass at the methods  The code is based on the closure effect that happens when we return an object function from a function  see that  g3 Utils  dt   repeated at the examples   I tested with php unit and between different test methods at the same test file it behaves fine so far  Hope that helps someone

User · Answer

when there is closure functionality in PHP  why not we get benefit out of it  function startTime         startTime   microtime true       return function    use   startTime           return microtime true  -  startTime            Now with the help of the above function  we can track time like this  stopTime   startTime      some code block or line  elapsedTime    stopTime     Every call to startTime function will initiate a separate time tracker  So you can initiate as many as you want and can stop them wherever you want them

User · Answer

Further expanding on Hamid s answer  I wrote a helper class that can be started and stopped repeatedly  for profiling inside a loop       class ExecutionTime            private  startTime        private  endTime        private  compTime   0        private  sysTime   0         public function Start              this- gt startTime   getrusage                   public function End              this- gt endTime   getrusage              this- gt compTime     this- gt runTime  this- gt endTime   this- gt startTime   utime              this- gt systemTime     this- gt runTime  this- gt endTime   this- gt startTime   stime                   private function runTime  ru   rus   index             return   ru  ru  index tv sec   1000   intval  ru  ru  index tv usec   1000            -    rus  ru  index tv sec   1000   intval  rus  ru  index tv usec   1000                   public function   toString             return  This process used      this- gt compTime     ms for its computations n                     It spent      this- gt systemTime     ms in system calls n

User · Answer

It is going to be prettier if you format the seconds output like   echo  Process took    number format microtime true  -  start  2     seconds      will print  Process took 6 45 seconds    This is much better than  Process took 6 4518549156189 seconds

User · Answer

I think you should look at xdebug   The profiling options will give you a head start toward knowing many process related items   http   www xdebug org

User · Answer

Small script that print  centered in bottom of the page  the script execution that started at server call with microsecond precision  So as not to distort the result and to be 100  compatible with content in page  I used  to write the result on the page  a browser-side native javascript snippet    Uncomment the line below to test with 2 seconds    usleep 2000000     prec   5     numbers after comma  time   number format microtime true  -   SERVER  REQUEST TIME FLOAT     prec            echo  quot  lt script gt      if  tI             var tI document createElement  div            tI style fontSize  8px           tI style marginBottom  5px           tI style position  absolute           tI style bottom  0px           tI style textAlign  center           tI style width  98            document body appendChild tI             tI innerHTML   time    lt  script gt  quot    Another approach is to make the snippet as small as possible  and style it with a class in your stylesheet   Replace the echo      part with the following  echo  quot  lt script gt if  tI  var tI document createElement  div   tI className  ldtme  document body appendChild tI   tI innerHTML   time   lt  script gt  quot    In your CSS create and fill the  ldtme      class

User · Answer

On unixoid systems  and in php 7  on Windows as well   you can use getrusage  like      Script start  rustart   getrusage        Code         Script end function rutime  ru   rus   index        return   ru  ru  index tv sec   1000   intval  ru  ru  index tv usec   1000        -    rus  ru  index tv sec   1000   intval  rus  ru  index tv usec   1000        ru   getrusage    echo  This process used     rutime  ru   rustart   utime           ms for its computations n   echo  It spent     rutime  ru   rustart   stime           ms in system calls n     Note that you don t need to calculate a difference if you are spawning a php instance for every test

User · Answer

SERVER  REQUEST TIME    check out that too  i e          your codes running     echo  time   -   SERVER  REQUEST TIME

User · Answer

You may only want to know the execution time of parts of your script  The most flexible way to time parts or an entire script is to create 3 simple functions  procedural code given here but you could turn it into a class by putting class timer   around it and making a couple of tweaks   This code works  just copy and paste and run    tstart   0   tend   0   function timer starts     global  tstart    tstart microtime true         function timer ends     global  tend    tend microtime true         function timer calc     global  tstart  tend   return  round  tend -  tstart 2       timer starts    file get contents  http   google com    timer ends    print  It took   timer calc     seconds to retrieve the google page

User · Answer

I created an ExecutionTime class out of phihag answer that you can use out of box   class ExecutionTime        private  startTime       private  endTime        public function start              this- gt startTime   getrusage                 public function end              this- gt endTime   getrusage                 private function runTime  ru   rus   index             return   ru  ru  index tv sec   1000   intval  ru  ru  index tv usec   1000        -    rus  ru  index tv sec   1000   intval  rus  ru  index tv usec   1000                     public function   toString             return  This process used      this- gt runTime  this- gt endTime   this- gt startTime   utime               ms for its computations nIt spent      this- gt runTime  this- gt endTime   this- gt startTime   stime               ms in system calls n               usage    executionTime   new ExecutionTime     executionTime- gt start       code  executionTime- gt end    echo  executionTime       Note  In PHP 5  the getrusage function only works in Unix-oid systems  Since PHP 7  it also works on Windows

[php] Tracking the script execution time in PHP

Examples related to php

Examples related to performance