Android计算函数时间戳的方法

对于做性能的人来说，知道时间的花在哪了是比较重要的，可以在函数前后得到系统的时间，计算时间戳能够得到每个函数的时间。

在JAVA中可以通过System.currentTimeMillis()得到：

long start_time = System.currentTimeMillis();

View.draw(canvas);

long end_time = System.currentTimeMillis();

long spend_time = end_time - start_time;

Log.i(TAG,"mView.draw: spend_time = " + spend_time);

在native的代码中，可以通过下面的方式得到函数的执行时间：

#include <stdio.h>

#include <sys/time.h>

void main ()

{

    struct timeval time;

    gettimeofday(&time, NULL);

    printf ( "\007The current date/time is: %lld\n", time.tv_sec * 1000 + time.tv_usec /1000);

}

在kernel里面，可以通过rtc,跟上层应用的时间对应起来，如下面的例子：

#include <linux/time.h>

#include <linux/rtc.h>

 

struct timespec  time_start, time_end;
struct rtc_time tm_start, tm_end;
long time_nsec = 0;
getnstimeofday(&time_start);
rtc_time_to_tm(time_end.tv_sec, &tm_start);

printk(KERN_ERR "\n (%d-%02d-%02d %02d:%02d:%02d.%09lu UTC)\n",
tm_start.tm_year + 1900, tm_start.tm_mon + 1, tm_start.tm_mday,
tm_start.tm_hour, tm_start.tm_min, tm_start.tm_sec, time_start.tv_nsec);

.......

getnstimeofday(&time_end);
rtc_time_to_tm(time_end.tv_sec, &tm_end);
time_nsec = time_end.tv_nsec - time_start.tv_nsec;
printk(KERN_ERR "\n  tid: %d, common: %s \n", current->pid, current->comm);
printk(KERN_ERR "\n end(%d-%02d-%02d %02d:%02d:%02d.%09lu UTC)\n",
tm_end.tm_year + 1900, tm_end.tm_mon + 1, tm_end.tm_mday,
tm_end.tm_hour, tm_end.tm_min, tm_end.tm_sec, time_end.tv_nsec);
printk(KERN_ERR "\n mdss_fb_commit_wq_handler  end, time_nsec : %ld \n"  , time_nsec);

当然过从java到native到kernel一个流程跟下去，有可能会发现user space里面的耗时比较多，而kernel里面却没有耗时的情况，这是因为有进程调度的存在。最近就遇到了这样的问题，user space一个函数耗时30ms，但是在kernel里面却没有花时间，因为从kernel返回到user space的时候，进行了进程调度，而此时的user space的thread block了，才会产生这样的情况，希望注意。

2014/03/12更新：

java得到当前的年月日，时分秒格式的时间

import java.text.SimpleDateFormat;
SimpleDateFormat mFormat = new java.text.SimpleDateFormat("yyyy:MM:dd HH:mm:ss:SSS");
String time = mFormat.format(System.currentTimeMillis());

2014/03/13更新：

Native得到当前的年月日，时分秒格式的时间

timeval tv;
gettimeofday(&tv, NULL);
int milli = tv.tv_usec / 1000;


char buffer [80];
strftime(buffer, 80, "%Y:%m:%d %H:%M:%S", localtime(&tv.tv_sec));


char currentTime[84] = "";
sprintf(currentTime, "%s.%d", buffer, milli);
ALOGD("time: %s \n", currentTime);