monkey运行日志基础分析

之前有写过： 

Monkey测试环境搭建---更新时间截止20170724可用：

http://blog.csdn.net/a136332462/article/details/75967719

monkey命令详解： 

http://blog.csdn.net/a136332462/article/details/76014412

要看的移步

这篇主要讲的是在使用monkey运行结束以后，对日志的基础分析，

注意查找日志中的无响应问题和崩溃问题。无响应问题可以在日志中搜索 “ANR” ，崩溃问题搜索 “CRASH” ，内存泄露问题搜索"GC"（需进一步分析），异常问题搜索 “Exception”（如果出现空指针， NullPointerException，需格外重视）。

一. 初步分析方法：Monkey测试出现错误后，一般的差错步骤为以下几步：
1、 找到是monkey里面的哪个地方出错
2、 查看Monkey里面出错前的一些事件动作，并手动执行该动作
3、 若以上步骤还不能找出，可以使用之前执行的monkey命令再执行一遍，注意seed值要一样
一般的测试结果分析：
1、 ANR问题：在日志中搜索“ANR”(“Application Not Responding")，说明有bug，出现ANR，一般是主线程的响应超过5秒，或者BroadcastReceiver没有在10秒内作出响应。这个就是一个比较严重的缺陷。把耗时的操作另起线程来处理就可以了。
2、 崩溃问题：在日志中搜索“Exception”
二. 详细分析monkey日志：
将执行Monkey生成的log，从手机中导出并打开查看该log；在log的最开始都会显示Monkey执行的seed值、执行次数和测试的包名。
首先我们需要查看Monkey测试中是否出现了ANR或者异常，具体方法如上述。然后我们要分析log中的具体信息，方法如下：
查看log中第一个Switch，主要是查看Monkey执行的是那一个Activity，譬如下面的log中，执行的是com.tencent.smtt.SplashActivity，在下一个swtich之间的，如果出现了崩溃或其他异常，可以在该Activity中查找问题的所在。
:Switch:#Intent;action=android.intent.action.MAIN;category=android.intent.category.LAUNCHER;launchFlags=0x10000000;component=com.tencent.smtt/.SplashActivity;end 
// Allowing start of Intent {act=android.intent.action.MAIN cat=
[android.intent.category.LAUNCHER]cmp=com.tencent.smtt/.SplashActivity } in package com.tencent.smtt
在下面的log中，Sending Pointer ACTION_DOWN和Sending Pointer ACTION_UP代表当前执行了一个单击的操作;
Sleeping for 500 milliseconds这句log是执行Monkey测试时，throttle设定的间隔时间，每出现一次，就代表一个事件。
SendKey(ACTION_DOWN) //KEYCODE_DPAD_DOWN   代表当前执行了一个点击下导航键的操作；
Sending Pointer ACTION_MOVE     代表当前执行了一个滑动界面的操作。
:Sending Pointer ACTION_DOWN x=47.0 y=438.0
:Sending Pointer ACTION_UP x=47.0 y=438.0Sleeping for 500 milliseconds
:SendKey (ACTION_DOWN): 20   //KEYCODE_DPAD_DOWN:SendKey (ACTION_UP): 20   //KEYCODE_DPAD_DOWNSleeping for 500 milliseconds
:Sending Pointer ACTION_MOVE x=-2.0 y=3.0
:Sending Pointer ACTION_MOVE x=4.0 y=-3.0
:Sending Pointer ACTION_MOVE x=-5.0 y=-3.0
:Sending Pointer ACTION_MOVE x=3.0 y=4.0
:Sending Pointer ACTION_MOVE x=-4.0 y=1.0
:Sending Pointer ACTION_MOVE x=-1.0 y=-1.0
:Sending Pointer ACTION_MOVE x=-2.0 y=-4.0
如果Monkey测试顺利执行完成，在log的最后，会打印出当前执行事件的次数和所花费的时间；// Monkey finished代表执行完成。Monkey执行中断，在log的最后也能查看到当前已执行的次数。Monkey执行完成的log具体如下：
Events injected: 6000
:Dropped: keys=0 pointers=9 trackballs=0 flips=0
## Network stats: elapsed time=808384ms (0ms mobile, 808384ms wifi, 0msnot connected)
// Monkey finished
三、常见问题的具体分析
1、Null指针异常
空指针异常主要是有NullPointerException异常提示，在Monkey测试过程中，该信息一般记录在plog.log (locat中也有该信息)中。
如果Monkey命令被中断，说明有异常信息并且有对应的打印信息，可以看到如下信息，说明几个问题：
A、android.process.acore该进程出现异常
B、异常信息主要是由于NullPointerException引起的，也就是出现了空指针，导致了acore进程进入debug
C、具体的可以继续分析是由哪个函数的那一行导致的，如可以从rollAnimate3dRotate.java文件中的275行查找等。
//at com.android.launcher.LauncherAnimator.ScrollAnimate3dRotate.drawFrame(ScrollAnimate3dRotate.java:275)
D、可能同一应用中存在多处的空指针异常，所以一旦出现空指异常后，可以分析和对比log信息，如果不一致的话，需要把新的log信息也一同添加到bug中，如果log信息一直的话，则不需要继续补充没有必要的log信息。
**************************************************************************
// CRASH: android.process.acore (pid 1339)
// Short Msg: java.lang.NullPointerException
// Long Msg: java.lang.NullPointerException
// Build Label: android:FIH/msm7627_surf/msm7627_surf/F0X:1.6/DONUT/0001_0_020:e
ng/release-keys
// Build Changelist: -1
// Build Time: 1271397239
// ID:
// Tag: AndroidRuntime
// java.lang.NullPointerException:
//   at com.android.launcher.LauncherAnimator.ScrollAnimate3dRotate.drawFrame(ScrollAnimate3dRotate.java:275)
//   at com.android.launcher.Workspace.dispatchDraw(Workspace.java:605)
//   at android.view.ViewGroup.drawChild(ViewGroup.java:1524)
//   at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1256)
//   at com.android.launcher.DragLayer.dispatchDraw(DragLayer.java:258)
//   at android.view.ViewGroup.drawChild(ViewGroup.java:1524)
//   at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1256)
//   at android.view.View.draw(View.java:6329)
//   at android.widget.FrameLayout.draw(FrameLayout.java:352)
//   at android.view.ViewGroup.drawChild(ViewGroup.java:1526)
//   at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1256)
//   at android.view.View.draw(View.java:6329)
//   at android.widget.FrameLayout.draw(FrameLayout.java:352)
//   at com.android.internal.policy.impl.PhoneWindow$DecorView.draw(PhoneWindow.java:1901)
//   at android.view.ViewRoot.draw(ViewRoot.java:1333)
//   at android.view.ViewRoot.performTraversals(ViewRoot.java:1098)
//   at android.view.ViewRoot.handleMessage(ViewRoot.java:1618)
//   at android.os.Handler.dispatchMessage(Handler.java:99)
//   at android.os.Looper.loop(Looper.java:123)
//   at android.app.ActivityThread.main(ActivityThread.java:4203)
//   at java.lang.reflect.Method.invokeNative(Method.java:-2)
//   at java.lang.reflect.Method.invoke(Method.java:521)
//   at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:799)
//   at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:557)
//   at dalvik.system.NativeStart.main(NativeStart.java:-2)
** System appears to have crashed at event 7908 of 5000000 using seed 0
2、debug异常
debug异常主要是由于应用程序本身的错误导致的异常。在Monkey测试过程中，该信息同样记录在plog.log (locat中也有该信息)中。一般情况下，出现的该问题，很可能在手动测试时也可以测试到。
A、com.android.browser该进程进入debug
B、出现的是IllegalStateException异常，该异常一般多是传递的参数非法或被多次调用时出现的异常
C、Bug信息提交时，详细信息中一般应该包含如下的log信息，方便开发人员分析和定位。
************************************************************************
// CRASH: com.android.browser (pid 5683)
// Short Msg: Connection is not open
// Long Msg: java.lang.IllegalStateException: Connection is not open
// Build Label: android:FIH/msm7627_surf/msm7627_surf/F0X:1.6/DONUT/0001_0_020:eng/release-keys
// Build Changelist: -1
// Build Time: 1271397239
// ID:
// Tag: AndroidRuntime
// java.lang.IllegalStateException: Connection is not open
//   at android.net.http.AndroidHttpClientConnection.assertOpen(AndroidHttpClien
tConnection.java:153)
//   at android.net.http.AndroidHttpClientConnection.setSocketTimeout(AndroidHtt
pClientConnection.java:195)
//   at android.net.http.Connection.openHttpConnection(Connection.java:364)
//   at android.net.http.Connection.processRequests(Connection.java:225)
//   at android.net.http.ConnectionThread.run(ConnectionThread.java:116)
** System appears to have crashed at event 34155 of 5000000 using seed 0**
3、低内存异常
低内存异常一般情况下，主要表现在出现OutOfMemoryError异常或者提示Out of memory，其后果同样表现为抛出OutOfMemoryError异常或者是通过kill process 来杀掉部分进程以释放内存空间，当然如果被kill点关键的进程的话，也就可能导致部分应用会自动的退出。出现该情况时，主要是在进行频繁的进行大量的操作导致的，所以使用手动的方式也是可能进行重现的。
A、com.android.elastos.backup该进程出现的异常
B、出现的是OutOfMemoryError异常，该异常一般多频繁的操作(即多次调用某个函数，存在申请变量空间未释放)导致的
C、Bug信息提交时，详细信息中同样应该包含如下的log信息，方便开发人员分析和定位。
// CRASH: com.android.elastos.backup (pid 1581)
// Short Msg: bitmap size exceeds VM budget
// Long Msg: java.lang.OutOfMemoryError: bitmap size exceeds VM budget
// Build Label: android:FIH/msm7627_surf/msm7627_surf/F0X:1.6/DONUT/0001_0_020:eng/release-keys
// Build Changelist: -1
// Build Time: 1271397239
// ID:
// Tag: AndroidRuntime
// java.lang.OutOfMemoryError: bitmap size exceeds VM budget
//   at android.graphics.Bitmap.nativeCreate(Bitmap.java:-2)
//   at android.graphics.Bitmap.createBitmap(Bitmap.java:464)
//   at android.graphics.Bitmap.createBitmap(Bitmap.java:431)
//   at android.graphics.Bitmap.createScaledBitmap(Bitmap.java:336)
//   at android.widget.AbsListView.preScaleBitmapBG(AbsListView.java:2354)
//   at android.widget.AbsListView.draw(AbsListView.java:2365)
//   at android.view.ViewGroup.drawChild(ViewGroup.java:1526)
//   at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1256)
//   at android.view.View.draw(View.java:6329)
//   at android.view.ViewGroup.drawChild(ViewGroup.java:1526)
//   at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1256)
//   at android.view.View.draw(View.java:6329)
//   at android.widget.FrameLayout.draw(FrameLayout.java:352)
//   at android.view.ViewGroup.drawChild(ViewGroup.java:1526)
//   at android.view.ViewGroup.dispatchDraw(ViewGroup.java:1256)
//   at android.view.View.draw(View.java:6329)
//   at android.widget.FrameLayout.draw(FrameLayout.java:352)
//   at com.android.internal.policy.impl.PhoneWindow$DecorView.draw(PhoneWindow.java:1901)
//   at android.view.ViewRoot.draw(ViewRoot.java:1333)
//   at android.view.ViewRoot.performTraversals(ViewRoot.java:1098)
//   at android.view.ViewRoot.handleMessage(ViewRoot.java:1618)
//   at android.os.Handler.dispatchMessage(Handler.java:99)
//   at android.os.Looper.loop(Looper.java:123)
//   at android.app.ActivityThread.main(ActivityThread.java:4203)
//   at java.lang.reflect.Method.invokeNative(Method.java:-2)
//   at java.lang.reflect.Method.invoke(Method.java:521)
//   at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:799)
//   at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:557)
//   at dalvik.system.NativeStart.main(NativeStart.java:-2)
** System appears to have crashed at event 143290 of 100000000 using seed 0#
或者出现plog信息的最后出现如下log信息也可能存在同样问题：
<3>[56351.640641] Out of memory: kill process 14177 (app_process) score 247872 or a child
<3>[56351.641121] Killed process 14177 (app_process)
4、操作无响应异常
操作无响应的问题，主要表现在Monkey运行过程中，出现某功能无响应，提示是否“强制关闭“或“等待“，同时会打印出相应的log信息，如下：
A、可以说明com.android.soundrecorder该进程出现的无响应
B、出现的是TimerOut异常，该异常一般出现时一般都会又keyDispatchingTimedOut提示
C、目前，uPhone手机中经常出现操作无响应的几个模块包括：clock、camera、soundrecorder、broser等，根据开发人员分析其他模块也是有可能出现操作无响应的，测试过程中，请多注意。
D、出现无响应异常，一般情况下很难被修复，和发送事件的频率也是有关系的，从质量上来要求的话，要求尽可能的减少出现操作无响应的频率和时间。
// NOT RESPONDING: com.android.soundrecorder (pid 1931)
ANR in process: com.android.soundrecorder (last in com.android.soundrecorder)
Annotation: keyDispatchingTimedOut
CPU usage:
Load: 8.24 / 8.41 / 8.25
CPU usage from 35911ms to 83ms ago:
  com.android.soundrecorder: 4% = 3% user + 0% kernel / faults: 997 minor 43 maj
or
  mediaserver: 2% = 2% user + 0% kernel / faults: 267 minor 3 major
  android.process.media: 2% = 0% user + 1% kernel / faults: 563 minor 194 major
  system_server: 1% = 1% user + 0% kernel / faults: 1279 minor 5 major
  com.cootek.touchpal: 0% = 0% user + 0% kernel / faults: 180 minor 1 major
  sensorserver_ya: 0% = 0% user + 0% kernel / faults: 47 minor 1 major
  mmcqd: 0% = 0% user + 0% kernel
  app_process: 0% = 0% user + 0% kernel / faults: 203 minor 1 major
  com.fihtdc.brservice: 0% = 0% user + 0% kernel / faults: 29 minor
  kswapd0: 0% = 0% user + 0% kernel
  com.android.settings: 0% = 0% user + 0% kernel / faults: 266 minor
  port-bridge: 0% = 0% user + 0% kernel / faults: 19 minor
  rpcrouter: 0% = 0% user + 0% kernel
  com.android.mms: 0% = 0% user + 0% kernel / faults: 135 minor 8 major
TOTAL: 13% = 8% user + 4% kernel + 0% iowait


procrank:
  PID      Vss      Rss      Pss      Uss  cmdline
 1209   35368K   25376K   15283K   14036K  system_server
 1342   32788K   24596K   14899K   14136K  android.process.acore
 ……
// procrank status was 0
anr traces:
// meminfo status was 0
** System appears to have crashed at event 59034 of 5000000 using seed 0
5、其他异常
A、Restart System异常(系统重新启动)，即log信息的最后几行会看到Restart System的打印信息，说明手机被自动的重启或断掉，有几个方面的原因
1、运行过程中，存在异常手机被自动重启
2、手机在运行过程中，进入设置中的恢复出场设置，导致手机重启
3、其他原因，如果手机后盖移动等
B、RuntimeException等异常,同样的会在plog.log中可以看到相应的打印信息, java.lang.RuntimeException该问题多出现在操作无响应之后,或者是某应用的服务无法启动或连接时,需要具体问题具体的分析
C、StaleDataException、readException等异常, 在plog.log中可以看到相应的打印信息,也会有相应的信息: android.database.StaleDataException,该信息说明android的数据库操作出现异常, 需要具体问题具体的分析
D、IllegalArgumentException等异常，多少情况下为说明向函数传递了一个不正确或不合法的参数需要具体问题具体的分析
E、其他，带补充和分析说明

说明几点：
1、log信息的分析，希望在bug信息的简述中能够明确说明清楚出现的是什么异常，具体的log信息要单独添加到详细信息描述中，方便相关人员定位和查看。
2、buy的提交，对于Monkey测试出现的问题：
a、首先要求分析log信息
b、再次要确认该bug是否已经提交？已经提交的bug和新运行出来的问题是否一致？
c、如果不一致的话，是否属于同一问题，如空指针异常，是的话就把该问题的log信息一起注释到bug信息中，并添加所有的log信息
d、如果不一致的，也不属于同一问题的话，可以提交新的bug
3、在java编程中，一般的异常都会抛出xxx Exception的信息，可以跟进xxx进行相关的判断和分析，具体的异常则需要具体问题具体分析。


四.percent相关命令控制了事件类型，如果我们不指定事件类型。生成的报告中，事件占比大致如下：


// Event percentages:


// 0: 15.0%


// 1: 10.0%


// 2: 2.0%


// 3: 15.0%


// 4: -0.0%


// 5: 25.0%


// 6: 15.0%


// 7: 2.0%


// 8: 2.0%


// 9: 1.0%


// 10: 13.0%


这里将事件分为了10种类型，分别为：


0：触摸事件百分比，即参数--pct-touch


1：滑动事件百分比，即参数--pct-motion


2：缩放事件百分比，即参数--pct-pinchzoom


3：轨迹球事件百分比，即参数--pct-trackball


4：屏幕旋转事件百分比，


5：基本导航事件百分比，即参数--pct-nav


6：主要导航事件百分比，即参数--pct-majornav


7：系统事件百分比，即参数--pct-syskeys


8：Activity启动事件百分比，即参数--pct-appswitch


9：键盘翻转事件百分比，即参数--pct-flip


10：其他事件百分比，即参数--pct-anyevent


更为具体的类型信息，可以查看查看Monkey源码中的MonkeySourceRandom.java文件。


五.以下内容为android系统中的keycode值，在以后的调试中会经常需要查询：
KEYCODE_UNKNOWN=0;
KEYCODE_SOFT_LEFT=1;
KEYCODE_SOFT_RIGHT=2;
KEYCODE_HOME=3;
KEYCODE_BACK=4;
KEYCODE_CALL=5;
KEYCODE_ENDCALL=6;
KEYCODE_0=7;
KEYCODE_1=8;
KEYCODE_2=9;
KEYCODE_3=10;
KEYCODE_4=11;
KEYCODE_5=12;
KEYCODE_6=13;
KEYCODE_7=14;
KEYCODE_8=15;
KEYCODE_9=16;
KEYCODE_STAR=17;      //-----------------------------------
KEYCODE_POUND=18;
KEYCODE_DPAD_UP=19;
KEYCODE_DPAD_DOWN=20;
KEYCODE_DPAD_LEFT=21;
KEYCODE_DPAD_RIGHT=22;
KEYCODE_DPAD_CENTER=23;
KEYCODE_VOLUME_UP=24;
KEYCODE_VOLUME_DOWN=25;
KEYCODE_POWER=26;
KEYCODE_CAMERA=27;
KEYCODE_CLEAR=28;    //-----------------------------------
KEYCODE_A=29;
KEYCODE_B=30;
KEYCODE_C=31;
KEYCODE_D=32;
KEYCODE_E=33;
KEYCODE_F=34;
KEYCODE_G=35;
KEYCODE_H=36;
KEYCODE_I=37;
KEYCODE_J=38;
KEYCODE_K=39;
KEYCODE_L=40;
KEYCODE_M=41;
KEYCODE_N=42;
KEYCODE_O=43;
KEYCODE_P=44;
KEYCODE_Q=45;
KEYCODE_R=46;
KEYCODE_S=47;
KEYCODE_T=48;
KEYCODE_U=49;
KEYCODE_V=50;
KEYCODE_W=51;
KEYCODE_X=52;
KEYCODE_Y=53;
KEYCODE_Z=54;
KEYCODE_COMMA=55;    //-----------------,------------------
KEYCODE_PERIOD=56;    //-----------------.------------------
KEYCODE_ALT_LEFT=57;
KEYCODE_ALT_RIGHT=58;
KEYCODE_SHIFT_LEFT=59;
KEYCODE_SHIFT_RIGHT=60;
KEYCODE_TAB=61;
KEYCODE_SPACE=62;
KEYCODE_SYM=63;
KEYCODE_EXPLORER=64; //-----------------------------------
KEYCODE_ENVELOPE=65;  //---------------信封--------------------
KEYCODE_ENTER=66;    
KEYCODE_DEL=67;
KEYCODE_GRAVE=68;  //---------------坟墓--------------------
KEYCODE_MINUS=69;  //---------------负--------------------
KEYCODE_EQUALS=70;  //--------------等于（＝）---------------------
KEYCODE_LEFT_BRACKET=71;  //----------------------------
KEYCODE_RIGHT_BRACKET=72;  //----------------------------
KEYCODE_BACKSLASH=73;  //-------------反斜线\---------------
KEYCODE_SEMICOLON=74;  //--------------；--------------
KEYCODE_APOSTROPHE=75; //---------------’-------------
KEYCODE_SLASH=76;         //--------------/?--------------
KEYCODE_AT=77;           //------------------@---------------
KEYCODE_NUM=78;
KEYCODE_HEADSETHOOK=79;  //----------------------------
KEYCODE_FOCUS=80;//*Camera*focus
KEYCODE_PLUS=81;   //------------＋----------------
KEYCODE_MENU=82;
KEYCODE_NOTIFICATION=83;   //----------------------------
KEYCODE_SEARCH=84;
KEYCODE_MEDIA_PLAY_PAUSE=85;
KEYCODE_MEDIA_STOP=86;
KEYCODE_MEDIA_NEXT=87;
KEYCODE_MEDIA_PREVIOUS=88;
KEYCODE_MEDIA_REWIND=89;  //------------倒带----------------
KEYCODE_MEDIA_FAST_FORWARD=90;
KEYCODE_MUTE=91;  //-----------静音-----------------