1. 前言

首先说明一下应用的几种启动方式

冷启动:系统不存在此 APP 的进程,此时需要重新创建进程、Application、Activity等,然后是 measure、layout、draw 过程

温启动:用户按 HOME 键后,如果 Activity 没有被回收,启动应用也只是唤醒到前台,不需要走初始化流程

热启动:系统存在此 APP 的进程,比如用户按 Back 键,或者按 Home键后 Activity 被回收了,此时由于进程存在,所以不会初始化 Application,只需要创建 Activity 并 measure、layout、draw。

最近有个需求需要统计App的启动时间,在查阅了一些资料后总结有如下三种方案

通过 adb 的 am 命令获取

通过 adb 的 logcat 命令获取

通过在Application和业务的第一个Activity埋点进行统计

2. 通过 adb 的 am 命令获取

网上大部分都是这种方案,可以通过 adb 的命令

adb shell am start -W com.gtr.sdkdemo/com.gtr.test.MainActivity

这个命令的输出日志如下:

Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] cmp=com.gtr.sdkdemo/com.gtr.test.MainActivity }
Status: ok
Activity: com.gtr.sdkdemo/com.gtr.test.MainActivity
ThisTime: 716
TotalTime: 4680
Complete

几个时间参数的讲解:

WaitTime 返回从 startActivity 到应用第一帧完全显示这段时间. 就是总的耗时,包括前一个应用 Activity pause 的时间和新应用启动的时间;

ThisTime 表示一连串启动 Activity 的最后一个 Activity 的启动耗时;

TotalTime 表示新应用启动的耗时,包括新进程的启动和 Activity 的启动,但不包括前一个应用Activity pause的耗时。

所以只关心 TotalTime 参数就可以了。但是问题来了:

首先这个是shell命令,能不能通过 Runtime 来进行调用这个命令我没试过,极有可能是不行的

这个命令需要新起一个Activity来统计,在已运行应用中肯定不可能新起Activity来统计,因为新起肯定是热启动的,启动时间不准

因此,这个方案被无情抛弃

3. 通过 adb 的 logcat 命令 获取

在一次无意的浏览 StackOverFlow 过程中,看到有个大牛给了一个提示:应用启用时,会输出一行日志

adb logcat -s ActivityManager:I | grep Displayed

我试了一下,果不其然:

I/ActivityManager( 949): Displayed com.gtr.sdkdemo/com.gtr.test.MainActivity: +303ms (total +1s546ms)

再启动一次,发现还会出来一条

I/ActivityManager( 949): Displayed com.gtr.sdkdemo/com.gtr.test.MainActivity: +303ms (total +1s546ms)
I/ActivityManager( 949): Displayed com.gtr.sdkdemo/com.gtr.test.MainActivity: +590ms

也就是说,只需要读取最后一条数据就OK了。激动的我马上封装了一个异步获取类来试了一下,源代码如下

public class BaseInfoManager {
private static final int WHAT_START_GET_APP_LAUNCH_TIME = 1000;
private static volatile BaseInfoManager instance;
private HandlerThread mHandlerThread;
private Handler mHandler;
private DataInputStream mReader;
// 数据相关
private List appLaunchTimeList = new ArrayList<>();
private BaseInfoManager() {
}
public static BaseInfoManager getInstance() {
if (instance == null) {
synchronized (BaseInfoManager.class) {
if (instance == null) {
instance = new BaseInfoManager();
}
}
}
return instance;
}
public interface Callback {
void callback(T t);
}
private void runThreadIfNeed() {
if (mHandlerThread == null) {
mHandlerThread = new HandlerThread(BaseInfoManager.class.getSimpleName());
mHandlerThread.start();
mHandler = new Handler(mHandlerThread.getLooper()) {
@Override
public void handleMessage(Message msg) {
switch (msg.what) {
case WHAT_START_GET_APP_LAUNCH_TIME:
runShellForAppLaunchTime();
break;
}
}
};
}
}
private void destoryThreadIfNeed() {
if (mReader != null) {
try {
mReader.close();
} catch (IOException e) {
e.printStackTrace();
}
}
if (mHandler != null) {
mHandler.removeCallbacksAndMessages(null);
mHandler = null;
}
if (mHandlerThread != null) {
mHandlerThread.quitSafely();
mHandlerThread = null;
}
}
public synchronized void getLaunchAppTimeASync(long delayTime, final Callback callback) {
runThreadIfNeed();
appLaunchTimeList.clear();
mHandler.sendEmptyMessage(WHAT_START_GET_APP_LAUNCH_TIME);
new Handler(Looper.getMainLooper()).postDelayed(new Runnable() {
@Override
public void run() {
destoryThreadIfNeed();
// 获取最近的一条数据
if (appLaunchTimeList.isEmpty()) {
callback.callback("");
} else {
callback.callback(appLaunchTimeList.get(appLaunchTimeList.size() - 1));
}
}
}, delayTime);
}
private void runShellForAppLaunchTime() {
Process logcatProcess = null;
try {
// adb logcat -s ActivityManager:I | grep Displayed
String cmd = "logcat -s ActivityManager:I | grep Displayed";
logcatProcess = Runtime.getRuntime().exec(cmd);
mReader = new DataInputStream(logcatProcess.getInputStream());
String line;
while ((line = mReader.readUTF()) != null) {
appLaunchTimeList.add(line);
}
} catch (IOException e){
// nothing to do
} catch (SecurityException |
IllegalArgumentException |
NullPointerException e) {
e.printStackTrace();
} finally {
if (mReader != null) {
try {
mReader.close();
} catch (IOException e) {
e.printStackTrace();
}
mReader = null;
}
if (logcatProcess != null) {
logcatProcess.destroy();
}
}
}
}

这个类提供了延时异步获取的方式,因为 logcat 命令是会阻塞 shell 进程的,如果在主线程直接读取的话,会造成主线程阻塞。

在设计这个类的时候,也踩了一些坑,在这里稍微总结一下以防以后忘了

Runtime 的 exec 方法只是执行 shell 语句,并不是 shell 解释器,因此一些管道符、重定向符是不生效的。也就是说 |grep 我无效的,只能手动在程序中判断

android 用 exec 执行 logcat 中会自动过滤掉非本应用的一些日志,可能是出于安全着想

exec(String) 和 exec(String[]) 是等效的,最终都会将 String 参数转换为 String[] 参数

Progress 的 waitFor 方法会阻塞当前线程,直到 shell 子进程结束,如果 shell 子进程一直不结束,则会造成死锁。

BufferedReader 的 readLine 和 close 方法都会阻塞。起初我是用 BufferedReader 的 readLine 在子线程读取数据的,但是在主线程调用 close 方法来解除 子线程的阻塞状态时发现主线程也被阻塞了。查看代码才发现 BufferedReader 的 close 方法加了一把锁。

public void close() throws IOException {
synchronized (lock) {
if (in == null)
return;
try {
in.close();
} finally {
in = null;
cb = null;
}
}
}

这样做就非常危险了,也就是说 BufferedReader 除非读到末尾的 '\n' 字符,否则是不能主动结束其阻塞状态的。而且主线程想结束子线程的阻塞状态调用 close 方法还可能把主线程给阻塞了。

后来我才用 DataInputStream 来替换的,因为它的 close 方法没有加锁,不会被阻塞,并且可以解除子线程的阻塞状态

public void close() throws IOException {
in.close();
}

总结的采坑就到这里差不多了,运行结果发现没有任何启动时间数据。原因正如上面说的第二点,android 用 Runtime 获取的 logcat 日志信息,屏蔽了非本应用的日志,而启动时间的日志是属于系统的,所以获取不到。

4. 通过在Application和业务的第一个Activity埋点进行统计

上面的两种方案都以失败告终,没办法在跟老大沟通后只有牺牲数据准确性了。

首先在 Application 的 attachBaseContext 方法记录开始时间

在业务的第一个 Activity 的 onWindowFocusChanged 方法记录结束时间

这里解释一下为什么是 onWindowFocusChanged 而不是 onResume 等其他生命周期。因为 onResume 只是 Activity 的一次步骤,此时控件只是被 measure 了,但是并没有 draw, 因此此时并不能被用户所见,而为了统计数据的准确性,以用户所见作为结束时间更为恰当。

然后解释一下这种方式的优缺点

优点:可以自由选择哪一个 Activity 作为业务上的“首页”,比如把主页作为首页而不是启动页

缺点:从用户点击 app icon 到 Application 被创建,中间还是有很多步骤的,比如冷启动的进程创建过程,而这个时间用此版本是没办法统计了,必须得承受这点数据的不准确性。