六、日志分析:
1)正常情况
顺利执行完成, 在log的最后, 会打印出当前执行事件的次数和所花费的时间;// Monkey finished 代表执行完成
详细分析参考:
http://blog.sina.com.cn/s/blog_44d19b500102vefp.html
http://blog.csdn.net/huangyabin001/article/details/38442359
image
image
image
image
image
image
2)异常情况
image
image
image
其中adb shell procrank 查询各进程内存使用情况;adb shell cat /proc/meminfo 查看当前的内存情况...
image
Monkey 测试出现错误后,一般的分析步骤
看Monkey的日志 (为了进一步分析问题的来源,可以找到Log中出现问题处的前一次Switch,随后根据Log主要是查看相关的Activity)
1、程序无响应的问题:在日志中搜索 “ANR”
可能原因:当前有耗时操作在UI线程指定,导致卡UI了;在5秒内没有响应输入的事件(例如,按键按下,屏幕触摸);BroadcastReceiver在10秒内没有执行完毕
2、崩溃问题:在日志中搜索 “Exception” (如果出现空指针, NullPointerException) 肯定是有bug
3、搜索"crash" 、"error"
Monkey 执行中断, 在log最后也能看到当前执行次数;若以上步骤还不能定位问题,可以使用之前执行的monkey命令再执行一遍,注意seed值要一样
可能出现Crash的场景
1)图片放大缩小
2)输入框无限输入各种类型字符
3)快速操作
4)和系统的交互等等
什么时候会有Log文件的产生 ?
Log的产生大家都知道 , 大家也都知道通过DDMS来看log , 但什么时候会产生log文件呢 ?一般在如下几种情况会产生log文件 。
1,程序异常退出 , uncaused exception
2,程序强制关闭 ,Force Closed (简称FC)
3,程序无响应 , Application No Response (简称ANR) , 顺便,一般主线程超过5秒么有处理就会ANR
4,手动生成 。
拿到一个日志文件,要分成多段来看 。log文件很长,其中包含十几个小单元信息,但不要被吓到 ,事实上他主要由三大块儿组成 。
1,系统基本信息 ,包括 内存,CPU ,进程队列 ,虚拟内存 , 垃圾回收等信息 。------ MEMORY INFO (/proc/meminfo) ------
------ CPU INFO (top -n 1 -d 1 -m 30 -t) ------
------ PROCRANK (procrank) ------
------ VIRTUAL MEMORY STATS (/proc/vmstat) ------
------ VMALLOC INFO (/proc/vmallocinfo) ------
格式如下:
------ MEMORY INFO (/proc/meminfo) ------ MemTotal: 347076 kB MemFree: 56408 kB Buffers: 7192 kB Cached: 104064 kB SwapCached: 0 kB Active: 192592 kB Inactive: 40548 kB Active(anon): 129040 kB Inactive(anon): 1104 kB Active(file): 63552 kB Inactive(file): 39444 kB Unevictable: 7112 kB Mlocked: 0 kB SwapTotal: 0 kB SwapFree: 0 kB Dirty: 44 kB Writeback: 0 kB AnonPages: 129028 kB Mapped: 73728 kB Shmem: 1148 kB Slab: 13072 kB SReclaimable: 4564 kB SUnreclaim: 8508 kB KernelStack: 3472 kB PageTables: 12172 kB NFS_Unstable: 0 kB Bounce: 0 kB WritebackTmp: 0 kB CommitLimit: 173536 kB Committed_AS: 7394524 kB VmallocTotal: 319488 kB VmallocUsed: 90752 kB VmallocChunk: 181252 kB
2,时间信息 , 也是我们主要分析的信息 。
------ VMALLOC INFO (/proc/vmallocinfo) ------
------ EVENT INFO (/proc/vmallocinfo) ------
格式如下:
------ SYSTEM LOG (logcat -b system -v time -d *:v) ------ 01-15 16:41:43.671 W/PackageManager( 2466): Unknown permission com.wsomacp.permission.PROVIDER in package com.android.mms 01-15 16:41:43.671 I/ActivityManager( 2466): Force stopping package com.android.mms uid=10092 01-15 16:41:43.675 I/UsageStats( 2466): Something wrong here, didn't expect com.sec.android.app.twlauncher to be paused 01-15 16:41:44.108 I/ActivityManager( 2466): Start proc com.sec.android.widgetapp.infoalarm for service com.sec.android.widgetapp.infoalarm/.engine.DataService: pid=20634 uid=10005 gids={3003, 1015, 3002} 01-15 16:41:44.175 W/ActivityManager( 2466): Activity pause timeout for HistoryRecord{48589868 com.sec.android.app.twlauncher/.Launcher} 01-15 16:41:50.864 I/KeyInputQueue( 2466): Input event 01-15 16:41:50.866 D/KeyInputQueue( 2466): screenCaptureKeyFlag setting 0 01-15 16:41:50.882 I/PowerManagerService( 2466): Ulight 0->7|0 01-15 16:41:50.882 I/PowerManagerService( 2466): Setting target 2: cur=0.0 target=70 delta=4.6666665 nominalCurrentValue=0 01-15 16:41:50.882 I/PowerManagerService( 2466): Scheduling light animator! 01-15 16:41:51.706 D/PowerManagerService( 2466): enableLightSensor true 01-15 16:41:51.929 I/KeyInputQueue( 2466): Input event 01-15 16:41:51.933 W/WindowManager( 2466): No focus window, dropping: KeyEvent{action=0 code=26 repeat=0 meta=0 scancode=26 mFlags=9}
3,虚拟机信息 , 包括进程的,线程的跟踪信息,这是用来跟踪进程和线程具体点的好地方 。------ VM TRACES JUST NOW (/data/anr/traces.txt.bugreport: 2011-01-15 16:49:02) ------
------ VM TRACES AT LAST ANR (/data/anr/traces.txt: 2011-01-15 16:49:02) ------
格式如下 :
----- pid 21161 at 2011-01-15 16:49:01 ----- Cmd line: com.android.mms DALVIK THREADS: "main" prio=5 tid=1 NATIVE | group="main" sCount=1 dsCount=0 s=N obj=0x4001d8d0 self=0xccc8 | sysTid=21161 nice=0 sched=0/0 cgrp=default handle=-1345017808 | schedstat=( 4151552996 5342265329 10995 ) at android.media.MediaPlayer._reset(Native Method) at android.media.MediaPlayer.reset(MediaPlayer.java:1218) at android.widget.VideoView.release(VideoView.java:499) at android.widget.VideoView.access6.surfaceDestroyed(VideoView.java:489) at android.view.SurfaceView.reportSurfaceDestroyed(SurfaceView.java:572) at android.view.SurfaceView.updateWindow(SurfaceView.java:476) at android.view.SurfaceView.onWindowVisibilityChanged(SurfaceView.java:206) at android.view.View.dispatchDetachedFromWindow(View.java:6082) at android.view.ViewGroup.dispatchDetachedFromWindow(ViewGroup.java:1156) at android.view.ViewGroup.removeAllViewsInLayout(ViewGroup.java:2296) at android.view.ViewGroup.removeAllViews(ViewGroup.java:2254) at com.android.mms.ui.SlideView.reset(SlideView.java:687) at com.android.mms.ui.SlideshowPresenter.presentSlide(SlideshowPresenter.java:189) at com.android.mms.ui.SlideshowPresenterMethodAndArgsCaller.run(ZygoteInit.java:858) at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:616) at dalvik.system.NativeStart.main(Native Method)
闲话少说, 我总结了观察log文件的基本步骤 。1,如果是ANR问题 , 则搜索“ANR”关键词 。快速定位到关键事件信息 。
adb logcat -v >D:\leamon\333.log
2,如果是ForceClosed 和其它异常退出信息,则搜索"Fatal exception" 关键词, 快速定位到关键事件信息 。
3,定位到关键事件信息后 , 如果信息不够明确的,再去搜索应用程序包的虚拟机信息 ,查看具体的进程和线程跟踪的日志,来定位到代码 。
用这种方法,出现问题,根本不需要断点调试 , 直接定位到问题,屡试不爽 。
下面,我们就开始来分析这个例子的log 。
打开log文件 , 由于是ANR错误,因此搜索"ANR IN " 只能看见anr问题的大体信息无法看见堆栈,需要用adb pull /data/anr/traces.txt D:\leamontest\ 搜索 main 处为堆栈信息 。
关闭Monkey
在monkey运行中,断开USB连接,mokey仍可继续运行
1. ps命令查找进程
adb shell ps | grep monkey 返回来的第一个数字,即是monkey的进程号
2.kill 命令结束进程
adb shell kill [刚才查到的进程号]