Skip to content
This repository has been archived by the owner on Feb 20, 2023. It is now read-only.

Intermittent UI test failure - < SmokeTest.audioPlaybackSystemNotificationTest> #25055

Closed
AndiAJ opened this issue May 2, 2022 · 18 comments
Closed

Intermittent UI test failure - < SmokeTest.audioPlaybackSystemNotificationTest> #25055

AndiAJ opened this issue May 2, 2022 · 18 comments
Labels
eng:disabled-test For any test that has been disabled eng:intermittent-test Evil test failures that only happen now-and-then and are a productivity killer eng:ui-test UI Tests

Comments

@AndiAJ
Copy link
Collaborator

AndiAJ commented May 2, 2022 •
edited by data-sync-user
Loading

Firebase Test Run: Firebase link

Stacktrace:

04-29 15:44:36.580: I/WindowManager(480): Input event dispatching timed out sending to org.mozilla.fenix.debug/org.mozilla.fenix.HomeActivity. Reason: 5c64224 org.mozilla.fenix.debug/org.mozilla.fenix.HomeActivity (server) is not responding. Waited 5000ms for FocusEvent(hasFocus=true)
04-29 15:44:36.661: D/sql_support::conn_ext(12550): Transaction commited after 127.233834ms
04-29 15:44:36.670: W/FenixPerf(12550): StrictMode violation suppressed: #50
04-29 15:44:36.792: D/nimbus(12550): nimbus_302d_NimbusClient_get_active_experiments
04-29 15:44:36.796: D/nimbus(12550): nimbus_302d_NimbusClient_get_feature_config_variables
04-29 15:44:36.798: W/FenixPerf(12550): StrictMode violation suppressed: #51
04-29 15:44:36.814: D/DefaultHistoryMetadataService(12550): Updating metadata for tab TabSessionState(id=db7d02ea-5aed-4617-bc2d-4bbd2621ccd9, content=ContentState(url=http://localhost:39213/pages/audioMediaPage.html, private=false, title=Audio_Test_Page, progress=100, loading=false, searchTerms=, securityInfo=SecurityInfoState(secure=false, host=, issuer=), thumbnail=null, icon=android.graphics.Bitmap@f6cc7a3, download=null, share=null, hitResult=null, promptRequests=[], findResults=[], windowRequest=null, searchRequest=null, fullScreen=false, layoutInDisplayCutoutMode=0, canGoBack=false, canGoForward=false, webAppManifest=null, firstContentfulPaint=true, history=HistoryState(items=[HistoryItem(title=Audio_Test_Page, uri=http://localhost:39213/pages/audioMediaPage.html)], currentIndex=0), permissionHighlights=PermissionHighlightsState(notificationChanged=false, cameraChanged=false, locationChanged=false, microphoneChanged=false, persistentStorageChanged=false, mediaKeySystemAccessChanged=false, autoPlayAudibleChanged=false, autoPlayInaudibleChanged=false, autoPlayAudibleBlocking=true, autoPlayInaudibleBlocking=false), permissionRequestsList=[], appPermissionRequestsList=[], pictureInPictureEnabled=false, loadRequest=LoadRequestState(url=http://localhost:39213/pages/audioMediaPage.html, triggeredByRedirect=false, triggeredByUser=false), refreshCanceled=false, recordingDevices=[], desktopMode=false, appIntent=null, showToolbarAsExpanded=false, previewImageUrl=null), trackingProtection=TrackingProtectionState(enabled=true, blockedTrackers=[], loadedTrackers=[], ignoredOnTrackingProtection=false), engineState=EngineState(engineSession=mozilla.components.browser.engine.gecko.GeckoEngineSession@fb9d4ba, engineSessionState=mozilla.components.browser.engine.gecko.GeckoEngineSessionState@136daa0, initializing=false, engineObserver=mozilla.components.browser.state.engine.EngineObserver@45c2175, crashed=false, timestamp=6692999, initialLoadFlags=mozilla.components.concept.engine.EngineSession$LoadUrlFlags@0), extensionState={webcompat-reporter@mozilla.org=WebExtensionState(id=webcompat-reporter@mozilla.org, url=null, name=null, enabled=true, allowedInPrivateBrowsing=false, browserAction=null, pageAction=Action(title=null, enabled=true, loadIcon=null, badgeText=null, badgeTextColor=null, badgeBackgroundColor=null, onClick=Function0<kotlin.Unit>), popupSessionId=null, popupSession=null)}, mediaSessionState=MediaSessionState(controller=mozilla.components.browser.engine.gecko.mediasession.GeckoMediaSessionController@c20021e, metadata=null, elementMetadata=null, playbackState=PAUSED, features=Feature(flags=0), positionState=PositionState(duration=-1.0, position=0.0, playbackRate=0.0), muted=false, fullscreen=false, timestamp=1651272258375), contextId=null, source=mozilla.components.browser.state.state.SessionState$Source$Internal$NewTab@b7a3aff, restored=false, parentId=null, lastAccess=1651272255620, createdAt=1651272250027, lastMediaAccessState=LastMediaAccessState(lastMediaUrl=http://localhost:39213/pages/audioMediaPage.html, lastMediaAccess=1651272258394, mediaSessionActive=true), readerState=ReaderState(readerable=false, active=false, checkRequired=false, connectRequired=false, baseUrl=null, activeUrl=null), historyMetadata=HistoryMetadataKey(url=http://localhost:39213/pages/audioMediaPage.html, searchTerm=null, referrerUrl=null))
04-29 15:44:36.814: D/DefaultHistoryMetadataService(12550): Failed to update metadata because it was already recorded or lastAccess is incorrect
04-29 15:44:36.816: D/GeckoViewModule(12550): dispatch GeckoView:WebExtension:SetTabActive, data={"active":false}
04-29 15:44:36.816: D/GeckoViewTab(12550): onEvent: event=GeckoView:WebExtension:SetTabActive, data={"active":false}
04-29 15:44:36.820: I/SessionStorage/AutoSave(12550): Save: New tab selected
04-29 15:44:36.820: D/SessionStorage/AutoSave(12550): Saved state to disk [0ms]
04-29 15:44:36.822: D/WebExtensionsMiddleware(12550): No engine session for new active tab (null)
04-29 15:44:36.842: D/places::ffi(12550): places_de65_PlacesConnection_get_history_metadata_since
04-29 15:44:36.851: D/LeakCanary(12550): Watching instance of org.mozilla.fenix.browser.SwipeGestureLayout (org.mozilla.fenix.browser.BrowserFragment received Fragment#onDestroyView() callback (references to its views should be cleared to prevent leaks)) with key 27156ed2-3a49-4a0d-a790-25091f78a9fa
04-29 15:44:36.851: D/LeakCanary(12550): Watching instance of androidx.loader.app.LoaderManagerImpl$LoaderViewModel (androidx.loader.app.LoaderManagerImpl$LoaderViewModel received ViewModel#onCleared() callback) with key db08576e-4372-4009-9ebe-df57f4e34962
04-29 15:44:36.851: D/LeakCanary(12550): Watching instance of org.mozilla.fenix.components.StoreProvider (org.mozilla.fenix.components.StoreProvider received ViewModel#onCleared() callback) with key bc948c3f-2249-47c8-99d1-1f2bff935a89
04-29 15:44:36.851: D/LeakCanary(12550): Watching instance of leakcanary.internal.ViewModelClearedWatcher (leakcanary.internal.ViewModelClearedWatcher received ViewModel#onCleared() callback) with key 4a441472-6e3a-4cc3-a323-00b107b36a0c
04-29 15:44:36.852: D/ThumbnailStorage(12550): Removed thumbnail from disk (sessionIdOrUrl = db7d02ea-5aed-4617-bc2d-4bbd2621ccd9)
04-29 15:44:36.852: D/LeakCanary(12550): Watching instance of org.mozilla.fenix.browser.BrowserFragment (org.mozilla.fenix.browser.BrowserFragment received Fragment#onDestroy() callback) with key a3241c9e-590f-4c26-9bca-f30b942557cf
04-29 15:44:36.854: W/FenixPerf(12550): StrictMode violation suppressed: #52
04-29 15:44:36.856: I/ActivityManager(480): Force stopping org.mozilla.fenix.debug appid=10151 user=0: finished inst
04-29 15:44:36.857: I/ActivityManager(480): Killing 12550:org.mozilla.fenix.debug/u0a151 (adj 0): stop org.mozilla.fenix.debug due to finished inst
04-29 15:44:36.857: W/Binder(12500): Caught a RuntimeException from the binder stub implementation.
04-29 15:44:36.857: W/Binder(12500): java.lang.SecurityException: Calling from not trusted UID!
04-29 15:44:36.857: W/Binder(12500): at android.app.UiAutomationConnection.throwIfCalledByNotTrustedUidLocked(UiAutomationConnection.java:525)
04-29 15:44:36.857: W/Binder(12500): at android.app.UiAutomationConnection.shutdown(UiAutomationConnection.java:429)
04-29 15:44:36.857: W/Binder(12500): at android.app.IUiAutomationConnection$Stub.onTransact(IUiAutomationConnection.java:390)
04-29 15:44:36.857: W/Binder(12500): at android.os.Binder.execTransactInternal(Binder.java:1159)
04-29 15:44:36.857: W/Binder(12500): at android.os.Binder.execTransact(Binder.java:1123)
04-29 15:44:36.862: I/ServiceChildProcess(13165): Destroying GeckoServiceChildProcess
04-29 15:44:36.863: W/ActivityTaskManager(480): Force removing ActivityRecord{b821848 u0 org.mozilla.fenix.debug/org.mozilla.fenix.HomeActivity t9 f}}: app died, no saved state
04-29 15:44:36.870: I/ActivityManager(480): Killing 13165:org.mozilla.fenix.debug:tab9/u0a151 (adj 0): stop org.mozilla.fenix.debug due to finished inst
04-29 15:44:36.870: I/ActivityManager(480): Killing 13370:org.mozilla.fenix.debug:gpu/u0a151 (adj 0): stop org.mozilla.fenix.debug due to finished inst
04-29 15:44:36.901: D/AndroidRuntime(12500): Shutting down VM
04-29 15:44:36.924: D/WindowManager(480): notifyANR took 357ms

Build: 4/30 Main

Notes Similar with #24047

┆Issue is synchronized with this Jira Story
@AndiAJ AndiAJ added b:crash Crashes Fenix: should link to Sentry, Crash-Stats or GPlay info eng:intermittent-test Evil test failures that only happen now-and-then and are a productivity killer eng:ui-test UI Tests labels May 2, 2022
@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 2, 2022

Flaky again due to the ANR Firebase link

@github-actions github-actions bot added the needs:triage Issue needs triage label May 2, 2022
@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 3, 2022

Flaky on 5/2 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 4, 2022

Flaky 1x on 5/3 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 4, 2022

Flaky 1x o 5/4 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 5, 2022 •
edited
Loading

Flaky 2x on 5/5 Main
Firebase link
Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 6, 2022

Flaky 1x on 5/6 Main Firebase link

@AaronMT
Copy link
Contributor

AaronMT commented May 6, 2022

1x on 5/6 PR Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 9, 2022

Flaky 1x on 5/9 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 9, 2022

Flaky 1x on 5/9 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 10, 2022

Flaky 1x on 5/9 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 17, 2022 •
edited
Loading

Flaky 1x on 5/16 Main
Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 17, 2022

Flaky 1x on 5/17 Main

Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 18, 2022

Flaky 1x on 5/17 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 19, 2022

Flaky 1x on 5/18 Main Firebase link

Flaky 1x on 5/19 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 23, 2022

Flaky 1x on 5/20 Main Firebase link

@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 23, 2022

Failed 1x on 5/21 Main
Firebase link
Firebase link

@Mugurell Mugurell mentioned this issue May 23, 2022
Merged
@AndiAJ
Copy link
Collaborator Author

AndiAJ commented May 23, 2022

Disabled in #25343

@AndiAJ AndiAJ added the eng:disabled-test For any test that has been disabled label May 23, 2022
@AndiAJ AndiAJ removed b:crash Crashes Fenix: should link to Sentry, Crash-Stats or GPlay info needs:triage Issue needs triage labels Jul 7, 2022
@sv-ohorvath
Copy link
Contributor

Closing as dupe, in favor of #25926 - tracking all ANR reports

Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
eng:disabled-test For any test that has been disabled eng:intermittent-test Evil test failures that only happen now-and-then and are a productivity killer eng:ui-test UI Tests
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@AaronMT @sv-ohorvath @AndiAJ