User Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/604.4.7 (KHTML, like Gecko) Version/11.0.2 Safari/604.4.7 Steps to reproduce: Mostly looking at Focus + GeckoView but same is true for Fennec. Focus takes a lot longer than Edge etc from cold start to having a URL completely loaded. Force stop Focus from the Android App info menu In Fennec navigate to www.bbc.co.uk/news Drop down the three-dot menu & click the "share" icon Select Firefox Focus Time from that click until the site is fully loaded in Focus. About 9 seconds on my Sony Xperia X Compact:- - about 6 seconds showing a blank screen with the site in the URL bar but no apparent activity - plus a short period showing about:blank in the URL bar, still a blank screen - plus about 3 seconds to load the page Carry out the same process using e.g. Edge [could use another Chromium browser] Force stop Edge in Fennec, open the three-dot menu and then hit "share" Choose to open in Edge About 3 seconds on my Sony Xperia X Compact until the page is fully loaded Miles quicker! I see similar on my slower older phone, the difference is even more marked. I see the same on Fennec, from cold start about 6 seconds before anything really happens and then 3 seconds to load the page. What's Focus doing for those initial 6 seconds? It's important if one's phone is short of RAM, using other apps kills Focus (or Fennec) and then it can be a long wait to get back to where one was. Competitors seem a lot quicker. geckoview_example.apk seems better, it doesn't have that initial 6 second delay, perhaps 1 second delay then it starts loading the page.

On my slow old phone (Snapdragon 425) it's excruciatingly slow:- Focus + GeckoView, about 20 seconds from cold start until page fully loaded. - about 13 seconds showing a blank screen with the site URL in the URL bar but no apparent activity - plus a few seconds showing about:blank in the URL bar, still a blank screen - plus about 6 seconds to load the page Edge, about 5 seconds from cold start until page fully loaded. Fennec Nightly, around 11 seconds.

I suspect the Focus + GeckoView problem is Focus issue #3853 where Focus clears its app cache every time. That's not a problem for WebView, but GeckoView stores some decompressed files (libxul) in the app cache, so clearing it forces GeckoView to decompress the files on startup every time. I'm not sure why Fennec is so slow to start. It should have the app cache problem. https://github.com/mozilla-mobile/focus-android/issues/3853

I posted on the github issue about decompression. Thanks. Why does Focus also load about:blank? It adds a couple of seconds to the total cold start to url loaded time? Fennec has always been much slower to cold start than competitors. But not as bad as Focus.

Vlad, have you or any of the QA folks noticed a colder slow start time in Fennec lately?

I don't think this is anything new, it's always been really slow. I just never filed a bug, I thought it was either (a) the price of add ons or (b) you already knew :) I see the same problem on 62, 63 & 64, but it goes way back IIRC.

Vlad can you actually have QA see if they can reproduce the similar slow startup time as described above? I wasn’t sure who to NI.

Re Fennec, I timed it on my old BQ Aquaris X5 which I think performs like your Moto G5. I compared Fennec Nightly with Samsung Internet Browser Beta which is reasonably similar:- a full-fat (Chromium) browser which supports add ons (aka extensions). I used Adblock Plus as the test add on since it’s available on both browsers. I assume it’s broadly the same codebase for both. I used a clean install of Fennec 64.0b7 Time from cold start until mozilla.org fully loaded:- Fennec Nightly Samsung Internet with no add ons ~9 sec ~3 sec with AdBlock Plus add on ~10 sec ~4 sec I don’t think this is a regression, it’s always been slow to cold start. You don’t notice so much on a fast new phone, but on an entry level phone it's really obvious how much longer Fennec takes to *fully* cold start than Samsung Internet (and Edge, and Chrome, blah blah). By *fully* cold start I mean time from tapping the icon to actually starting to load a page - not just showing a UI or the Top Sites page.

You could close this bug, perhaps we should be looking more at GeckoView cold start times and not worrying too much about Fennec? My about:blank issue is already covered by https://github.com/mozilla-mobile/focus-android/issues/3473 I filed bug 1506099 to talk about the slow cold start issue, particularly looking at GeckoView testing.

Ioana, can someone from the team take a look at this issue ?

@Chris, can you help me? Part of my problem in comment 0 is I think that sometimes Fennec Nightly is very slow to start up when adblocking add ons are enabled. I definitely see this with Adblock Plus, but it's intermittent. I think I have also seen it with uBlock Origin as well but can't reproduce. When I have this problem I consistently see the same thing in the adb log:- on my slow BQ phone, a long pause of up to 10 seconds after "New network state: UP, WIFI, WIFI". I see similar on my other phone. I've put the full log below but here's the relevant extract with ~8 second pause this time. 11-09 18:24:32.419 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:40.590 5318 5318 D GeckoToolbar: onTabChanged: SECURITY_CHANGE On the screen the progress bar just stalls about 60% of the way across the screen. For ages. When this problem occurs, my BQ phone takes up to 20 seconds to load mozilla.org from cold start, compared to the usual ~10 seconds. Once Fennec gets into this state it's entirely repeatable. I can disable & re-enable ABP and it comes back. But I don't know how to get it into this state in the first place :) struggling... Can you or your colleagues give me any hints about what's going on here & what I can do to dig in more. (sorry if this is the wrong place to post) Mark 11-09 18:24:25.990 5318 5318 I GeckoApplication: zerdatime 277262313 - application start 11-09 18:24:26.152 5318 5333 D GeckoSharedPrefs: Current version = 2, prefs version = 2 11-09 18:24:26.153 5318 5318 D GeckoScreenOrientation: updating to new orientation PORTRAIT_PRIMARY 11-09 18:24:26.160 5318 5318 D GeckoThread: State changed to LAUNCHED 11-09 18:24:26.161 5318 5336 I GeckoThread: preparing to run Gecko 11-09 18:24:26.161 5318 5336 D GeckoThread: State changed to MOZGLUE_READY 11-09 18:24:26.162 5318 5336 E GeckoLibLoad: Load sqlite start 11-09 18:24:26.179 5318 5336 W GeckoLinker: /data/app/org.mozilla.fennec_aurora-1/base.apk!/assets/armeabi-v7a/libnss3.so: Relocation to NULL @0x00135980 for symbol "__cxa_type_match" 11-09 18:24:26.179 5318 5336 E GeckoLibLoad: Load sqlite done 11-09 18:24:26.179 5318 5336 E GeckoLibLoad: Load nss start 11-09 18:24:26.180 5318 5336 E GeckoLibLoad: Load nss done 11-09 18:24:26.213 5318 5336 E GeckoLibLoad: Loaded libs in 33.173854ms total, 20ms(40ms) user, 20ms(20ms) system, 0(0) faults 11-09 18:24:26.213 5318 5336 D GeckoThread: State changed to LIBS_READY 11-09 18:24:26.213 5318 5336 W GeckoThread: zerdatime 277262535 - runGecko 11-09 18:24:26.215 5318 5336 D GeckoProfile: Found profile dir. 11-09 18:24:26.300 5318 5336 I Gecko:DumpUtils: Fifo watcher disabled via pref. 11-09 18:24:26.389 5318 5318 D GeckoSysInfo: System memory: 1912MB. 11-09 18:24:26.397 5318 5333 D GeckoSessInfo: Building SessionInformation from prefs: 1541787861685, 0, true, false 11-09 18:24:26.399 5318 5333 D GeckoApp: OS locale is en_GB, app locale is null 11-09 18:24:26.400 5318 5318 D GeckoNetworkManager: Incoming event start for state OffNoListeners -> OnNoListeners 11-09 18:24:26.400 5318 5333 D GeckoLocales: Previous locale en_GB same as new. Doing nothing. 11-09 18:24:26.404 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:26.430 5318 5333 D GeckoJarReader: No Entry for chrome/en-GB/locale/en-GB/browser/searchplugins/list.json 11-09 18:24:26.432 5318 5333 D GeckoJarReader: No Entry for chrome/en/locale/en/browser/searchplugins/list.json 11-09 18:24:26.435 5318 5333 D GeckoJarReader: No Entry for chrome/en-US/locale/en-US/browser/searchplugins/list.json 11-09 18:24:26.435 5318 5333 E GeckoSearchEngineManager: Error missing list.json 11-09 18:24:26.437 5318 5333 D GeckoJarReader: No Entry for chrome/en-GB/locale/en-GB/browser/searchplugins/list.json 11-09 18:24:26.438 5318 5333 D GeckoJarReader: No Entry for chrome/en/locale/en/browser/searchplugins/list.json 11-09 18:24:26.439 5318 5333 D GeckoJarReader: No Entry for chrome/en-US/locale/en-US/browser/searchplugins/list.json 11-09 18:24:26.440 5318 5333 E GeckoSearchEngineManager: Could not find search plugin files in profile directory 11-09 18:24:26.440 5318 5333 E GeckoSearchEngineManager: Could not create search engine from name: null 11-09 18:24:26.440 5318 5333 D GeckoSessInfo: Recording start of session: 1541787866424 11-09 18:24:26.460 5318 5354 D GeckoDLCCatalog: Loading from disk 11-09 18:24:26.476 5318 5354 D GeckoDLCCatalog: Loaded 48 elements 11-09 18:24:26.480 5318 5354 V GeckoDLCCatalog: Not persisting: Catalog has not changed 11-09 18:24:26.485 5318 5336 D GeckoThread: State changed to JNI_READY 11-09 18:24:26.515 5318 5318 D GeckoToolbar: onTabChanged: RESTORED 11-09 18:24:26.537 5318 5318 D GeckoToolbar: onTabChanged: SELECTED 11-09 18:24:26.540 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: SELECTED 11-09 18:24:26.542 5318 5318 D GeckoToolbar: onTabChanged: TITLE 11-09 18:24:26.543 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: TITLE 11-09 18:24:26.544 5318 5318 D GeckoBrowserApp: onLocaleReady: en_GB 11-09 18:24:26.548 5318 5318 D GeckoNetworkManager: Incoming event receivedUpdate for state OnNoListeners -> OnNoListeners 11-09 18:24:26.550 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:26.552 5318 5318 D GeckoToolbar: onTabChanged: MENU_UPDATED 11-09 18:24:26.553 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: MENU_UPDATED 11-09 18:24:26.671 5318 5363 D GeckoTelemetryUploadSer: Service started 11-09 18:24:27.154 5318 5336 D GeckoThread: State changed to PROFILE_READY 11-09 18:24:27.183 5318 5318 D GeckoNetworkManager: Incoming event enableNotifications for state OnNoListeners -> OnWithListeners 11-09 18:24:27.186 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:27.192 5318 5318 D GeckoNetworkManager: Incoming event receivedUpdate for state OnWithListeners -> OnWithListeners 11-09 18:24:27.194 5318 5336 W GeckoEventDispatcher: No listener for AndroidCastDevice:SyncDevice 11-09 18:24:27.195 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:27.202 5318 5336 I Gecko : -*- nsDNSServiceDiscovery.js : nsDNSServiceDiscovery 11-09 18:24:27.312 5318 5336 I Gecko : Attempting load of libEGL.so 11-09 18:24:27.311 5336 5336 W Gecko : type=1400 audit(0.0:19523): avc: denied { read } for name="gpuclk" dev="sysfs" ino=10603 scontext=u:r:untrusted_app:s0:c512,c768 tcontext=u:object_r:sysfs:s0 tclass=file permissive=0 11-09 18:24:27.525 5318 5363 D GeckoTelemetryUploadSer: Telemetry upload success! 11-09 18:24:27.526 5318 5363 D GeckoTelemetryUploadSer: Service finished: upload and prune attempts completed 11-09 18:24:27.686 5318 5369 I GeckoConsole: OpenGL compositor Initialized Succesfully. 11-09 18:24:27.686 5318 5369 I GeckoConsole: Version: OpenGL ES 3.0 V@145.0 AU@ (GIT@I09d312ff84) 11-09 18:24:27.686 5318 5369 I GeckoConsole: Vendor: Qualcomm 11-09 18:24:27.686 5318 5369 I GeckoConsole: Renderer: Adreno (TM) 306 11-09 18:24:27.686 5318 5369 I GeckoConsole: FBO Texture Target: TEXTURE_2D 11-09 18:24:27.671 5369 5369 W Gecko : type=1400 audit(0.0:19524): avc: denied { read } for name="gpuclk" dev="sysfs" ino=10603 scontext=u:r:untrusted_app:s0:c512,c768 tcontext=u:object_r:sysfs:s0 tclass=file permissive=0 11-09 18:24:28.091 5318 5336 D GeckoThread: State changed to RUNNING 11-09 18:24:28.091 5318 5336 I GeckoSession: zerdatime 277264413 - chrome startup finished 11-09 18:24:28.133 5318 5336 I Gecko : console.log: "browser.js: loading Firefox Accounts WebChannel" 11-09 18:24:28.140 5318 5336 I GeckoConsole: browser.js: loading Firefox Accounts WebChannel 11-09 18:24:28.166 5318 5336 D GeckoFxAccounts: FxAccountsWebChannel registered: account_updates with origin https://accounts.firefox.com 11-09 18:24:28.169 5318 5333 D GeckoPushService: Registered Gecko event listener. 11-09 18:24:28.169 5318 5333 I GeckoPushService: Starting up. 11-09 18:24:28.169 5318 5333 I GeckoPushManager: Startup: requesting GCM token. 11-09 18:24:28.174 5318 5336 D GeckoMediaControlAgent: initialize 11-09 18:24:28.186 5318 5333 I GeckoPushGCM: Cached GCM token exists. 11-09 18:24:28.186 5318 5333 I GeckoPushManager: Startup: advancing all registrations. 11-09 18:24:28.187 5318 5333 I GeckoPushManager: Startup: no subscriptions for profileName; not advancing registration: default 11-09 18:24:28.337 5318 5336 W GeckoLinker: /data/app/org.mozilla.fennec_aurora-1/base.apk!/assets/armeabi-v7a/libsoftokn3.so: Relocation to NULL @0x00025608 for symbol "__cxa_type_match" 11-09 18:24:28.338 5318 5336 W GeckoLinker: /data/app/org.mozilla.fennec_aurora-1/base.apk!/assets/armeabi-v7a/libfreebl3.so: Relocation to NULL @0x00042208 for symbol "__cxa_type_match" 11-09 18:24:28.377 5318 5386 W GeckoLinker: /data/app/org.mozilla.fennec_aurora-1/base.apk!/assets/armeabi-v7a/libnssckbi.so: Relocation to NULL @0x00056a80 for symbol "__cxa_type_match" 11-09 18:24:28.387 5318 5318 I GeckoTabs: zerdatime 277264709 - page load start 11-09 18:24:28.387 5318 5318 D GeckoToolbar: onTabChanged: START 11-09 18:24:28.388 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: START 11-09 18:24:28.396 5318 5318 D GeckoNetworkManager: Incoming event enableNotifications for state OnWithListeners -> null 11-09 18:24:28.396 5318 5318 W GeckoNetworkManager: Invalid event enableNotifications for state OnWithListeners 11-09 18:24:28.408 5318 5336 D GeckoScreenOrientation: unlocking 11-09 18:24:28.452 5318 5336 D GeckoViewAutoFill: Clearing auto-fill 11-09 18:24:28.501 5318 5318 I GeckoTabs: zerdatime 277264823 - page load stop 11-09 18:24:28.501 5318 5318 D GeckoToolbar: onTabChanged: STOP 11-09 18:24:28.503 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: STOP 11-09 18:24:28.521 5318 5318 I GeckoTabs: zerdatime 277264843 - page load start 11-09 18:24:28.521 5318 5318 D GeckoToolbar: onTabChanged: START 11-09 18:24:28.524 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: START 11-09 18:24:28.676 5318 5333 I GeckoPushService: Handling event: PushServiceAndroidGCM:Configure 11-09 18:24:28.676 5318 5333 I GeckoPushManager: Updating configuration. 11-09 18:24:28.711 5318 5336 D GeckoViewAutoFill: Clearing auto-fill 11-09 18:24:28.715 5318 5336 W GeckoConsole: [JavaScript Warning: "Content Security Policy: Directive ‘child-src’ has been deprecated. Please use directive ‘worker-src’ to control workers, or directive ‘frame-src’ to control frames respectively."] 11-09 18:24:28.718 5318 5318 D GeckoNetworkManager: Incoming event disableNotifications for state OnWithListeners -> OnNoListeners 11-09 18:24:28.727 5318 5318 D GeckoToolbar: onTabChanged: LOCATION_CHANGE 11-09 18:24:28.729 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: LOCATION_CHANGE 11-09 18:24:28.768 5318 5318 D GeckoToolbar: onTabChanged: SECURITY_CHANGE 11-09 18:24:28.769 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: SECURITY_CHANGE 11-09 18:24:28.770 5318 5333 I GeckoPushService: Handling event: PushServiceAndroidGCM:Initialized 11-09 18:24:28.874 5318 5336 W GeckoEventDispatcher: No listener for GeckoView:AccessibilityReady 11-09 18:24:29.442 5318 5336 D GeckoDistribution: Custom distribution directory not found. 11-09 18:24:29.509 5318 5336 I GeckoConsole: 1541787869508 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing background.persistent: Event pages are not currently supported. This will run as a persistent background page. 11-09 18:24:29.509 5318 5336 I Gecko : 1541787869508 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing background.persistent: Event pages are not currently supported. This will run as a persistent background page. 11-09 18:24:29.535 5318 5336 I GeckoConsole: 1541787869534 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing permissions.2: Value "contextMenus" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["topSites"], be one of ["webNavigation"], be one of ["webRequest", "webRequestBlocking"], or be one of ["activeTab", "tabs"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["contextualIdentities"], be one of ["dns"], be one of ["management"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], be one of ["browsingData"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/( 11-09 18:24:29.535 5318 5336 I Gecko : 1541787869534 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing permissions.2: Value "contextMenus" must either: must either [must either [be one of ["clipboardRead", "clipboardWrite", "geolocation", "idle", "notifications"], be one of ["browserSettings"], be one of ["cookies"], be one of ["downloads", "downloads.open"], be one of ["topSites"], be one of ["webNavigation"], be one of ["webRequest", "webRequestBlocking"], or be one of ["activeTab", "tabs"]], be one of ["alarms", "mozillaAddons", "storage", "unlimitedStorage"], be one of ["contextualIdentities"], be one of ["dns"], be one of ["management"], be one of ["privacy"], be one of ["proxy"], be one of ["nativeMessaging"], be one of ["telemetry"], be one of ["theme"], be one of ["browsingData"], or match the pattern /^experiments(\.\w+)+$/], or must either [be one of ["<all_urls>"], must either [match the pattern /^(https?|wss?|file|ftp|\*):\/\/( 11-09 18:24:29.541 5318 5336 I GeckoConsole: 1541787869540 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing browser_action.default_icon: Unsupported on Android. 11-09 18:24:29.541 5318 5336 I Gecko : 1541787869540 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing browser_action.default_icon: Unsupported on Android. 11-09 18:24:29.548 5318 5336 I GeckoConsole: 1541787869548 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing devtools_page: An unexpected property was found in the WebExtension manifest. 11-09 18:24:29.549 5318 5336 I Gecko : 1541787869548 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing devtools_page: An unexpected property was found in the WebExtension manifest. 11-09 18:24:29.550 5318 5336 I GeckoConsole: 1541787869550 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing storage: An unexpected property was found in the WebExtension manifest. 11-09 18:24:29.551 5318 5336 I Gecko : 1541787869550 addons.webextension.{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} WARN Loading extension '{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}': Reading manifest: Error processing storage: An unexpected property was found in the WebExtension manifest. 11-09 18:24:29.612 5318 5336 W GeckoEventDispatcher: No listener for Website:AppLeft 11-09 18:24:30.289 5318 5336 W GeckoConsole: [JavaScript Warning: "Successfully compiled asm.js code (total compilation time 5ms; not stored in cache (too small to benefit))" {file: "moz-extension://4882113d-c328-411f-8772-6b9250156130/lib/adblockplus.js" line: 0}] 11-09 18:24:30.549 5318 5336 E GeckoConsole: [JavaScript Error: "Native manifests are not supported on android" {file: "resource://gre/modules/NativeManifests.jsm" line: 49}] 11-09 18:24:30.549 5318 5336 E GeckoConsole: init@resource://gre/modules/NativeManifests.jsm:49:15 11-09 18:24:30.549 5318 5336 E GeckoConsole: lookupManifest@resource://gre/modules/NativeManifests.jsm:141:12 11-09 18:24:30.549 5318 5336 E GeckoConsole: lookupManagedStorage@chrome://extensions/content/parent/ext-storage.js:29:20 11-09 18:24:30.549 5318 5336 E GeckoConsole: get@chrome://extensions/content/parent/ext-storage.js:133:24 11-09 18:24:30.549 5318 5336 E GeckoConsole: call/result</<@resource://gre/modules/ExtensionParent.jsm:948:49 11-09 18:24:30.549 5318 5336 E GeckoConsole: withPendingBrowser@resource://gre/modules/ExtensionParent.jsm:602:26 11-09 18:24:30.549 5318 5336 E GeckoConsole: call/result<@resource://gre/modules/ExtensionParent.jsm:947:16 11-09 18:24:30.549 5318 5336 E GeckoConsole: withTiming@resource://gre/modules/ExtensionParent.jsm:914:14 11-09 18:24:30.549 5318 5336 E GeckoConsole: call@resource://gre/modules/ExtensionParent.jsm:946:20 11-09 18:24:32.409 5318 5318 D GeckoNetworkManager: Incoming event enableNotifications for state OnNoListeners -> OnWithListeners 11-09 18:24:32.413 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:32.416 5318 5318 D GeckoNetworkManager: Incoming event receivedUpdate for state OnWithListeners -> OnWithListeners 11-09 18:24:32.419 5318 5318 D GeckoNetworkManager: New network state: UP, WIFI, WIFI 11-09 18:24:40.590 5318 5318 D GeckoToolbar: onTabChanged: SECURITY_CHANGE 11-09 18:24:40.590 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: SECURITY_CHANGE 11-09 18:24:40.602 5318 5318 D GeckoToolbar: onTabChanged: LOADED 11-09 18:24:40.603 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: LOADED 11-09 18:24:41.009 5318 5333 W GeckoGlobalHistory: Rebuilding visited link set... 11-09 18:24:41.325 5318 5336 I GeckoConsole: ON PAINT WHEN WAITED FOR 11-09 18:24:41.357 5318 5336 W GeckoEventDispatcher: No listener for Session:DataWritten 11-09 18:24:42.896 5318 5336 W GeckoConsole: [JavaScript Warning: "In add-on {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}, attempting to use listener "notifications.onButtonClicked", which is unimplemented." {file: "resource://gre/modules/ExtensionParent.jsm" line: 1025}] 11-09 18:24:43.085 5318 5318 I GeckoBrowserApp: Found tag 11-09 18:24:43.132 5318 5354 D GeckoDLCCatalog: Loading from disk 11-09 18:24:43.147 5318 5354 D GeckoDLCCatalog: Loaded 48 elements 11-09 18:24:43.174 5318 5354 V GeckoDLCCatalog: Not persisting: Catalog has not changed 11-09 18:24:43.465 5318 5354 V GeckoDLCCatalog: Not persisting: Catalog has not changed 11-09 18:24:43.514 5318 5318 D GeckoToolbar: onTabChanged: PAGE_SHOW 11-09 18:24:43.514 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: PAGE_SHOW 11-09 18:24:43.568 5318 5318 I GeckoTabs: zerdatime 277279890 - page load stop 11-09 18:24:43.568 5318 5318 D GeckoToolbar: onTabChanged: STOP 11-09 18:24:43.571 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: STOP 11-09 18:24:43.626 5318 5451 D GeckoSuggestedSites: No cached sites, refreshing. 11-09 18:24:43.626 5318 5451 D GeckoSuggestedSites: Refreshing suggested sites from file 11-09 18:24:43.633 5318 5451 D GeckoSuggestedSites: Loading blacklisted suggested sites from SharedPreferences. 11-09 18:24:43.633 5318 5451 D GeckoSuggestedSites: Number of suggested sites: 8 11-09 18:24:43.648 5318 5318 D GeckoToolbar: onTabChanged: FAVICON 11-09 18:24:43.648 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: FAVICON 11-09 18:24:45.002 5318 5318 D GeckoToolbar: onTabChanged: THUMBNAIL 11-09 18:24:45.003 5318 5318 D GeckoBrowserApp: BrowserApp.onTabChanged: 0: THUMBNAIL

Capturing a profile would helpful, but while I'm fairly certain that it's possible to wrangle a profile even out of Fennec, I don't know offhand how to do so.

I was afraid you'd say that ;) I don't think I can profile during the Fennec's start up phase, as soon as I kill Fennec the profiler on my laptop resets. Anyway this might only be an issue with ABP, whether it's in Fennec or ABP I don't know. Will do some more digging.

I think I see what's going on. Maybe? I filed bug 1506471. Without add ons at this stage

Ioana can your team take a look at whether this is true on newer phones? Or is it just for the snapdragon older versions?

Hi Liz I am fairly sure this isn't a chipset issue, at least bug 1506471 (which I think is the key issue here, this current bug might be redundant) isn't. I see bug 1506471 on my Sony Xperia X Compact (Snapdragon 625) and Samsung Galaxy S7 (Exynos 8890) which are both fairly recent. If it affects recent Snapdragon and Exynos it's most likely not a chipset issue? I think the least effort for Mozilla might be to fix bug 1507253 which blocks bug 1506471 then I could take some start up profiles and things would hopefully be a lot clearer, particularly around that very slow reflow event which looks fishy to me.

Marking this P1 until we know more.

Resetting whiteboard from [qf:p2:responsiveness] to [qf] because the GV team thinks this startup performance problem deserves a higher priority investigation.

Florian - can someone look at the two bugs about improving startup profiling on android in comment 15?