Bug #118700
【AF】【EVT3】【Monkey】0608版本Monkey测试12小时后异常停止（4+27个APK）（1）
| Status: | CLOSED | Start date: | 2023-06-09 | |
|---|---|---|---|---|
| Priority: | High | Due date: | ||
| Assignee: | CD TEST-方永红 | % Done: | 0% | |
| Category: | CD-FW | |||
| Target version: | - | |||
| Need_Info: | -- | Found Version: | 0608 | |
| Resolution: | INVALID | Degrated: | -- | |
| Severity: | Critical | Verified Version: | ||
| Reproducibility: | Rarely | Fixed Version: | ||
| Test Type: | Monkey Test | Root cause: |
Description
【执行命令】
monkey --pkg-blacklist-file /sdcard/blackList.txt --hprof --throttle 500 -s 1234567 --pct-touch 35 --pct-motion 25 --pct-appswitch 30 --pct-pinchzoom 5 --pct-trackball 0 --pct-anyevent 5 --ignore-crashes --ignore-timeouts --ignore-security-exceptions --monitor-native-crashes --ignore-native-crashes --pct-syskeys 0 -v -v -v 12000000 2>/sdcard/error.txt 1>/sdcard/info.txt
【测试结果】
monkey异常停止，设备进入屏保
History
#1 Updated by CD TEST-方永红 about 2 years ago
- Priority changed from Normal to High
- Severity changed from Normal to Critical
#2 Updated by CD FW 曹覃刚 about 2 years ago
- Status changed from New to NEED_INFO
- Assignee changed from CD FW 曹覃刚 to CD TEST-方永红
■ 我的分析
------------------------
logcat/000468_persist_03141_230609_092800.log:1595:06-09 09:28:02.885 31506 31506 I Monkey : Sleeping for 500 milliseconds
logcat/000468_persist_03141_230609_092800.log:1913:06-09 09:28:03.386 31506 31506 I Monkey : :Sending Touch (ACTION_DOWN): 0:(1286.0,856.0)
logcat/000468_persist_03141_230609_092800.log:1916:06-09 09:28:03.389 31506 31506 I Monkey : :Sending Touch (ACTION_UP): 0:(1297.9231,854.6034)
logcat/000468_persist_03141_230609_092800.log:1918:06-09 09:28:03.390 31506 31506 I Monkey : Sleeping for 500 milliseconds
logcat/000468_persist_03144_230609_092806.log:10:06-09 09:28:06.845 31506 31632 W Monkey : ANR in com.smile.gifmaker (com.smile.gifmaker/com.yxcorp.gifshow.HomeActivity)
logcat/000468_persist_03144_230609_092806.log:11:06-09 09:28:06.845 31506 31632 W Monkey : PID: 11726
logcat/000468_persist_03144_230609_092806.log:12:06-09 09:28:06.845 31506 31632 W Monkey : Reason: Input dispatching timed out (1a360c com.smile.gifmaker/com.yxcorp.gifshow.HomeActivity (server) is
.....
logcat/000468_persist_03169_230609_093346.log:768:06-09 09:34:00.020 31506 31506 W Monkey : Sweep: Sum: 33us Avg: 1.833us
logcat/000468_persist_03169_230609_093346.log:780:06-09 09:34:00.125 31506 31506 W Monkey : ResumeOtherThreads: Sum: 5us Avg: 277ns
logcat/000468_persist_03169_230609_093346.log:781:06-09 09:34:00.228 31506 31506 W Monkey : Done Dumping Averages
logcat/000468_persist_03169_230609_093346.log:782:06-09 09:34:00.332 31506 31506 W Monkey : concurrent copying paused: Sum: 1.304ms 99% C.I. 7us-130us Avg: 36.222us Max: 130us
logcat/000468_persist_03169_230609_093346.log:789:06-09 09:34:00.436 31506 31506 W Monkey : concurrent copying freed-bytes: Avg: 253KB Max: 1189KB Min: 31KB
logcat/000468_persist_03169_230609_093346.log:800:06-09 09:34:00.541 31506 31506 W Monkey : Freed-bytes histogram: 0:12,320:4,640:1,960:1
logcat/000468_persist_03169_230609_093346.log:805:06-09 09:34:00.644 31506 31506 W Monkey : concurrent copying total time: 250.864ms mean time: 13.936ms
logcat/000468_persist_03169_230609_093346.log:812:06-09 09:34:00.748 31506 31506 W Monkey : concurrent copying freed: 45846 objects with total size 4580KB
logcat/000468_persist_03169_230609_093346.log:818:06-09 09:34:00.852 31506 31506 W Monkey : concurrent copying throughput: 183384/s / 17MB/s per cpu-time: 28599804/s / 27MB/s
logcat/000468_persist_03169_230609_093346.log:828:06-09 09:34:00.956 31506 31506 W Monkey : concurrent copying tracing throughput: 28MB/s per cpu-time: 43MB/s
logcat/000468_persist_03169_230609_093346.log:830:06-09 09:34:01.060 31506 31506 W Monkey : Average major GC reclaim bytes ratio 0.129075 over 18 GC cycles
logcat/000468_persist_03169_230609_093346.log:837:06-09 09:34:01.164 31506 31506 W Monkey : Average major GC copied live bytes ratio 0.5996 over 23 major GCs
logcat/000468_persist_03169_230609_093346.log:838:06-09 09:34:01.268 31506 31506 W Monkey : Cumulative bytes moved 33753696
logcat/000468_persist_03169_230609_093346.log:853:06-09 09:34:01.372 31506 31506 W Monkey : Cumulative objects moved 634454
logcat/000468_persist_03169_230609_093346.log:857:06-09 09:34:01.476 31506 31506 W Monkey : Peak regions allocated 47 (11MB) / 1024 (256MB)
logcat/000468_persist_03169_230609_093346.log:868:06-09 09:34:01.584 31506 31506 W Monkey : Total madvise time 6.511ms
logcat/000468_persist_03169_230609_093346.log:880:06-09 09:34:01.696 31506 31506 W Monkey : Start Dumping Averages for 2 iterations for young concurrent copying
logcat/000468_persist_03169_230609_093346.log:910:06-09 09:34:01.808 31506 31506 W Monkey : ScanImmuneSpaces: Sum: 10.714ms Avg: 5.357ms
logcat/000468_persist_03169_230609_093346.log:917:06-09 09:34:01.920 31506 31506 W Monkey : VisitConcurrentRoots: Sum: 2.916ms Avg: 1.458ms
logcat/000468_persist_03169_230609_093346.log:926:06-09 09:34:02.032 31506 31506 W Monkey : ScanCardsForSpace: Sum: 2.060ms Avg: 1.030ms
logcat/000468_persist_03169_230609_093346.log:931:06-09 09:34:02.144 31506 31506 W Monkey : GrayAllDirtyImmuneObjects: Sum: 2.046ms Avg: 1.023ms
logcat/000468_persist_03169_230609_093346.log:938:06-09 09:34:02.256 31506 31506 W Monkey : FlipOtherThreads: Sum: 1.302ms Avg: 651us
logcat/000468_persist_03169_230609_093346.log:939:06-09 09:34:02.368 31506 31506 W Monkey : InitializePhase: Sum: 1.209ms Avg: 604.500us
logcat/000468_persist_03169_230609_093346.log:940:06-09 09:34:02.480 31506 31506 W Monkey : ProcessMarkStack: Sum: 818us Avg: 409us
logcat/000468_persist_03169_230609_093346.log:951:06-09 09:34:02.592 31506 31506 W Monkey : SweepSystemWeaks: Sum: 548us Avg: 274us
logcat/000468_persist_03169_230609_093346.log:962:06-09 09:34:02.704 31506 31506 W Monkey : ClearFromSpace: Sum: 500us Avg: 250us
logcat/000468_persist_03169_230609_093346.log:969:06-09 09:34:02.816 31506 31506 W Monkey : SweepArray: Sum: 125us Avg: 62.500us
logcat/000468_persist_03169_230609_093346.log:972:06-09 09:34:02.928 31506 31506 W Monkey : ThreadListFlip: Sum: 75us Avg: 37.500us
logcat/000468_persist_03169_230609_093346.log:975:06-09 09:34:03.040 31506 31506 W Monkey : EnqueueFinalizerReferences: Sum: 64us Avg: 32us
logcat/000468_persist_03169_230609_093346.log:982:06-09 09:34:03.152 31506 31506 W Monkey : RecordFree: Sum: 43us Avg: 21.500us
logcat/000468_persist_03169_230609_093346.log:989:06-09 09:34:03.264 31506 31506 W Monkey : CopyingPhase: Sum: 43us Avg: 21.500us
logcat/000468_persist_03169_230609_093346.log:990:06-09 09:34:03.376 31506 31506 W Monkey : VisitNonThreadRoots: Sum: 43us Avg: 21.500us
logcat/000468_persist_03169_230609_093346.log:992:06-09 09:34:03.488 31506 31506 W Monkey : (Paused)GrayAllNewlyDirtyImmuneObjects: Sum: 35us Avg: 17.500us
logcat/000468_persist_03169_230609_093346.log:997:06-09 09:34:03.600 31506 31506 W Monkey : ForwardSoftReferences: Sum: 32us Avg: 16us
logcat/000468_persist_03169_230609_093346.log:1012:06-09 09:34:03.712 31506 31506 W Monkey : (Paused)ClearCards: Sum: 25us Avg: 12.500us
logcat/000468_persist_03169_230609_093346.log:1024:06-09 09:34:03.824 31506 31506 W Monkey : EmptyRBMarkBitStack: Sum: 20us Avg: 10us
logcat/000468_persist_03169_230609_093346.log:1027:06-09 09:34:03.936 31506 31506 W Monkey : MarkZygoteLargeObjects: Sum: 18us Avg: 9us
logcat/000468_persist_03169_230609_093346.log:1034:06-09 09:34:04.048 31506 31506 W Monkey : ProcessReferences: Sum: 17us Avg: 8.500us
-------------------------
①在09:32:46时进入屏保
logcat/000468_persist_03166_230609_093245.log:135:06-09 09:32:46.489 31506 31631 I Monkey : // Allowing start of Intent { pkg=com.thundercomm.screensaver cmp=com.thundercomm.screensaver/android.service.dreams.DreamActivity } in package com.thundercomm.screensaver
②从09:28:02最后一次注入事件，之后因为发生anr，从09:28到09:34之间monkey一直在dump信息，没有注入事件，但是并没有结束
③与测试沟通，在09:30左右，由于显示了屏保，认为monkey停了，adb kill monkey了
综上分析，monkey并没有停止，只是在dump信息时，被手动杀掉了，因此并没有异常停止，请确认
#3 Updated by CD TEST-方永红 about 2 years ago
- Status changed from NEED_INFO to RESOLVED
- Resolution changed from -- to INVALID
#4 Updated by CD TEST-方永红 about 2 years ago
- Status changed from RESOLVED to VERIFIED
#5 Updated by CD TEST-方永红 about 2 years ago
- Status changed from VERIFIED to CLOSED