Intermittent UI test failure - < MediaPlaybackTest.hiddenVideoControlsContextMenuTest>

[AndiAJ]

Firebase Test Run: Firebase link

Stacktrace:

06-27 08:07:30.221: I/WindowManager(478): Input event dispatching timed out sending to org.mozilla.reference.browser.debug/org.mozilla.reference.browser.BrowserActivity. Reason: 6c85b63 org.mozilla.reference.browser.debug/org.mozilla.reference.browser.BrowserActivity (server) is not responding. Waited 5001ms for MotionEvent(deviceId=-1, source=0x00001002, displayId=0, action=MOVE, actionButton=0x00000000, flags=0x00000000, metaState=0x00000000, buttonState=0x00000000, classification=NONE, edgeFlags=0x00000000, xPrecision=1.0, yPrecision=1.0, xCursorPosition=nan, yCursorPosition=nan, pointers=[0: (185.0, 248.0)]), policyFlags=0x6b000000 06-27 08:07:30.268: D/CCodec(16185): allocate(c2.android.aac.decoder) 06-27 08:07:30.278: I/Codec2Client(16185): Available Codec2 services: "software" 06-27 08:07:30.281: V/C2Store(422): in init 06-27 08:07:30.282: V/C2Store(422): loading dll 06-27 08:07:30.352: I/CCodec(16185): Created component [c2.android.aac.decoder] 06-27 08:07:30.353: D/CCodecConfig(16185): read media type: audio/mp4a-latm 06-27 08:07:30.367: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: algo.buffers.max-count.values 06-27 08:07:30.368: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: output.subscribed-indices.values 06-27 08:07:30.372: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: input.buffers.allocator-ids.values 06-27 08:07:30.372: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: output.buffers.allocator-ids.values 06-27 08:07:30.377: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: algo.buffers.allocator-ids.values 06-27 08:07:30.379: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: output.buffers.pool-ids.values 06-27 08:07:30.379: D/ReflectedParamUpdater(16185): extent() != 1 for single value type: algo.buffers.pool-ids.values 06-27 08:07:30.387: I/CCodecConfig(16185): query failed after returning 19 values (BAD_INDEX) 06-27 08:07:30.387: D/CCodecConfig(16185): c2 config diff is Dict { 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 coded.aac-packaging.value = 0 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 coded.bitrate.value = 64000 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 coded.pl.level = 0 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 coded.pl.profile = 8192 06-27 08:07:30.387: D/CCodecConfig(16185): c2::i32 coding.drc.album-mode.value = 0 06-27 08:07:30.387: D/CCodecConfig(16185): c2::float coding.drc.attenuation-factor.value = 1 06-27 08:07:30.387: D/CCodecConfig(16185): c2::float coding.drc.boost-factor.value = 1 06-27 08:07:30.387: D/CCodecConfig(16185): c2::i32 coding.drc.compression-mode.value = 3 06-27 08:07:30.387: D/CCodecConfig(16185): c2::i32 coding.drc.effect-type.value = 3 06-27 08:07:30.387: D/CCodecConfig(16185): c2::float coding.drc.encoded-level.value = 0.25 06-27 08:07:30.387: D/CCodecConfig(16185): c2::float coding.drc.reference-level.value = -16 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 input.buffers.max-size.value = 8192 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 input.delay.value = 0 06-27 08:07:30.387: D/CCodecConfig(16185): string input.media-type.value = "audio/mp4a-latm" 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 output.delay.value = 2 06-27 08:07:30.387: D/CCodecConfig(16185): c2::float output.drc.output-loudness.value = 0.25 06-27 08:07:30.387: D/CCodecConfig(16185): string output.media-type.value = "audio/raw" 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 raw.channel-count.value = 1 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 raw.max-channel-count.value = 8 06-27 08:07:30.387: D/CCodecConfig(16185): c2::u32 raw.sample-rate.value = 44100 06-27 08:07:30.387: D/CCodecConfig(16185): } 06-27 08:07:30.389: I/MediaCodec(16185): MediaCodec will operate in async mode 06-27 08:07:30.390: D/CCodec(16185): [c2.android.aac.decoder] buffers are bound to CCodec for this session 06-27 08:07:30.390: D/CCodecConfig(16185): no c2 equivalents for flags 06-27 08:07:30.391: D/CCodecConfig(16185): c2 config diff is c2::u32 raw.channel-count.value = 2 06-27 08:07:30.391: D/CCodecConfig(16185): c2::u32 raw.sample-rate.value = 48000 06-27 08:07:30.392: W/Codec2Client(16185): query -- param skipped: index = 1107298332. 06-27 08:07:30.392: D/CCodec(16185): setup formats input: AMessage(what = 0x00000000) = { 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-album-mode = 0 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-boost-level = 127 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-cut-level = 127 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-effect-type = 3 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-heavy-compression = 3 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-encoded-target-level = -1 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-max-output-channel_count = 8 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-target-ref-level = 64 06-27 08:07:30.392: D/CCodec(16185): int32_t channel-count = 2 06-27 08:07:30.392: D/CCodec(16185): int32_t level = 0 06-27 08:07:30.392: D/CCodec(16185): int32_t max-input-size = 8192 06-27 08:07:30.392: D/CCodec(16185): string mime = "audio/mp4a-latm" 06-27 08:07:30.392: D/CCodec(16185): int32_t profile = 2 06-27 08:07:30.392: D/CCodec(16185): int32_t sample-rate = 48000 06-27 08:07:30.392: D/CCodec(16185): } and output: AMessage(what = 0x00000000) = { 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-album-mode = 0 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-boost-level = 127 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-cut-level = 127 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-effect-type = 3 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-heavy-compression = 3 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-drc-output-loudness = -1 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-encoded-target-level = -1 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-max-output-channel_count = 8 06-27 08:07:30.392: D/CCodec(16185): int32_t aac-target-ref-level = 64 06-27 08:07:30.392: D/CCodec(16185): int32_t channel-count = 2 06-27 08:07:30.392: D/CCodec(16185): string mime = "audio/raw" 06-27 08:07:30.392: D/CCodec(16185): int32_t sample-rate = 48000 06-27 08:07:30.392: D/CCodec(16185): } 06-27 08:07:30.576: I/ActivityManager(478): Force stopping org.mozilla.reference.browser.debug appid=10152 user=0: finished inst 06-27 08:07:30.576: I/ActivityManager(478): Killing 14439:org.mozilla.reference.browser.debug/u0a152 (adj 0): stop org.mozilla.reference.browser.debug due to finished inst 06-27 08:07:30.579: W/ActivityTaskManager(478): Force removing ActivityRecord{846ac01 u0 org.mozilla.reference.browser.debug/org.mozilla.reference.browser.BrowserActivity t9 f}}: app died, no saved state 06-27 08:07:30.589: I/ServiceChildProcess(15607): Destroying GeckoServiceChildProcess 06-27 08:07:30.609: I/ServiceChildProcess(15278): Destroying GeckoServiceChildProcess 06-27 08:07:30.630: D/AndroidRuntime(14383): Shutting down VM 06-27 08:07:30.634: I/wser.debug:tab(15278): System.exit called, status: 0 06-27 08:07:30.634: I/AndroidRuntime(15278): VM exiting with result code 0, cleanup skipped. 06-27 08:07:30.679: I/owser.debug:gp(15607): System.exit called, status: 0 06-27 08:07:30.679: I/AndroidRuntime(15607): VM exiting with result code 0, cleanup skipped. 06-27 08:07:30.782: I/Zygote(290): Process 15607 exited cleanly (0) 06-27 08:07:30.826: W/Binder(14383): Caught a RuntimeException from the binder stub implementation. 06-27 08:07:30.826: W/Binder(14383): java.lang.SecurityException: Calling from not trusted UID! 06-27 08:07:30.826: W/Binder(14383): at android.app.UiAutomationConnection.throwIfCalledByNotTrustedUidLocked(UiAutomationConnection.java:525) 06-27 08:07:30.826: W/Binder(14383): at android.app.UiAutomationConnection.shutdown(UiAutomationConnection.java:429) 06-27 08:07:30.826: W/Binder(14383): at android.app.IUiAutomationConnection$Stub.onTransact(IUiAutomationConnection.java:390) 06-27 08:07:30.826: W/Binder(14383): at android.os.Binder.execTransactInternal(Binder.java:1159) 06-27 08:07:30.826: W/Binder(14383): at android.os.Binder.execTransact(Binder.java:1123) 06-27 08:07:30.876: I/binder(0): release 15278:16140 transaction 1319974 out, still active 06-27 08:07:30.878: I/binder(0): undelivered TRANSACTION_COMPLETE 06-27 08:07:30.833: I/Zygote(290): Process 15278 exited cleanly (0) 06-27 08:07:30.894: D/logd(0): logdr: UID=10097 GID=10097 PID=16310 n tail=200 logMask=99 pid=0 start=0ns timeout=0ns 06-27 08:07:31.044: I/binder(0): 478:1489 transaction failed 29189/-22, size 272-0 line 3146 06-27 08:07:31.096: I/binder(0): release 16185:16208 transaction 1319974 in, still active 06-27 08:07:31.098: I/binder(0): send failed reply for transaction 1319974, target dead 06-27 08:07:31.099: I/binder(0): undelivered death notification, 00000000f12d3a50 06-27 08:07:30.915: E/JavaBinder(14383): *** Uncaught remote exception! (Exceptions are not yet supported across processes.) 06-27 08:07:30.915: E/JavaBinder(14383): java.lang.RuntimeException: Bad file descriptor 06-27 08:07:30.915: E/JavaBinder(14383): at android.os.BinderProxy.transactNative(Native Method) 06-27 08:07:30.915: E/JavaBinder(14383): at android.os.BinderProxy.transact(BinderProxy.java:540) 06-27 08:07:30.915: E/JavaBinder(14383): at android.view.IWindowManager$Stub$Proxy.injectInputAfterTransactionsApplied(IWindowManager.java:5262) 06-27 08:07:30.915: E/JavaBinder(14383): at android.app.UiAutomationConnection.injectInputEvent(UiAutomationConnection.java:136) 06-27 08:07:30.915: E/JavaBinder(14383): at android.app.IUiAutomationConnection$Stub.onTransact(IUiAutomationConnection.java:228) 06-27 08:07:30.915: E/JavaBinder(14383): at android.os.Binder.execTransactInternal(Binder.java:1154) 06-27 08:07:30.915: E/JavaBinder(14383): at android.os.Binder.execTransact(Binder.java:1123) 06-27 08:07:30.919: W/Codec2Client(16185): query -- param skipped: index = 1342179345. 06-27 08:07:30.919: W/Codec2Client(16185): query -- param skipped: index = 2415921170. 06-27 08:07:30.919: W/Codec2Client(16185): query -- param skipped: index = 1610614798. 06-27 08:07:30.993: I/ActivityManager(478): Killing 16185:org.mozilla.reference.browser.debug:media/u0a152 (adj 0): stop org.mozilla.reference.browser.debug due to finished inst 06-27 08:07:31.021: I/BackdropActivity(12988): onResume() 06-27 08:07:31.022: I/ActivityTaskManager(478): Activity requesting to dismiss Keyguard: ActivityRecord{1e04075 u0 com.google.android.apps.mtaas.backdrop/.BackdropActivity t8} 06-27 08:07:31.049: I/Zygote(290): Process 16185 exited due to signal 9 (Killed) 06-27 08:07:31.055: V/C2Store(422): in ~ComponentModule 06-27 08:07:31.055: V/C2Store(422): unloading dll 06-27 08:07:31.058: W/ActivityManager(478): Failure disconnecting service org.mozilla.reference.browser.debug/org.mozilla.gecko.media.MediaManager to connection android.os.BinderProxy@f2f6144 (in org.mozilla.reference.browser.debug:tab8) 06-27 08:07:31.058: W/ActivityManager(478): android.os.DeadObjectException 06-27 08:07:31.058: W/ActivityManager(478): at android.os.BinderProxy.transactNative(Native Method) 06-27 08:07:31.058: W/ActivityManager(478): at android.os.BinderProxy.transact(BinderProxy.java:540) 06-27 08:07:31.058: W/ActivityManager(478): at android.app.IServiceConnection$Stub$Proxy.connected(IServiceConnection.java:129) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActiveServices.bringDownServiceLocked(ActiveServices.java:3247) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActiveServices.killServicesLocked(ActiveServices.java:4011) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActivityManagerService.cleanUpApplicationRecordLocked(ActivityManagerService.java:14772) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActivityManagerService.handleAppDiedLocked(ActivityManagerService.java:3772) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ProcessList.removeProcessLocked(ProcessList.java:2777) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ProcessList.killPackageProcessesLocked(ProcessList.java:2722) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActivityManagerService.forceStopPackageLocked(ActivityManagerService.java:4905) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActivityManagerService.finishInstrumentationLocked(ActivityManagerService.java:17090) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActivityManagerService.inputDispatchingTimedOut(ActivityManagerService.java:19836) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.am.ActivityManagerService$LocalService.inputDispatchingTimedOut(ActivityManagerService.java:19550) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.wm.ActivityRecord.keyDispatchingTimedOut(ActivityRecord.java:5565) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.wm.InputManagerCallback.notifyANRInner(InputManagerCallback.java:257) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.wm.InputManagerCallback.notifyANR(InputManagerCallback.java:179) 06-27 08:07:31.058: W/ActivityManager(478): at com.android.server.input.InputManagerService.notifyANR(InputManagerService.java:1959) 06-27 08:07:31.060: I/ActivityManager(478): Killing 15607:org.mozilla.reference.browser.debug:gpu/u0a152 (adj 0): stop org.mozilla.reference.browser.debug due to finished inst 06-27 08:07:31.062: I/ActivityManager(478): Killing 15278:org.mozilla.reference.browser.debug:tab8/u0a152 (adj 0): stop org.mozilla.reference.browser.debug due to finished inst 06-27 08:07:31.067: D/WindowManager(478): notifyANR took 878ms

Build: 6/27 Master

Notes: Similar with #24047