Bug #115842

测试 Test-ST #113421: V4.0功能与专项测试

测试 Test-ST #113422: V4.0专项--AF专项--monkey测试

【AF】【EVT2】【Monkey】Monkey测试6小时，出现ANR: com.android.gallery3d（3）

Added by CD Test赵飞 over 2 years ago. Updated over 2 years ago.

Status:CLOSEDStart date:2023-02-03
Priority:NormalDue date:2023-03-15
Assignee:CD Test赵飞% Done:

100%

Category:CD-APP
Target version:-
Need_Info:-- Found Version:FlatBuild_HH_VX1_MCE_FSE.M.D.user.01.00.X101.202302030336
Resolution:DUPLICATE Degrated:--
Severity:Normal Verified Version:
Reproducibility:Frequently Fixed Version:
Test Type:Monkey Test Root cause:与116540产生原因相同

Description

【执行命令】

adb shell
monkey --pkg-blacklist-file /sdcard/blacklist.txt --ignore-crashes --ignore-timeouts --ignore-security-exceptions --monitor-native-crashes --ignore-native-crashes -s 20 --throttle 500 --pct-touch 30 --pct-motion 15 --pct-nav 15 --pct-majornav 15 --pct-syskeys 5 --pct-appswitch 10 --pct-anyevent 10 --ignore-crashes -v -v -v 43200 2>/sdcard/error.txt 1>/sdcard/info.txt

【错误信息】

// NOT RESPONDING: com.android.gallery3d (pid 4036)
ANR in com.android.gallery3d (com.android.gallery3d/.filtershow.FilterShowActivity)
PID: 4036
Reason: Input dispatching timed out (Application does not have a focused window)
Parent: com.android.gallery3d/.filtershow.FilterShowActivity
Frozen: false
Load: 0.0 / 0.0 / 0.0
----- Output from /proc/pressure/memory -----
some avg10=0.00 avg60=0.00 avg300=0.00 total=0
full avg10=0.00 avg60=0.00 avg300=0.00 total=0
----- End output from /proc/pressure/memory -----

CPU usage from 146308ms to 0ms ago (2023-02-02 02:35:21.195 to 2023-02-02 02:37:47.502):
  15% 836/vendor.qti.hardware.display.composer-service: 9.8% user + 5.6% kernel / faults: 511 minor
  11% 1513/system_server: 7.7% user + 3.8% kernel / faults: 270672 minor
  11% 980/surfaceflinger: 7.9% user + 3.5% kernel / faults: 1518 minor
  11% 2350/com.android.systemui: 9.6% user + 1.4% kernel / faults: 48693 minor
  6.3% 3070/com.android.deskclock: 5% user + 1.2% kernel / faults: 14278 minor
  2.4% 2952/com.android.settings: 2% user + 0.4% kernel / faults: 34952 minor
  2% 2848/com.android.launcher3: 1.4% user + 0.6% kernel / faults: 7826 minor
  1.4% 3918/com.android.calendar: 1.1% user + 0.3% kernel / faults: 4098 minor
  1.4% 4963/adbd: 0.4% user + 0.9% kernel / faults: 5 minor
  1.3% 503/logd: 0.3% user + 1% kernel / faults: 175 minor
  1.3% 335/kworker/u16:14-kverityd: 0% user + 1.3% kernel
  1.2% 470/kworker/u16:16-kverityd: 0% user + 1.2% kernel
  1.1% 743/crtc_commit:125: 0% user + 1.1% kernel
  0.8% 4036/com.android.gallery3d: 0.6% user + 0.1% kernel / faults: 30865 minor
...

【日志】
ftp://cdiot@192.168.87.46/Pre_figure/Test_Log/Bug_115842/Tlog.zip

Related issues

Related to Figure - Bug #116540: 【AF】【EVT3】【Monkey】Monkey测试12小时，出现ANR: com.android.setting... CLOSED 2023-02-24 2023-03-15

History

#1 Updated by CD Test赵飞 over 2 years ago

  • Description updated (diff)

#2 Updated by CD APP-王营 over 2 years ago

  • Status changed from New to ASSIGNED
  • Assignee changed from CD APP-王营 to CD SYSTEM-赵正军

■ Current conclusion
正在分析中
■ My analysis
从目前的trace文件中 暂时没能分析出体现应用内anr的问题点，其帮忙看看是否需要其他抓取log的方式

"main" prio=5 tid=1 Native | group="main" sCount=1 ucsCount=0 flags=1 obj=0x71520948 self=0xb400006ee0ad5010 | sysTid=4036 nice=0 cgrp=default sched=0/0 handle=0x70a9b364f8 | state=S schedstat=( 929864789 291681789 3143 ) utm=66 stm=26 core=0 HZ=100 | stack=0x7ff86ef000-0x7ff86f1000 stackSize=8188KB | held mutexes=
native: #00 pc 00000000000a2c4c /apex/com.android.runtime/lib64/bionic/libc.so (__epoll_pwait+12)
native: #01 pc 0000000000017ea8 /system/lib64/libutils.so (android::Looper::pollInner(int)+184)
native: #02 pc 0000000000017d84 /system/lib64/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+116)
native: #03 pc 0000000000152d3c /system/lib64/libandroid_runtime.so (android::android_os_MessageQueue_nativePollOnce(_JNIEnv*, _jobject*, long, int)+48)
at android.os.MessageQueue.nativePollOnce(Native method)
at android.os.MessageQueue.next(MessageQueue.java:335)
at android.os.Looper.loopOnce(Looper.java:161)
at android.os.Looper.loop(Looper.java:288)
at android.app.ActivityThread.main(ActivityThread.java:7880)
at java.lang.reflect.Method.invoke(Native method)
at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:1009)

■ Next action
请System同事帮忙分析一下Tlog来定位一下问题产生的范围。

#3 Updated by CD FW周平 over 2 years ago

  • Assignee changed from CD SYSTEM-赵正军 to CD TPM-王祥林

To 祥林
结论： 从log看和GameMode比较相关，所以需要关闭Gamemode再做测试：

1， 在com.android.gallery3d启动过程中，一直需要notify gamemode， 然而这个是gamemode却不是on的状态。
Game mode工作log：
02-02 02:37:41.852 2543 4281 I GameMode_ActivityMonitoringService: Notifying about top task: TaskInfoContainer [topActivity=ComponentInfo{com.android.gallery3d/com.android.gallery3d.filtershow.FilterShowActivity}, taskId=25, stackId=25, userId=0, displayId=0, position=17
02-02 02:37:41.852 2543 4281 I GameMode_ActivityMonitoringService: WindowMode: 1
02-02 02:37:41.852 2543 4281 E GameMode_ActivityMonitoringService: onActivityLaunch:com.android.gallery3d
02-02 02:37:41.852 2543 4281 V GameMode_GameTypeUtils: judge isGame:com.android.gallery3d
02-02 02:37:41.852 2543 4281 V GameMode_GpuUtils: fps:60

Game mode状态：
02-02 02:37:41.861 1513 1513 I GameModeConfig: isGameModeOn = false
02-02 02:37:41.861 2543 2543 D GameMode_GameModeService: updateGlobalSettings = gamemode_isgaming
02-02 02:37:41.861 1513 1513 I GameModeConfig: isGameModeOn = false
02-02 02:37:41.861 1513 1513 I GameModeConfig: isDisableVoice = true

2， 在com.android.deskclock启动过程中，一直需要notify gamemode， 然而这个是gamemode却不是on的状态。
Game mode工作log：
02-02 02:38:45.389 2543 4281 I GameMode_ActivityMonitoringService: Updating top task to: TaskInfoContainer [topActivity=ComponentInfo{com.android.deskclock/com.android.deskclock.DeskClock}, taskId=21, stackId=21, userId=0, displayId=0, position=17
02-02 02:38:45.389 2543 4281 I GameMode_ActivityMonitoringService: Notifying about top task: TaskInfoContainer [topActivity=ComponentInfo{com.android.deskclock/com.android.deskclock.DeskClock}, taskId=21, stackId=21, userId=0, displayId=0, position=17
02-02 02:38:45.389 2543 4281 I GameMode_ActivityMonitoringService: WindowMode: 1
02-02 02:38:45.389 2543 4281 E GameMode_ActivityMonitoringService: onActivityLaunch:com.android.deskclock
02-02 02:38:45.389 2543 4281 V GameMode_GameTypeUtils: judge isGame:com.android.deskclock

Game mode状态：
02-02 02:38:45.396 1513 1513 I GameModeConfig: isGameModeOn = false
02-02 02:38:45.396 1513 1513 I GameModeConfig: isGameModeOn = false
02-02 02:38:45.396 1513 1513 I GameModeConfig: isDisableVoice = true

#4 Updated by CD TPM-王祥林 over 2 years ago

  • Assignee changed from CD TPM-王祥林 to CD APP-王营

#5 Updated by CD APP-王营 over 2 years ago

  • Due date set to 2023-02-15

#6 Updated by CD APP-王营 over 2 years ago

  • Due date changed from 2023-02-15 to 2023-02-17

#7 Updated by CD APP-王营 over 2 years ago

  • Due date changed from 2023-02-17 to 2023-02-28

#8 Updated by CD APP-王营 over 2 years ago

  • Due date changed from 2023-02-28 to 2023-03-15

#9 Updated by CD APP-王营 over 2 years ago

  • Status changed from ASSIGNED to NEED_INFO
  • Assignee changed from CD APP-王营 to CD Test赵飞

to 赵飞
当前ANR问题原因相同，都在票#116540上跟踪和更新状态，请关闭此票。

#10 Updated by CD Test赵飞 over 2 years ago

  • Status changed from NEED_INFO to RESOLVED
  • % Done changed from 0 to 100
  • Resolution changed from -- to DUPLICATE
  • Root cause set to 与116540产生原因相同

与116540产生原因相同，在116540中跟踪，关闭此问题

#11 Updated by CD Test赵飞 over 2 years ago

  • Status changed from RESOLVED to VERIFIED

#12 Updated by CD Test赵飞 over 2 years ago

  • Status changed from VERIFIED to CLOSED

Also available in: Atom PDF