caroline informational PFQ failed cheets_MediaPlayerVideoHWDecodeUsed HWTest |
||||
Issue descriptionIt started failing since https://cros-goldeneye.corp.google.com/chromeos/healthmonitoring/buildDetails?buildbucketId=8926700398435243408 and has failed for two consecutive runs. See the test suite for logs: https://pantheon.corp.google.com/storage/browser/chromeos-autotest-results/268733082-chromeos-test/chromeos6-row2-rack23-host14/cheets_MediaPlayerVideoHWDecodeUsed/ Selected error messages: 12/19 10:17:56.384 DEBUG|histogram_verifier:0268| begin histograms/Media.GpuArcVideoDecodeAccelerator.InitializeResult: {} raw_text: h 12/19 10:17:56.385 DEBUG| utils:0219| Running 'android-sh -c 'getprop ro.data_mounted'' 12/19 10:17:56.385 DEBUG| global_hooks:0056| "android-sh -c 'getprop ro.data_mounted'" 12/19 10:17:56.414 DEBUG| utils:0219| Running 'adb get-state' 12/19 10:17:56.414 DEBUG| global_hooks:0056| 'adb get-state' 12/19 10:17:56.422 DEBUG| arc:0080| adb get-state: device 12/19 10:17:56.422 DEBUG| utils:0219| Running 'adb shell 'pm grant org.chromium.arc.testapp.video android.permission.READ_EXTERNAL_STORAGE'' 12/19 10:17:56.423 DEBUG| global_hooks:0056| "adb shell 'pm grant org.chromium.arc.testapp.video android.permission.READ_EXTERNAL_STORAGE'" 12/19 10:17:56.849 DEBUG| utils:0219| Running 'android-sh -c 'getprop ro.data_mounted'' 12/19 10:17:56.849 DEBUG| global_hooks:0056| "android-sh -c 'getprop ro.data_mounted'" 12/19 10:17:56.877 DEBUG| utils:0219| Running 'adb get-state' 12/19 10:17:56.877 DEBUG| global_hooks:0056| 'adb get-state' 12/19 10:17:56.883 DEBUG| arc:0080| adb get-state: device 12/19 10:17:56.883 DEBUG| utils:0219| Running 'adb shell 'am start --activity-clear-top --es PATH /sdcard/720_h264.mp4 org.chromium.arc.testapp.video/.MainActivity'' 12/19 10:17:56.884 DEBUG| global_hooks:0056| "adb shell 'am start --activity-clear-top --es PATH /sdcard/720_h264.mp4 org.chromium.arc.testapp.video/.MainActivity'" 12/19 10:17:57.313 DEBUG| utils:0219| Running 'android-sh -c 'getprop ro.data_mounted'' 12/19 10:17:57.313 DEBUG| global_hooks:0056| "android-sh -c 'getprop ro.data_mounted'" 12/19 10:17:57.338 DEBUG| utils:0219| Running 'adb get-state' 12/19 10:17:57.339 DEBUG| global_hooks:0056| 'adb get-state' 12/19 10:17:57.347 DEBUG| arc:0080| adb get-state: device 12/19 10:17:57.347 DEBUG| utils:0219| Running 'adb shell 'input keyevent 126'' 12/19 10:17:57.348 DEBUG| global_hooks:0056| "adb shell 'input keyevent 126'" 12/19 10:17:57.837 DEBUG| inspector_backend:0068| InspectorBackend._Connect() to ws://127.0.0.1:37848/devtools/page/D4CC2665BEE4B49944B9FA582C79D766 12/19 10:17:57.840 DEBUG|inspector_websocke:0117| sent [{ "id": 0, "method": "Log.enable" }] 12/19 10:18:07.854 DEBUG| global_hooks:0056| ['sh', '-c', '/bin/ps --no-headers -A -o pid,ppid,args:4096,state'] 12/19 10:18:07.898 DEBUG| cros_interface:0378| ListProcesses(<predicate>)->[311 processes] 12/19 10:18:07.900 INFO | helper_logger:0095| videobuf2 log disable : echo 0 > /sys/module/videobuf2_core/parameters/debug 12/19 10:18:07.901 DEBUG| global_hooks:0056| 'echo 0 > /sys/module/videobuf2_core/parameters/debug' 12/19 10:18:07.904 DEBUG| test:0381| Test failed due to ******************************************************************************** (/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:513 _ConvertExceptionFromInspectorWebsocket) The app is probably crashed: 12/19 10:18:08.061 DEBUG| arc:0581| Recent activities dump: ACTIVITY MANAGER RECENT TASKS (dumpsys activity recents) Recent tasks: * Recent #0: TaskRecord{f90e9c1 #1 A=org.chromium.arc.home U=0 StackId=0 sz=1} userId=0 effectiveUid=u0a46 mCallingUid=0 mUserSetupComplete=false mCallingPackage=null affinity=org.chromium.arc.home intent={act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10000100 cmp=org.chromium.arc.home/.HomeActivity} realActivity=org.chromium.arc.home/.HomeActivity autoRemoveRecents=false isPersistable=true numFullscreen=1 taskType=1 mTaskToReturnTo=1 rootWasReset=false mNeverRelinquishIdentity=true mReuseTask=false mLockTaskAuth=LOCK_TASK_AUTH_PINNABLE Activities=[ActivityRecord{b094051 u0 org.chromium.arc.home/.HomeActivity t1}] askedCompatMode=false inRecents=true isAvailable=true lastThumbnail=null lastThumbnailFile=/data/system_ce/0/recent_images/1_task_thumbnail.png stackId=0 hasBeenVisible=true mResizeMode=RESIZE_MODE_FORCE_RESIZEABLE isResizeable=false firstActiveTime=1545243477844 lastActiveTime=1545243477844 (inactive for 10s) Arc Window State: mWindowState=WINDOW_STATE_MAXIMIZED mRestoreBounds=Rect(0, 0 - 0, 0) * Recent #1: TaskRecord{4328566 #2 A=org.chromium.arc.testapp.video U=0 StackId=2 sz=1} userId=0 effectiveUid=u0a51 mCallingUid=2000 mUserSetupComplete=true mCallingPackage=null affinity=org.chromium.arc.testapp.video intent={act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x14000000 cmp=org.chromium.arc.testapp.video/.MainActivity} realActivity=org.chromium.arc.testapp.video/.MainActivity autoRemoveRecents=false isPersistable=true numFullscreen=1 taskType=0 mTaskToReturnTo=1 rootWasReset=false mNeverRelinquishIdentity=true mReuseTask=false mLockTaskAuth=LOCK_TASK_AUTH_PINNABLE Activities=[ActivityRecord{4567ec4 u0 org.chromium.arc.testapp.video/.MainActivity t2}] askedCompatMode=false inRecents=true isAvailable=true lastThumbnail=null lastThumbnailFile=/data/system_ce/0/recent_images/2_task_thumbnail.png stackId=2 hasBeenVisible=true mResizeMode=RESIZE_MODE_FORCE_RESIZEABLE isResizeable=true firstActiveTime=1545243477843 lastActiveTime=1545243477843 (inactive for 10s) Arc Window State: mWindowState=WINDOW_STATE_NORMAL mRestoreBounds=Rect(0, 0 - 0, 0) Seems there is a ARC side crash. Assign to ARC constable for investigation.
,
Dec 20
I took a look, since I'm substituting for yusukes@ for pst constable duty. It looks like the failure in the app was caused by the media player not finding the file it was supposed to play: 12-19 10:17:57.433 1620 1620 W MediaPlayer: Couldn't open /sdcard/720_h264.mp4: java.io.FileNotFoundException: No content provider: /sdcard/720_h264.mp4 I'm not familiar enough with the test to know how that file ends up on the DUT. I didn't see any obvious mention of it being copied in the other autotest logs. I'll track down someone that does know more.
,
Dec 20
,
Dec 20
From what I saw in the test script, the file is supposed to be copied from /usr/local/autotest/cros/video to $ANDROID_DATA_ROOT/data/media/0 in the CrOS filesystem. The destination path is apparently mounted as /sdcard/ inside the container, so assuming the file did get copied, it should have been found. I didn't see a clear indication of what went wrong though. If the file was copied, and there were just no messages logged that it was happening, then this may be a problem with the mount. Otherwise it could be some simple error like the video file having been moved, or something similar. Except that video_ChromeHWDecodeUsed.h264 appears to use the same file, and seems to be green, so that doesn't seem right. It's late my time, either edcourtney@ will pick this up, or yusukes@ will pick it up tomorrow.
,
Dec 20
I had a look and was unable to repro locally and couldn't find anything illuminating in the logs, unfortunately. Let me pass back to PST constable.
,
Dec 20
After 3 consecutive failed runs, caroline now cycled green. So I guess this bug was fixed silently?
,
Dec 20
I'd close this for now. Please reopen if this happens again. |
||||
►
Sign in to add a comment |
||||
Comment 1 by x...@chromium.org
, Dec 20Owner: edcourtney@chromium.org