focus-android icon indicating copy to clipboard operation
focus-android copied to clipboard

Intermittent UI test failure - < MultitaskingTest. closeTabButtonTest >

Open AndiAJ opened this issue 2 years ago • 4 comments

The ANR crash reports (removed from this issue) will be tracked at https://github.com/mozilla-mobile/focus-android/issues/7344

Keeping this issue open for a (possibly) different crash, reported in a comment below: https://github.com/mozilla-mobile/focus-android/issues/6600#issuecomment-1138245397

AndiAJ avatar Mar 08 '22 13:03 AndiAJ

Disabled the test in https://github.com/mozilla-mobile/focus-android/pull/6706 due to https://github.com/mozilla-mobile/focus-android/issues/6708

AndiAJ avatar Mar 21 '22 09:03 AndiAJ

re-enabled in https://github.com/mozilla-mobile/focus-android/pull/6813

sv-ohorvath avatar Apr 12 '22 12:04 sv-ohorvath

Closing, as it didn't fail for approx a month.

AndiAJ avatar May 09 '22 08:05 AndiAJ

Flaky 1x on 5/25 Main Firebase link

App crashed after closing a tab (from a list of 3 tabs):

Matched selector: UiSelector[INDEX=0, CHILD=UiSelector[CONTAINS_TEXT=tab1], RESOURCE_ID=org.mozilla.focus.debug:id/session_item] <<==>> [android.view.accessibility.AccessibilityNodeInfo@35411; boundsInParent: Rect(0, 0 - 638, 154); boundsInScreen: Rect(200, 220 - 838, 374); packageName: org.mozilla.focus.debug; className: android.widget.TextView; text: tab1; error: null; maxTextLength: -1; stateDescription: null; contentDescription: null; tooltipText: null; viewIdResName: org.mozilla.focus.debug:id/session_title; checkable: false; checked: false; focusable: true; focused: false; selected: false; clickable: true; longClickable: false; contextClickable: false; enabled: true; password: false; scrollable: false; importantForAccessibility: true; visible: true; actions: [AccessibilityAction: ACTION_SELECT - null, AccessibilityAction: ACTION_CLEAR_SELECTION - null, AccessibilityAction: ACTION_FOCUS - null, AccessibilityAction: ACTION_ACCESSIBILITY_FOCUS - null, AccessibilityAction: ACTION_CLICK - null, AccessibilityAction: ACTION_SHOW_ON_SCREEN - null, AccessibilityAction: ACTION_NEXT_AT_MOVEMENT_GRANULARITY - null, AccessibilityAction: ACTION_PREVIOUS_AT_MOVEMENT_GRANULARITY - null, AccessibilityAction: ACTION_SET_SELECTION - null]] 05-25 13:17:40.416: I/QueryController(13588): Matched selector: UiSelector[INDEX=1, CHILD=UiSelector[CONTAINS_TEXT=tab3], RESOURCE_ID=org.mozilla.focus.debug:id/session_item] <<==>> [android.view.accessibility.AccessibilityNodeInfo@35f54; boundsInParent: Rect(0, 0 - 638, 154); boundsInScreen: Rect(200, 374 - 838, 528); packageName: org.mozilla.focus.debug; className: android.widget.TextView; text: localhost/tab3.html; error: null; maxTextLength: -1; stateDescription: null; contentDescription: null; tooltipText: null; viewIdResName: org.mozilla.focus.debug:id/session_title; checkable: false; checked: false; focusable: true; focused: false; selected: false; clickable: true; longClickable: false; contextClickable: false; enabled: true; password: false; scrollable: false; importantForAccessibility: true; visible: true; actions: [AccessibilityAction: ACTION_SELECT - null, AccessibilityAction: ACTION_CLEAR_SELECTION - null, AccessibilityAction: ACTION_FOCUS - null, AccessibilityAction: ACTION_ACCESSIBILITY_FOCUS - null, AccessibilityAction: ACTION_CLICK - null, AccessibilityAction: ACTION_SHOW_ON_SCREEN - null, AccessibilityAction: ACTION_NEXT_AT_MOVEMENT_GRANULARITY - null, AccessibilityAction: ACTION_PREVIOUS_AT_MOVEMENT_GRANULARITY - null, AccessibilityAction: ACTION_SET_SELECTION - null]] 05-25 13:17:40.421: I/QueryController(13588): Matched selector: UiSelector[INDEX=2, CHILD=UiSelector[CONTAINS_TEXT=tab2], RESOURCE_ID=org.mozilla.focus.debug:id/session_item] <<==>> [android.view.accessibility.AccessibilityNodeInfo@36a97; boundsInParent: Rect(0, 0 - 638, 154); boundsInScreen: Rect(200, 528 - 838, 682); packageName: org.mozilla.focus.debug; className: android.widget.TextView; text: tab2; error: null; maxTextLength: -1; stateDescription: null; contentDescription: null; tooltipText: null; viewIdResName: org.mozilla.focus.debug:id/session_title; checkable: false; checked: false; focusable: true; focused: false; selected: false; clickable: true; longClickable: false; contextClickable: false; enabled: true; password: false; scrollable: false; importantForAccessibility: true; visible: true; actions: [AccessibilityAction: ACTION_SELECT - null, AccessibilityAction: ACTION_CLEAR_SELECTION - null, AccessibilityAction: ACTION_FOCUS - null, AccessibilityAction: ACTION_ACCESSIBILITY_FOCUS - null, AccessibilityAction: ACTION_CLICK - null, AccessibilityAction: ACTION_SHOW_ON_SCREEN - null, AccessibilityAction: ACTION_NEXT_AT_MOVEMENT_GRANULARITY - null, AccessibilityAction: ACTION_PREVIOUS_AT_MOVEMENT_GRANULARITY - null, AccessibilityAction: ACTION_SET_SELECTION - null]] 05-25 13:17:40.425: I/ViewInteraction(13588): Performing 'single click' action on view (view.getId() is <2131361936/org.mozilla.focus.debug:id/close_button> and (view.getParent() is an instance of android.view.ViewGroup and has a sibling matching an instance of android.widget.TextView and view.getText() with or without transformation to match: a string containing "tab1")) 05-25 13:17:40.426: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.439: I/Zygote(289): Process 12396 exited due to signal 9 (Killed) 05-25 13:17:40.441: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.456: I/chatty(1717): uid=1041(audioserver) writer identical 1 line 05-25 13:17:40.471: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.474: I/libprocessgroup(478): Successfully killed process cgroup uid 10105 pid 12396 in 216ms 05-25 13:17:40.479: D/GeckoViewModule(13588): dispatch GeckoView:WebExtension:SetTabActive, data={"active":false} 05-25 13:17:40.479: I/system_server(478): oneway function results will be dropped but finished with status OK and parcel size 4 05-25 13:17:40.479: D/GeckoViewTab(13588): onEvent: event=GeckoView:WebExtension:SetTabActive, data={"active":false} 05-25 13:17:40.486: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.546: I/chatty(1717): uid=1041(audioserver) writer identical 4 lines 05-25 13:17:40.561: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.568: D/BoundBrokerSvc(10377): onUnbind: Intent { act=com.google.android.gms.feedback.internal.IFeedbackService dat=chimera-action:com.google.android.gms.feedback.internal.IFeedbackService cmp=com.google.android.gms/.chimera.GmsBoundBrokerService } 05-25 13:17:40.576: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.591: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.596: D/GeckoViewContent(13588): handleEvent: DOMWindowClose 05-25 13:17:40.597: D/GeckoViewNavigation(13588): onDisable 05-25 13:17:40.597: D/GeckoViewProcessHangMonitor(13588): onDisable 05-25 13:17:40.598: D/GeckoViewModule(13588): unregisterListener 05-25 13:17:40.598: D/GeckoViewProcessHangMonitor(13588): onDestroy 05-25 13:17:40.598: D/GeckoViewProgress(13588): onDisable 05-25 13:17:40.598: D/GeckoViewModule(13588): unregisterListener 05-25 13:17:40.598: D/GeckoViewSelectionAction(13588): onDisable 05-25 13:17:40.600: D/GeckoViewModule(13588): unregisterListener 05-25 13:17:40.601: D/GeckoViewModule(13588): unregisterListener 05-25 13:17:40.602: D/GeckoViewMediaControl(13588): onDisable 05-25 13:17:40.602: D/GeckoViewModule(13588): unregisterListener 05-25 13:17:40.606: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.636: I/chatty(1717): uid=1041(audioserver) writer identical 2 lines 05-25 13:17:40.651: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.656: I/system_server(478): oneway function results will be dropped but finished with status OK and parcel size 4 05-25 13:17:40.657: D/GeckoViewSelectionActionDelegateC: handleEvent: visibilitychange 05-25 13:17:40.657: D/GeckoViewAutoFillC: handleEvent: pagehide 05-25 13:17:40.665: D/WebExtensionsMiddleware(13588): New active tab (8522f70d-0c22-44b6-a337-a0368dc971b8) 05-25 13:17:40.666: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.741: I/chatty(1717): uid=1041(audioserver) writer identical 5 lines 05-25 13:17:40.756: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.767: W/google-breakpad(13588): ExceptionHandler::GenerateDump cloned child 05-25 13:17:40.767: W/google-breakpad(13588): 15451 05-25 13:17:40.767: W/google-breakpad(13588): ExceptionHandler::SendContinueSignalToChild sent continue signal to child 05-25 13:17:40.771: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:40.774: W/google-breakpad(15451): ExceptionHandler::WaitForContinueSignal waiting for continue signal... 05-25 13:17:40.786: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:41.970: I/chatty(1717): uid=1041(audioserver) writer identical 79 lines 05-25 13:17:41.985: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:41.998: E/MmTelFeatureConnection 0: ImsRegistrationCallbackAdapter: ImsRegistration is null 05-25 13:17:42.000: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.849: D/CompatibilityChangeReporter(478): Compat change id reported: 135634846; UID 10152; state: DISABLED 05-25 13:17:42.849: D/CompatibilityChangeReporter(478): Compat change id reported: 143937733; UID 10152; state: ENABLED 05-25 13:17:42.824: I/chatty(1717): uid=1041(audioserver) writer identical 55 lines 05-25 13:17:42.839: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.850: I/cmd(15553): oneway function results will be dropped but finished with status OK and parcel size 4 05-25 13:17:42.854: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.869: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.876: D/Zygote(289): Forked child process 15558 05-25 13:17:42.878: I/ActivityManager(478): Start proc 15558:org.mozilla.focus.debug:mozilla.components.lib.crash.CrashHandler/u0a152 for service {org.mozilla.focus.debug/mozilla.components.lib.crash.handler.CrashHandlerService} 05-25 13:17:42.884: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.888: I/sh.CrashHandle(15558): Late-enabling -Xcheck:jni 05-25 13:17:42.899: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.910: I/sh.CrashHandle(15558): Unquickening 12 vdex files! 05-25 13:17:42.912: W/sh.CrashHandle(15558): Unexpected CPU variant for X86 using defaults: x86 05-25 13:17:42.914: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:42.923: I/adbd(381): jdwp connection from 15558 05-25 13:17:42.929: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:43.004: I/chatty(1717): uid=1041(audioserver) writer identical 5 lines 05-25 13:17:43.019: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:43.026: I/PeriodicStatsRunner(3368): PeriodicStatsRunner.call():180 call() 05-25 13:17:43.026: I/PeriodicStatsRunner(3368): PeriodicStatsRunner.call():184 No submit PeriodicStats since input started. 05-25 13:17:43.034: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:43.049: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:43.060: I/Gecko(15065): Exiting due to channel error. 05-25 13:17:43.061: I/Gecko(14117): Exiting due to channel error. 05-25 13:17:43.064: W/InputDispatcher(478): channel '5365174 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity (server)' ~ Consumer closed input channel or an error occurred. events=0x9 05-25 13:17:43.064: W/audio_hw_generic(1717): Hardware backing HAL too slow, could only write 0 of 661 frames 05-25 13:17:43.064: E/InputDispatcher(478): channel '5365174 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity (server)' ~ Channel is unrecoverably broken and will be disposed! 05-25 13:17:43.076: I/ActivityManager(478): Process org.mozilla.focus.debug (pid 13588) has died: fg TOP

AndiAJ avatar May 26 '22 07:05 AndiAJ

Flaky 1x on 8/19 Main Firebase link

androidx.test.espresso.base.RootViewPicker$RootViewWithoutFocusException: Waited for the root of the view hierarchy to have window focus and not request layout for 10 seconds. If you specified a non default root matcher, it may be picking a root that never takes focus. Root: Root{application-window-token=android.view.ViewRootImpl$W@7683afb, window-token=android.view.ViewRootImpl$W@7683afb, has-window-focus=false, layout-params-type=1, layout-params-string={(0,0)(fillxfill) sim={state=always_hidden adjust=resize} layoutInDisplayCutoutMode=shortEdges ty=BASE_APPLICATION wanim=0x10302fe fl=LAYOUT_IN_SCREEN LAYOUT_INSET_DECOR SPLIT_TOUCH HARDWARE_ACCELERATED DRAWS_SYSTEM_BAR_BACKGROUNDS pfl=FORCE_DRAW_STATUS_BAR_BACKGROUND FIT_INSETS_CONTROLLED fitSides=}, decor-view-string=DecorView{id=-1, visibility=VISIBLE, width=1080, height=2160, has-focus=true, has-focusable=true, has-window-focus=false, is-clickable=false, is-enabled=true, is-focused=false, is-focusable=false, is-layout-requested=false, is-selected=false, layout-params={(0,0)(fillxfill) sim={state=always_hidden adjust=resize} layoutInDisplayCutoutMode=shortEdges ty=BASE_APPLICATION wanim=0x10302fe fl=LAYOUT_IN_SCREEN LAYOUT_INSET_DECOR SPLIT_TOUCH HARDWARE_ACCELERATED DRAWS_SYSTEM_BAR_BACKGROUNDS pfl=FORCE_DRAW_STATUS_BAR_BACKGROUND FIT_INSETS_CONTROLLED fitSides=}, tag=null, root-is-layout-requested=false, has-input-connection=false, x=0.0, y=0.0, child-count=1}} at dalvik.system.VMStack.getThreadStackTrace(Native Method) at java.lang.Thread.getStackTrace(Thread.java:1736) at androidx.test.espresso.base.DefaultFailureHandler.getUserFriendlyError(DefaultFailureHandler.java:12) at androidx.test.espresso.base.DefaultFailureHandler.handle(DefaultFailureHandler.java:7) at androidx.test.espresso.ViewInteraction.waitForAndHandleInteractionResults(ViewInteraction.java:8) at androidx.test.espresso.ViewInteraction.desugaredPerform(ViewInteraction.java:11) at androidx.test.espresso.ViewInteraction.perform(ViewInteraction.java:4) at org.mozilla.focus.activity.robots.BrowserRobot.openLinkInNewTab(BrowserRobot.kt:132) at org.mozilla.focus.activity.MultitaskingTest$closeTabButtonTest$2.invoke(MultitaskingTest.kt:120) at org.mozilla.focus.activity.MultitaskingTest$closeTabButtonTest$2.invoke(MultitaskingTest.kt:115) at org.mozilla.focus.activity.robots.SearchRobot$Transition.loadPage(SearchRobot.kt:97) at org.mozilla.focus.activity.MultitaskingTest.closeTabButtonTest(MultitaskingTest.kt:115)

AndiAJ avatar Aug 22 '22 13:08 AndiAJ

Closing, as it didn't fail for a month.

AndiAJ avatar Sep 22 '22 07:09 AndiAJ