search

mozilla-mobile / focus-android

⚠️ Firefox Focus (Android) moved to a new repository. It is now developed and maintained as part of: https://github.com/mozilla-mobile/firefox-android
https://github.com/mozilla-mobile/firefox-android
Mozilla Public License 2.0
2.11k stars 711 forks source link

Intermittent UI test failure - < EnhancedTrackingProtectionSettingsTest. blockAnalyticsTrackersTest > #5871

Closed AndiAJ closed 2 years ago

AndiAJ commented 2 years ago

Firebase Test Run: Firebase link

Stacktrace:

11-14 12:08:29.775: I/ViewInteraction(6902): Performing 'single click' action on view (with id: org.mozilla.focus.debug:id/erase and is displayed on the screen to the user)
11-14 12:08:29.813: I/Icing(2723): Indexing com.google.android.gms-apps from com.google.android.gms
11-14 12:08:29.858: D/GeckoViewModule(6902): dispatch GeckoView:WebExtension:SetTabActive, data={"active":false}
11-14 12:08:29.858: D/GeckoViewTab(6902): onEvent: event=GeckoView:WebExtension:SetTabActive, data={"active":false}
11-14 12:08:29.866: W/AudioFlinger(1940): createTrack_l(): mismatch between requested flags (00000004) and output flags (00000002)
11-14 12:08:29.867: W/AudioTrack(2073): AUDIO_OUTPUT_FLAG_FAST denied by server; frameCount 0 -> 4512
11-14 12:08:29.870: I/Icing(2723): IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=null serviceId=30
11-14 12:08:29.892: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:29.892: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:29.901: D/WebExtensionsMiddleware(6902): No engine session for new active tab (null)
11-14 12:08:30.072: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:30.072: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:30.085: W/Icing(2723): No valid account for uploading user actions
11-14 12:08:30.104: I/Icing(2723): Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0
11-14 12:08:30.106: I/Icing(2723): Indexing done com.google.android.gms-apps
11-14 12:08:30.337: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:30.337: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:30.508: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:30.508: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:30.740: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:30.740: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:30.858: I/Remoter(1916): Saved screenshot to '/data/screenshots/__pmv__-0158.jpg'
11-14 12:08:30.887: I/OpenGLRenderer(6902): Davey! duration=1061ms; Flags=0, IntendedVsync=246331785253, Vsync=246331785253, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=246332010621, AnimationStart=246332047481, PerformTraversalsStart=246332561076, DrawStart=246333157705, SyncQueued=246333767954, SyncStart=246333800716, IssueDrawCommandsStart=246333894647, SwapBuffers=247390615530, FrameCompleted=247393047007, DequeueBufferDuration=90000, QueueBufferDuration=197000, 
11-14 12:08:31.001: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:31.001: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:31.205: E/libGLESv2_swiftshader(6902): badness: unimplemented: getBinaryLength external/swiftshader/src/OpenGL/libGLESv2/Program.cpp:2684
11-14 12:08:31.205: E/swiftshader(6902): AndroidEnterDebugger
11-14 12:08:31.211: E/libGLESv2_swiftshader(6902): badness: unimplemented: getBinaryLength external/swiftshader/src/OpenGL/libGLESv2/Program.cpp:2684
11-14 12:08:31.211: E/swiftshader(6902): AndroidEnterDebugger
11-14 12:08:31.217: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:31.217: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:31.300: I/FirebaseJobDispatcher(2207): Schedule task: fetch_configuration. Success.
11-14 12:08:31.300: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:31.300: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:31.325: E/ExecutionCriteria(2507): Package unavailable for task: com.google.android.youtube/com.google.android.libraries.youtube.common.gcore.gcoreclient.gcm.impl.GcmTaskServiceDelegator{u=0 tag="offline_settings_fetch" trigger=window{period=86400s,flex=1800s,earliest=-868539s,latest=-864219s} requirements=[NET_CONNECTED] attributes=[PERSISTED,RECURRING] scheduled=-950619s last_run=N/A exec_window_multiplier=1.0000 jid=N/A status=PENDING retries=0 client_lib=MANCHEGO_GCM-11910000} [CONTEXT service_id=218 ]
11-14 12:08:31.334: E/ExecutionCriteria(2507): Package unavailable for task: com.google.android.youtube/com.google.android.libraries.youtube.common.gcore.gcoreclient.gcm.impl.GcmTaskServiceDelegator{u=0 tag="innertube_config_fetch_charging" trigger=window{period=172800s,flex=86400s,earliest=-864219s,latest=-777819s} requirements=[NET_CONNECTED,CHARGING] attributes=[PERSISTED,RECURRING] scheduled=-950619s last_run=N/A exec_window_multiplier=1.0000 jid=N/A status=PENDING retries=0 client_lib=MANCHEGO_GCM-11910000} [CONTEXT service_id=218 ]
11-14 12:08:31.344: E/ExecutionCriteria(2507): Package unavailable for task: com.google.android.youtube/com.google.android.libraries.youtube.common.gcore.gcoreclient.gcm.impl.GcmTaskServiceDelegator{u=0 tag="innertube_config_fetch" trigger=window{period=172800s,flex=14400s,earliest=-792219s,latest=-777819s} requirements=[NET_CONNECTED] attributes=[PERSISTED,RECURRING] scheduled=-950619s last_run=N/A exec_window_multiplier=1.0000 jid=N/A status=PENDING retries=0 client_lib=MANCHEGO_GCM-11910000} [CONTEXT service_id=218 ]
11-14 12:08:31.385: I/NetworkScheduler.Stats(2507): Task com.google.android.inputmethod.latin/ started execution. cause:7 exec_start_elapsed_seconds: 247 [CONTEXT service_id=218 ]
11-14 12:08:31.401: I/FirebaseJobDispatcher(2207): onStartJob(): fetch_configuration.
11-14 12:08:31.402: I/PhenotypeIme(2207): ExperimentTaskCallable.fetchConfiguration() : com.google.android.inputmethod.latin
11-14 12:08:31.421: I/PhenotypeIme(2207): ExperimentTaskCallable.fetchConfiguration() : ConfigurationsResult[0] = Success
11-14 12:08:31.438: I/EmojiSuperpacksManager(2207): triggerSync() : isBundled = false
11-14 12:08:31.439: I/EmojiSuperpacksManager(2207): Already registered manifest for emoji
11-14 12:08:31.441: I/DefaultStickerUpdate(2207): Ignoring schedule request for locale=en, data=DefaultStickerTaskData{enabled=true, metadataUriPrefix=https://www.gstatic.com/android/keyboard/stickers/configs/v5/, supportedLanguage=en}, and reason=EXPERIMENT_CONFIG
11-14 12:08:31.444: I/PhenotypeIme(2207): ExperimentTaskCallable.fetchConfiguration() : ConfigurationsResult[0] = Persisted
11-14 12:08:31.447: I/HWRSuperpacksManager(2207): register(): version '3', url 'https://dl.google.com/handwriting/models/handwriting.superpack_manifest.20180709.json'
11-14 12:08:31.448: I/Superpacks(2207): Registering manifest handwriting_recognition.3 with URL https://dl.google.com/handwriting/models/handwriting.superpack_manifest.20180709.json and constraints: m:*, current version: 3
11-14 12:08:31.449: I/Superpacks(2207): Manifest handwriting_recognition is already the current sync version
11-14 12:08:31.449: I/HWRSuperpacksManager(2207): syncPackMapping()
11-14 12:08:31.449: I/Superpacks(2207): Syncing handwriting_recognition, version: 3
11-14 12:08:31.449: I/Superpacks(2207): Using sync version 3 for handwriting_recognition
11-14 12:08:31.450: I/HWSlicingStrategy(2207): getSlices(): handwriting_recognition.3
11-14 12:08:31.451: I/SuperDelight(2207): initializeDelightSuperpacks() : Bundled = false
11-14 12:08:31.451: I/SuperDelight(2207): getDelightMetadataUriAndVersion(): Phenotype : 2019100102 : https://www.gstatic.com/android/keyboard/dictionarypack/Ney-normal/metadata.json
11-14 12:08:31.451: I/SuperDelight(2207): initializeOverridesSuperpacks()
11-14 12:08:31.451: I/HWSlicingStrategy(2207): getSlices(): result {slices=[{handwriting_recognition:pack_mapping_v1_segment_decode_20180709, m:*}], last batch=true}
11-14 12:08:31.451: I/Superpacks(2207): Requesting packs for handwriting_recognition with slices: [{handwriting_recognition:pack_mapping_v1_segment_decode_20180709, m:*}]
11-14 12:08:31.460: I/PhenotypeIme(2207): ExperimentTaskCallable.fetchConfiguration() : ConfigurationsResult[0] = Committed
11-14 12:08:31.460: I/PhenotypeIme(2207): ExperimentTaskCallable.fetchConfiguration() : Updating SharedPreferences
11-14 12:08:31.460: I/PhenotypeIme(2207): ExperimentTaskCallable.fetchConfiguration() : Success after 58 ms
11-14 12:08:31.462: I/FirebaseJobDispatcher(2207): Task: fetch_configuration successes.
11-14 12:08:31.481: I/NetworkScheduler.Stats(2507): Task com.google.android.inputmethod.latin/ finished executing. cause:7 result: 1 elapsed_millis: 103 uptime_millis: 103 exec_start_elapsed_seconds: 247 [CONTEXT service_id=218 ]
11-14 12:08:31.487: I/Superpacks-FP(2207): Fetch pipeline requesting slice {handwriting_recognition:pack_mapping_v1_segment_decode_20180709, m:*}
11-14 12:08:31.487: I/Superpacks-FP(2207): Final file handwriting_recognition:pack_mapping_v1_segment_decode_20180709 is already available
11-14 12:08:31.487: I/Superpacks-FP(2207): Successfully fetched pack handwriting_recognition:pack_mapping_v1_segment_decode_20180709
11-14 12:08:31.512: I/SuperDelight(2207): SuperDelightManager#registerSuperpacks(delight): current 2019100102, required 2019100102
11-14 12:08:31.512: I/SuperDelight(2207): SuperDelightManager#syncDownloadableLanguageModelsForSuperpack(delight_overrides): [OnDevice] Syncing for version -1
11-14 12:08:31.514: E/ExecutionCriteria(2507): Package unavailable for task: com.google.android.youtube/com.google.android.libraries.youtube.common.gcore.gcoreclient.gcm.impl.GcmTaskServiceDelegator{u=0 tag="offline_settings_fetch" trigger=window{period=86400s,flex=1800s,earliest=-868539s,latest=-864219s} requirements=[NET_CONNECTED] attributes=[PERSISTED,RECURRING] scheduled=-950619s last_run=N/A exec_window_multiplier=1.0000 jid=N/A status=PENDING retries=0 client_lib=MANCHEGO_GCM-11910000} [CONTEXT service_id=218 ]
11-14 12:08:31.514: I/Superpacks(2207): Syncing emoji, version: 6
11-14 12:08:31.515: I/Superpacks(2207): Using sync version 6 for emoji
11-14 12:08:31.516: I/EmojiSlicingStrategy(2207): matchEnabledLocalesWithPackLocales() : User enabled 1 locales; returning 1 slices
11-14 12:08:31.516: I/Superpacks(2207): Requesting packs for emoji with slices: [{emoji:de96d6a43683ddb4f3bc1b59bcd73bb4, *:*}]
11-14 12:08:31.524: E/ExecutionCriteria(2507): Package unavailable for task: com.google.android.youtube/com.google.android.libraries.youtube.common.gcore.gcoreclient.gcm.impl.GcmTaskServiceDelegator{u=0 tag="innertube_config_fetch_charging" trigger=window{period=172800s,flex=86400s,earliest=-864219s,latest=-777819s} requirements=[NET_CONNECTED,CHARGING] attributes=[PERSISTED,RECURRING] scheduled=-950619s last_run=N/A exec_window_multiplier=1.0000 jid=N/A status=PENDING retries=0 client_lib=MANCHEGO_GCM-11910000} [CONTEXT service_id=218 ]
11-14 12:08:31.525: E/ExecutionCriteria(2507): Package unavailable for task: com.google.android.youtube/com.google.android.libraries.youtube.common.gcore.gcoreclient.gcm.impl.GcmTaskServiceDelegator{u=0 tag="innertube_config_fetch" trigger=window{period=172800s,flex=14400s,earliest=-792219s,latest=-777819s} requirements=[NET_CONNECTED] attributes=[PERSISTED,RECURRING] scheduled=-950619s last_run=N/A exec_window_multiplier=1.0000 jid=N/A status=PENDING retries=0 client_lib=MANCHEGO_GCM-11910000} [CONTEXT service_id=218 ]
11-14 12:08:31.525: I/Superpacks(2207): There was a previous request pending for emoji
11-14 12:08:31.541: I/Superpacks-FP(2207): Fetch pipeline requesting slice {emoji:de96d6a43683ddb4f3bc1b59bcd73bb4, *:*}
11-14 12:08:31.541: I/Superpacks-FP(2207): There is a pending fetch request for emoji:de96d6a43683ddb4f3bc1b59bcd73bb4
11-14 12:08:31.541: I/Superpacks-FP(2207): Fetching pack de96d6a43683ddb4f3bc1b59bcd73bb4 to file emoji/de96d6a43683ddb4f3bc1b59bcd73bb4.zip.fetched with fetcher fpm:90955111
11-14 12:08:31.541: I/Superpacks(2207): Fetching de96d6a43683ddb4f3bc1b59bcd73bb4 to file de96d6a43683ddb4f3bc1b59bcd73bb4.zip.fetched with params: {*:*, p {relative:300}}
11-14 12:08:31.556: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:31.556: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:31.562: I/Superpacks(2207): => Scheduling downloads, reason: download registered
11-14 12:08:31.563: I/Superpacks(2207): Pending (1): [{emoji:de96d6a43683ddb4f3bc1b59bcd73bb4, W:b, p300}]
11-14 12:08:31.563: I/Superpacks(2207): Active: none
11-14 12:08:31.563: I/Superpacks(2207): Candidates for W:b (1): [{emoji:de96d6a43683ddb4f3bc1b59bcd73bb4, W:b, p300}]
11-14 12:08:31.563: I/Superpacks(2207): Scheduling job with delay of 0s for W:b, 1 candidates
11-14 12:08:31.591: I/Superpacks(2207): <== Scheduling result: 0 started, 0 paused, 1 scheduled
11-14 12:08:31.593: I/SuperDelight(2207): SuperDelightManager#syncDownloadableLanguageModelsForSuperpack(delight): [OnDevice] Syncing for version 2019100102
11-14 12:08:31.596: I/Superpacks(2207): Sync for 'handwriting_recognition' succeeded, result: {old=[pack_mapping_v1_segment_decode_20180709], new=[pack_mapping_v1_segment_decode_20180709], last batch=true}
11-14 12:08:31.596: I/AbstractSyncResult(2207): onFailure(): emoji.sync cancelled; expected if new request supersedes pending one.
11-14 12:08:31.597: I/Superpacks(2207): Syncing delight_overrides, version: -1
11-14 12:08:31.597: I/HWRSuperpacksManager(2207): syncInternal(): syncing packs
11-14 12:08:31.597: I/HWRSuperpacksManager(2207): sync(): complete {old=[pack_mapping_v1_segment_decode_20180709], new=[pack_mapping_v1_segment_decode_20180709], last batch=true}
11-14 12:08:31.601: I/Superpacks(2207): Syncing delight, version: 2019100102
11-14 12:08:31.601: I/Superpacks(2207): Using sync version 2019100102 for delight
11-14 12:08:31.602: I/SuperDelight(2207): DownloadSlicing#getSlices() : Locale = [en_US]
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207): triggerSync() : Failed to obtain model.
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207): java.util.concurrent.CancellationException: Task was cancelled.
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at gyi.a(PG:80)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at gyi.get(PG:62)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at gqb.a(PG:144)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at gzc.b(PG:28)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at gze.run(PG:5)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at java.lang.Thread.run(Thread.java:764)
11-14 12:08:31.624: W/EmojiSuperpacksManager(2207):     at bcl.run(PG:8)
11-14 12:08:31.625: I/SuperDelight(2207): DownloadSlicing#getSlices(): result {slices=[{delight:main_en_us_20180316_1, *:*}], last batch=true}
11-14 12:08:31.626: I/Superpacks(2207): Requesting packs for delight with slices: [{delight:main_en_us_20180316_1, *:*}]
11-14 12:08:31.636: I/Superpacks-FP(2207): Fetch pipeline requesting slice {delight:main_en_us_20180316_1, *:*}
11-14 12:08:31.636: I/Superpacks-FP(2207): Final file delight:main_en_us_20180316_1 is already available
11-14 12:08:31.636: I/Superpacks-FP(2207): Successfully fetched pack delight:main_en_us_20180316_1
11-14 12:08:31.638: I/SuperDelight(2207): SuperDelightMergingStrategy#merge(): selected[[main_en_us_20180316_1]] synced[[main_en_us_20180316_1]]
11-14 12:08:31.638: I/Superpacks(2207): Sync for 'delight' succeeded, result: {old=[main_en_us_20180316_1], new=[main_en_us_20180316_1], last batch=true}
11-14 12:08:31.744: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:31.744: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:31.896: I/Remoter(1916): Saved screenshot to '/data/screenshots/__pmv__-0159.jpg'
11-14 12:08:31.909: D/GeckoViewContent(6902): handleEvent: DOMWindowClose
11-14 12:08:31.918: D/GeckoViewProgressDelegate[C](7169): handleEvent: MozAfterPaint
11-14 12:08:31.918: D/GeckoViewProgressDelegate[C](7169): handleEvent: MozAfterPaint
11-14 12:08:31.930: I/Choreographer(6902): Skipped 123 frames!  The application may be doing too much work on its main thread.
11-14 12:08:31.937: D/GeckoViewSelectionActionDelegate[C](7169): handleEvent: visibilitychange
11-14 12:08:31.951: I/OpenGLRenderer(6902): Davey! duration=2075ms; Flags=0, IntendedVsync=246381785175, Vsync=248431785093, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=248436407126, AnimationStart=248436473467, PerformTraversalsStart=248437303217, DrawStart=248441613038, SyncQueued=248442794072, SyncStart=248442818019, IssueDrawCommandsStart=248443012517, SwapBuffers=248448214694, FrameCompleted=248457633045, DequeueBufferDuration=6702000, QueueBufferDuration=185000, 
11-14 12:08:31.960: D/GeckoViewNavigation(6902): onDisable
11-14 12:08:31.961: D/GeckoViewProcessHangMonitor(6902): onDisable
11-14 12:08:31.961: D/GeckoViewModule(6902): unregisterListener
11-14 12:08:31.962: D/GeckoViewProcessHangMonitor(6902): onDestroy
11-14 12:08:31.962: D/GeckoViewProgress(6902): onDisable
11-14 12:08:31.963: D/GeckoViewModule(6902): unregisterListener
11-14 12:08:31.963: D/GeckoViewModule(6902): unregisterListener
11-14 12:08:31.963: D/GeckoViewMediaControl(6902): onDisable
11-14 12:08:31.964: D/GeckoViewModule(6902): unregisterListener
11-14 12:08:31.967: D/GeckoViewMediaControl[C](7169): onDisable
11-14 12:08:31.980: D/GeckoViewSelectionActionDelegate[C](7169): handleEvent: visibilitychange
11-14 12:08:31.980: D/GeckoViewAutoFill[C](7169): handleEvent: pagehide
11-14 12:08:31.980: D/GeckoViewAutofill(7169): Clearing auto-fill
11-14 12:08:31.991: E/GeckoConsole(6902): [JavaScript Error: "TypeError: can't access property "ownerGlobal", this.browser is null" {file: "resource://gre/modules/GeckoViewActorParent.jsm" line: 22}]
11-14 12:08:31.991: E/GeckoConsole(6902): get window@resource://gre/modules/GeckoViewActorParent.jsm:22:5
11-14 12:08:31.991: E/GeckoConsole(6902): receiveMessage@resource://gre/modules/GeckoViewActorParent.jsm:31:5
11-14 12:08:31.994: E/GeckoConsole(6902): [JavaScript Error: "TypeError: can't access property "ownerGlobal", this.browser is null" {file: "resource://gre/modules/GeckoViewActorParent.jsm" line: 22}]
11-14 12:08:31.994: E/GeckoConsole(6902): get window@resource://gre/modules/GeckoViewActorParent.jsm:22:5
11-14 12:08:31.994: E/GeckoConsole(6902): receiveMessage@resource://gre/modules/GeckoViewActorParent.jsm:31:5
11-14 12:08:32.128: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:32.128: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:32.251: I/FeaturePermissions(2207): onSharedPreferenceChanged() : Key = enable_incognito_mode : Feature = null
11-14 12:08:32.251: I/FeaturePermissions(2207): onSharedPreferenceChanged() : Key = enable_incognito_mode : Feature = null
11-14 12:08:32.314: W/lla.focus.debu(6902): Accessing hidden method Landroid/view/RenderNode;->getScaleX()F (dark greylist, linking)
11-14 12:08:32.464: I/Conv2QueryExtension(2207): isEnabled() : true
11-14 12:08:32.464: I/Conv2QueryExtension(2207): Current locale: en_US, config allows these locales: de,en,fr,it,es,pt
11-14 12:08:32.465: W/Conv2QueryExtension(2207): Conv2Query not enabled due to current app [org.mozilla.focus.debug] not in whitelist
11-14 12:08:32.465: W/Conv2QueryExtension(2207): onActivate() : Disabled by unsupported host app
11-14 12:08:32.465: I/EmojiDataExtension(2207): onActivate() : Locale = en_US
11-14 12:08:32.465: I/EmojiSuperpacksFeature(2207): Emoji superpacks enabled
11-14 12:08:32.465: I/FeatureCardGenerator(2207): Make a GIF card not added
11-14 12:08:32.466: I/FeatureCardGenerator(2207): Autospace card not added
11-14 12:08:32.466: I/FeatureCardNotice(2207): Feature card notice not shown: no cards to display
11-14 12:08:32.466: I/GifEntryExtension(2207): onActivate()
11-14 12:08:32.466: I/DevFeatureConfig(2207): Gif candidate disabled by Phenotype
11-14 12:08:32.469: I/PrimesMetrics(2207): Ignore memory metric of com.google.android.apps.inputmethod.libs.pill.PillExtension.
11-14 12:08:32.469: I/PrimesMetrics(2207): Ignore memory metric of com.google.android.apps.inputmethod.libs.search.nativecard.FeatureCardNoticeExtension.
11-14 12:08:32.469: I/PrimesMetrics(2207): Ignore memory metric of com.google.android.apps.inputmethod.libs.search.gif.GifEntryPointCandidateExtension.
11-14 12:08:32.471: W/VoiceImeTranscriptor(2207): UIME feature enabled: false
11-14 12:08:32.473: I/VoiceImeExtension(2207): Voice Extension onStartInputView() Mic status = [MicIconHidden-VoiceInputUnAvailable, MicIconHidden-IncognitoMode, ]
11-14 12:08:32.556: I/FeaturePermissions(2207): onSharedPreferenceChanged() : Key = latest_activation_time : Feature = null
11-14 12:08:32.583: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:32.583: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:32.937: D/GeckoIdleService(6902): Get idle time: time since reset 5014 msec
11-14 12:08:32.937: D/GeckoIdleService(6902): Idle timer callback: current idle time 5014 msec
11-14 12:08:32.937: D/GeckoIdleService(6902): next timeout 174985 msec from now
11-14 12:08:32.937: D/GeckoIdleService(6902): SetTimerExpiryIfBefore: next timeout 174985 msec from now
11-14 12:08:32.937: D/GeckoIdleService(6902): reset timer expiry to 174995 msec from now
11-14 12:08:32.937: D/GeckoIdleService(6902): Idle timer callback: tell observer 0xabf935b4 user is idle
11-14 12:08:33.082: W/google-breakpad(6902): ExceptionHandler::GenerateDump cloned child 
11-14 12:08:33.082: W/google-breakpad(6902): 8051
11-14 12:08:33.082: W/google-breakpad(6902): ExceptionHandler::SendContinueSignalToChild sent continue signal to child
11-14 12:08:33.091: W/google-breakpad(8051): ExceptionHandler::WaitForContinueSignal waiting for continue signal...
11-14 12:08:33.099: I/Remoter(1916): Saved screenshot to '/data/screenshots/__pmv__-0160.jpg'
11-14 12:08:33.120: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:33.120: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:33.213: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:33.213: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:33.487: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:33.488: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:33.719: E/memtrack(2073): Couldn't load memtrack module
11-14 12:08:33.719: W/android.os.Debug(2073): failed to get memory consumption info: -1
11-14 12:08:33.796: I/Zygote(8104): seccomp disabled by setenforce 0
11-14 12:08:33.797: I/sh.CrashHandle(8104): Late-enabling -Xcheck:jni
11-14 12:08:33.798: I/ActivityManager(2073): Start proc 8104:org.mozilla.focus.debug:mozilla.components.lib.crash.CrashHandler/u0a95 for service org.mozilla.focus.debug/mozilla.components.lib.crash.handler.CrashHandlerService
11-14 12:08:33.817: W/sh.CrashHandle(8104): Unexpected CPU variant for X86 using defaults: x86
11-14 12:08:33.844: I/Gecko(7169): Exiting due to channel error.
11-14 12:08:33.844: W/InputDispatcher(2073): channel 'cf1e679 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
11-14 12:08:33.844: E/InputDispatcher(2073): channel 'cf1e679 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
11-14 12:08:33.847: I/ActivityManager(2073): Process org.mozilla.focus.debug (pid 6902) has died: fore TOP 
11-14 12:08:33.847: W/ActivityManager(2073): Force removing ActivityRecord{9be2adc u0 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity t4}: app died, no saved state
11-14 12:08:33.847: W/libprocessgroup(2073): kill(-6902, 9) failed: No such process
11-14 12:08:33.848: W/WindowManager(2073): removeWindowToken: Attempted to remove non-existing token: android.os.Binder@463e229
11-14 12:08:33.849: I/WindowManager(2073): WIN DEATH: Window{cf1e679 u0 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity}
11-14 12:08:33.849: W/InputDispatcher(2073): Attempted to unregister already unregistered input channel 'cf1e679 org.mozilla.focus.debug/org.mozilla.focus.activity.MainActivity (server)'
11-14 12:08:33.851: W/BinderNative(2073): Uncaught exception from death notification
11-14 12:08:33.851: W/BinderNative(2073): java.lang.NullPointerException: Attempt to read from field 'android.accessibilityservice.IAccessibilityServiceClient com.android.server.accessibility.AbstractAccessibilityServiceConnection.mServiceInterface' on a null object reference
11-14 12:08:33.851: W/BinderNative(2073):   at com.android.server.accessibility.UiAutomationManager.destroyUiAutomationService(UiAutomationManager.java:179)
11-14 12:08:33.851: W/BinderNative(2073):   at com.android.server.accessibility.UiAutomationManager.access$200(UiAutomationManager.java:41)
11-14 12:08:33.851: W/BinderNative(2073):   at com.android.server.accessibility.UiAutomationManager$UiAutomationService.binderDied(UiAutomationManager.java:230)
11-14 12:08:33.851: W/BinderNative(2073):   at android.os.BinderProxy.sendDeathNotice(Binder.java:1193)
11-14 12:08:33.853: W/ActivityManager(2073): Crash of app org.mozilla.focus.debug running instrumentation ComponentInfo{org.mozilla.focus.debug.test/androidx.test.runner.AndroidJUnitRunner}
11-14 12:08:33.853: I/ActivityManager(2073): Force stopping org.mozilla.focus.debug appid=10095 user=0: finished inst
11-14 12:08:33.853: I/ActivityManager(2073): Killing 7169:org.mozilla.focus.debug:tab11/u0a95 (adj 0): stop org.mozilla.focus.debug
11-14 12:08:33.855: I/ActivityManager(2073): Killing 8104:org.mozilla.focus.debug:mozilla.components.lib.crash.CrashHandler/u0a95 (adj 200): stop org.mozilla.focus.debug
11-14 12:08:33.855: W/ActivityManager(2073): Scheduling restart of crashed service org.mozilla.focus.debug/mozilla.components.lib.crash.handler.CrashHandlerService in 1000ms
11-14 12:08:33.856: I/ActivityManager(2073):   Force stopping service ServiceRecord{cfbb238 u0 org.mozilla.focus.debug/mozilla.components.lib.crash.handler.CrashHandlerService}
11-14 12:08:33.856: W/ActivityManager(2073): Bringing down service while still waiting for start foreground: ServiceRecord{cfbb238 u0 org.mozilla.focus.debug/mozilla.components.lib.crash.handler.CrashHandlerService}
11-14 12:08:33.859: I/Zygote(1910): Process 6902 exited cleanly (11)
11-14 12:08:33.867: V/SettingsProvider(2073): Notifying for 0: content://settings/secure/accessibility_enabled
11-14 12:08:33.868: W/ActivityManager(2073): setHasOverlayUi called on unknown pid: 6902
11-14 12:08:33.871: W/Binder(6823): Binder call failed.
11-14 12:08:33.871: W/Binder(6823): java.lang.SecurityException: Calling from not trusted UID!
11-14 12:08:33.871: W/Binder(6823):     at android.app.UiAutomationConnection.throwIfCalledByNotTrustedUidLocked(UiAutomationConnection.java:468)
11-14 12:08:33.871: W/Binder(6823):     at android.app.UiAutomationConnection.shutdown(UiAutomationConnection.java:372)
11-14 12:08:33.871: W/Binder(6823):     at android.app.IUiAutomationConnection$Stub.onTransact(IUiAutomationConnection.java:222)
11-14 12:08:33.871: W/Binder(6823):     at android.os.Binder.execTransact(Binder.java:731)
11-14 12:08:33.884: W/libprocessgroup(2073): kill(-6902, 9) failed: No such process
11-14 12:08:33.884: I/libprocessgroup(2073): Successfully killed process cgroup uid 10095 pid 6902 in 36ms
11-14 12:08:33.884: W/libprocessgroup(2073): kill(-7169, 9) failed: No such process
11-14 12:08:33.886: W/Binder(2073): Outgoing transactions from this process must be FLAG_ONEWAY
11-14 12:08:33.886: W/Binder(2073): java.lang.Throwable
11-14 12:08:33.886: W/Binder(2073):     at android.os.BinderProxy.transact(Binder.java:1114)
11-14 12:08:33.886: W/Binder(2073):     at android.app.IInstrumentationWatcher$Stub$Proxy.instrumentationFinished(IInstrumentationWatcher.java:164)
11-14 12:08:33.886: W/Binder(2073):     at com.android.server.am.InstrumentationReporter$MyThread.run(InstrumentationReporter.java:86)
11-14 12:08:33.888: D/AndroidRuntime(6823): Shutting down VM
11-14 12:08:33.890: I/PhenotypeExpConfig(2207): refreshConfiguration() : Force = false : UpdateAvailable = false : Age = 0 minutes : MaxAge = 720 minutes
11-14 12:08:33.891: I/FeaturePermissions(2207): onSharedPreferenceChanged() : Key = enable_incognito_mode : Feature = null
11-14 12:08:33.892: I/GoogleInputMethod(2207): onStartInput() : Dummy InputConnection bound
11-14 12:08:33.892: I/GoogleInputMethod(2207): onStartInputView() : Dummy InputConnection bound
11-14 12:08:33.914: I/Zygote(1910): Process 8104 exited due to signal (9)
11-14 12:08:33.915: W/VoiceImeTranscriptor(2207): UIME feature enabled: false
11-14 12:08:33.926: W/libprocessgroup(2073): kill(-7169, 9) failed: No such process
11-14 12:08:33.945: I/Zygote(1910): Process 7169 exited cleanly (0)
11-14 12:08:33.960: I/Conv2QueryExtension(2207): isEnabled() : true
11-14 12:08:33.960: I/Conv2QueryExtension(2207): Current locale: en_US, config allows these locales: de,en,fr,it,es,pt
11-14 12:08:33.960: W/Conv2QueryExtension(2207): Conv2Query not enabled due to current app [com.android.launcher3] not in whitelist
11-14 12:08:33.960: W/Conv2QueryExtension(2207): onActivate() : Disabled by unsupported host app
11-14 12:08:33.960: I/EmojiDataExtension(2207): onActivate() : Locale = en_US
11-14 12:08:33.960: I/EmojiSuperpacksFeature(2207): Emoji superpacks enabled
11-14 12:08:33.961: I/FeatureCardGenerator(2207): Make a GIF card not added
11-14 12:08:33.961: I/FeatureCardGenerator(2207): Autospace card not added
11-14 12:08:33.961: I/FeatureCardNotice(2207): Feature card notice not shown: no cards to display
11-14 12:08:33.961: I/GifEntryExtension(2207): onActivate()
11-14 12:08:33.961: I/DevFeatureConfig(2207): Gif candidate disabled by Phenotype
11-14 12:08:33.962: I/PrimesMetrics(2207): Ignore memory metric of com.google.android.apps.inputmethod.libs.pill.PillExtension.
11-14 12:08:33.962: I/PrimesMetrics(2207): Ignore memory metric of com.google.android.apps.inputmethod.libs.search.nativecard.FeatureCardNoticeExtension.
11-14 12:08:33.962: I/PrimesMetrics(2207): Ignore memory metric of com.google.android.apps.inputmethod.libs.search.gif.GifEntryPointCandidateExtension.
11-14 12:08:33.975: W/libprocessgroup(2073): kill(-7169, 9) failed: No such process
11-14 12:08:33.975: I/libprocessgroup(2073): Successfully killed process cgroup uid 10095 pid 7169 in 91ms
11-14 12:08:33.975: W/libprocessgroup(2073): kill(-8104, 9) failed: No such process
11-14 12:08:33.975: I/libprocessgroup(2073): Successfully killed process cgroup uid 10095 pid 8104 in 0ms
11-14 12:08:33.990: W/VoiceImeTranscriptor(2207): UIME feature enabled: false
11-14 12:08:33.990: I/VoiceImeExtension(2207): Voice Extension onStartInputView() Mic status = [MicIconHidden-VoiceInputUnAvailable, ]
11-14 12:08:34.032: I/FeaturePermissions(2207): onSharedPreferenceChanged() : Key = latest_activation_time : Feature = null
11-14 12:08:34.123: I/Remoter(1916): Saved screenshot to '/data/screenshots/__pmv__-0161.jpg'
11-14 12:08:34.202: I/ActivityManager(2073): Force stopping androidx.test.orchestrator appid=10089 user=0: finished inst
11-14 12:08:34.202: W/Binder(2073): Outgoing transactions from this process must be FLAG_ONEWAY
11-14 12:08:34.202: W/Binder(2073): java.lang.Throwable
11-14 12:08:34.202: W/Binder(2073):     at android.os.BinderProxy.transact(Binder.java:1114)
11-14 12:08:34.202: W/Binder(2073):     at android.app.IInstrumentationWatcher$Stub$Proxy.instrumentationFinished(IInstrumentationWatcher.java:164)
11-14 12:08:34.202: W/Binder(2073):     at com.android.server.am.InstrumentationReporter$MyThread.run(InstrumentationReporter.java:86)
11-14 12:08:34.203: I/ActivityManager(2073): Killing 6766:androidx.test.orchestrator/u0a89 (adj 0): stop androidx.test.orchestrator
11-14 12:08:34.204: W/libprocessgroup(2073): kill(-6766, 9) failed: No such process
11-14 12:08:34.204: D/AndroidRuntime(6736): Shutting down VM
11-14 12:08:34.211: W/app_process(6736): Thread attaching while runtime is shutting down: Binder:6736_3
11-14 12:08:34.211: I/AndroidRuntime(6736): NOTE: attach of thread 'Binder:6736_3' failed
11-14 12:08:34.247: W/libprocessgroup(2073): kill(-6766, 9) failed: No such process
11-14 12:08:34.248: I/Zygote(1910): Process 6766 exited due to signal (9)
11-14 12:08:34.257: I/app_process(6668): System.exit called, status: 0
11-14 12:08:34.257: I/AndroidRuntime(6668): VM exiting with result code 0.
11-14 12:08:34.291: W/libprocessgroup(2073): kill(-6766, 9) failed: No such process
11-14 12:08:34.292: I/libprocessgroup(2073): Successfully killed process cgroup uid 10089 pid 6766 in 87ms
11-14 12:08:34.326: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
11-14 12:08:34.327: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
11-14 12:08:34.329: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
11-14 12:08:34.329: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
11-14 12:08:34.330: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
11-14 12:08:34.330: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
11-14 12:08:34.331: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
11-14 12:08:34.331: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
11-14 12:08:34.332: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
11-14 12:08:34.332: W/BroadcastQueue(2073): Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
11-14 12:08:34.354: I/GoogleInputMethod(2207): onFinishInputView() : Dummy InputConnection bound
11-14 12:08:34.354: I/PhenotypeExpConfig(2207): refreshConfiguration() : Force = false : UpdateAvailable = false : Age = 0 minutes : MaxAge = 720 minutes

Build: 11/14 Main

Notes

❗ The last performed action was to tap the clearBrowsingData button after a tracker blocking page is properly opened.

sv-ohorvath commented 2 years ago

Might be fixed with https://github.com/mozilla-mobile/focus-android/pull/5798. We'll wait for a while to see if it appears again and close this if not.

mcarare commented 2 years ago

Not that fixes the problem, but I wonder why so many tests have this step (clearing browsing data) when they test other components of the app. If a test part makes a test flaky it should be avoided in places it is not necessary.

sv-ohorvath commented 2 years ago

It is necessary. In the case of ETP tests, we need to load a page before the ETP test page to allow GV to load on a first cold startup (a thing I learned from Arturo :) ). If I don't clear the page, sometimes the app shows the content of the initial page instead of the ETP page. If I'd just refresh the ETP page it would show a blank page sometimes or hang. So by not clearing data, I would trade one flaky part of the test with another.

mcarare commented 2 years ago

It is necessary. In the case of ETP tests, we need to load a page before the ETP test page to allow GV to load on a first cold startup (a thing I learned from Arturo :) ). If I don't clear the page, sometimes the app shows the content of the initial page instead of the ETP page. If I'd just refresh the ETP page it would show a blank page sometimes or hang. So by not clearing data, I would trade one flaky part of the test with another.

But you could get the same effect by pressing the back button (mDevice.pressBack()) instead of the erase button. Am I wrong?

sv-ohorvath commented 2 years ago

No, you are right, I could try that as well. Thanks for the tip.