1719447 - Intermittent browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected read on C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\pkcs11.txt before first paint -

Status: RESOLVED FIXED

(Firefox :: General, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=344550932&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/A0jqa877T_qv4TIuK86-Rg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-07-07T10:00:16.351Z] 10:00:16     INFO - TEST-START | browser/base/content/test/performance/browser_startup_mainthreadio.js
[task 2021-07-07T10:00:16.419Z] 10:00:16     INFO - TEST-INFO | started process screenshot
[task 2021-07-07T10:00:16.481Z] 10:00:16     INFO - TEST-INFO | screenshot: exit 0
[task 2021-07-07T10:00:16.482Z] 10:00:16     INFO - <snipped 14 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2021-07-07T10:00:16.482Z] 10:00:16     INFO - Buffered messages logged at 10:00:16
[task 2021-07-07T10:00:16.483Z] 10:00:16     INFO - (PoisonIOInterposer) write - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\compatibility.ini
[task 2021-07-07T10:00:16.483Z] 10:00:16     INFO - (PoisonIOInterposer) write - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\compatibility.ini
[task 2021-07-07T10:00:16.484Z] 10:00:16     INFO - (PoisonIOInterposer) write - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\compatibility.ini
[task 2021-07-07T10:00:16.484Z] 10:00:16     INFO - (PoisonIOInterposer) write - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\compatibility.ini
[task 2021-07-07T10:00:16.485Z] 10:00:16     INFO - (PoisonIOInterposer) write - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\compatibility.ini
[task 2021-07-07T10:00:16.485Z] 10:00:16     INFO - (PoisonIOInterposer) read - Z:\task_1625651703\build\application\firefox\defaults\pref\channel-prefs.js
[task 2021-07-07T10:00:16.486Z] 10:00:16     INFO - (PoisonIOInterposer) stat - Z:\task_1625651703\build\application\firefox\browser\defaults\preferences
<...>
[task 2021-07-07T10:00:16.536Z] 10:00:16     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on Z:\task_1625651703\build\application\firefox\browser\features\[e-mail] allowed 2 more times before opening first browser window - 
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO - known main thread IO paths during before first paint:
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   C:\Users\task_1625651703\AppData\Local\Mozilla\updates\DFD50D6AA2F2A42F - condition: true, stat: 1, listedPath: OldUpdRootD:
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   C:\ProgramData\Mozilla\updates\DFD50D6AA2F2A42F\updates\0\update.status - condition: true, stat: 1, listedPath: UpdRootD:updates/0/update.status
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   Z:\task_1625651703\build\application\firefox\distribution\distribution.ini - condition: true, stat: 1, listedPath: XREAppDist:distribution.ini
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   *Fonts\StaticCache.dat - condition: true, ignoreIfUnused: true, read: 1, listedPath: *Fonts/StaticCache.dat
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   C:\Windows\system32\spool\drivers\color\* - condition: true, read: 1, listedPath: SysD:spool/drivers/color/*
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   Z:\task_1625651703\AppData\Roaming\Mozilla\Firefox - ignoreIfUnused: true, condition: true, stat: 1, listedPath: UAppData:
[task 2021-07-07T10:00:16.538Z] 10:00:16     INFO -   C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\d3d11layers.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:d3d11layers.guard
[task 2021-07-07T10:00:16.539Z] 10:00:16     INFO -   C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\glcontext.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:glcontext.guard
[task 2021-07-07T10:00:16.539Z] 10:00:16     INFO -   C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\wmfvpxvideo.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:wmfvpxvideo.guard
[task 2021-07-07T10:00:16.539Z] 10:00:16     INFO - (PoisonIOInterposer) stat - C:\Users\task_1625651703\AppData\Local\Mozilla\updates\DFD50D6AA2F2A42F
[task 2021-07-07T10:00:16.540Z] 10:00:16     INFO - (PoisonIOInterposer) stat - C:\ProgramData\Mozilla\updates\DFD50D6AA2F2A42F\updates\0\update.status
[task 2021-07-07T10:00:16.540Z] 10:00:16     INFO - (PoisonIOInterposer) stat - Z:\task_1625651703\build\application\firefox\distribution\distribution.ini
[task 2021-07-07T10:00:16.540Z] 10:00:16     INFO - (PoisonIOInterposer) read - C:\Windows\system32\spool\drivers\color\sRGB Color Space Profile.icm
[task 2021-07-07T10:00:16.541Z] 10:00:16     INFO - Buffered messages finished
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected read on C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\pkcs11.txt before first paint - 
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO - Stack trace:
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   nsNSSComponent::InitializeNSS
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   GetServiceByContractID @mozilla.org/psm;1
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   fetchLatestChanges (resource://services-settings/Utils.jsm:150:26)
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   sync (resource://services-settings/RemoteSettingsClient.jsm:465:12)
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   InterpretGeneratorResume (self-hosted:1482:33)
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   AsyncFunctionNext (self-hosted:692:26)
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   promise callback
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   EventDispatcher::Dispatch message
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   EventDispatcher::Dispatch
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   Task Worker debuggee queue
[task 2021-07-07T10:00:16.542Z] 10:00:16     INFO -   XREMain::XRE_main
[task 2021-07-07T10:00:16.543Z] 10:00:16     INFO - (PoisonIOInterposer) read - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\pkcs11.txt
[task 2021-07-07T10:00:16.543Z] 10:00:16     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-07T10:00:16.544Z] 10:00:16     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected read on C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\pkcs11.txt before first paint - 
[task 2021-07-07T10:00:16.544Z] 10:00:16     INFO - Stack trace:
[task 2021-07-07T10:00:16.544Z] 10:00:16     INFO -   nsNSSComponent::InitializeNSS
[task 2021-07-07T10:00:16.544Z] 10:00:16     INFO -   GetServiceByContractID @mozilla.org/psm;1
[task 2021-07-07T10:00:16.544Z] 10:00:16     INFO -   fetchLatestChanges (resource://services-settings/Utils.jsm:150:26)
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   sync (resource://services-settings/RemoteSettingsClient.jsm:465:12)
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   InterpretGeneratorResume (self-hosted:1482:33)
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   AsyncFunctionNext (self-hosted:692:26)
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   promise callback
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   EventDispatcher::Dispatch message
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   EventDispatcher::Dispatch
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   Task Worker debuggee queue
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO -   XREMain::XRE_main
[task 2021-07-07T10:00:16.545Z] 10:00:16     INFO - (PoisonIOInterposer) read - C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\pkcs11.txt
[task 2021-07-07T10:00:16.546Z] 10:00:16     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-07T10:00:16.547Z] 10:00:16     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected stat on C:\Users\task_1625651703\AppData\Local\Temp\tmpnp_fkke1.mozrunner\cert9.db before first paint - 
[task 2021-07-07T10:00:16.547Z] 10:00:16     INFO - Stack trace:
[task 2021-07-07T10:00:16.547Z] 10:00:16     INFO -   nsNSSComponent::InitializeNSS
[task 2021-07-07T10:00:16.547Z] 10:00:16     INFO -   GetServiceByContractID @mozilla.org/psm;1
<...>

Comment 1

[Intermittent Failures Robot]

20 failures in 510 pushes (0.039 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 19
• mozilla-central: 1

Platform and build breakdown:

• windows10-64-qr: 17
  • opt: 17
• windows10-64-shippable-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-07&endday=2021-07-07&tree=all

Comment 2

[Intermittent Failures Robot]

21 failures in 444 pushes (0.047 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 11
• mozilla-central: 10

Platform and build breakdown:

• windows7-32-qr: 3
  • opt: 3
• windows10-64-qr: 7
  • opt: 7
• windows10-64-shippable-qr: 7
  • opt: 7
• windows10-64-ccov-qr: 3
  • opt: 3
• windows7-32-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-08&endday=2021-07-08&tree=all

Comment 3

[Intermittent Failures Robot]

17 failures in 456 pushes (0.037 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• mozilla-central: 3

Platform and build breakdown:

• windows10-64-shippable-qr: 5
  • opt: 5
• windows7-32-qr: 2
  • opt: 2
• windows10-64-qr: 9
  • opt: 9
• windows10-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-09&endday=2021-07-09&tree=all

Comment 4

[Intermittent Failures Robot]

24 failures in 44 pushes (0.545 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 14
• autoland: 10

Platform and build breakdown:

• windows10-64-shippable-qr: 6
  • opt: 6
• windows7-32-shippable-qr: 1
  • opt: 1
• windows10-64-ccov-qr: 9
  • opt: 9
• windows10-64-qr: 4
  • opt: 4
• windows7-32-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-10&endday=2021-07-10&tree=all

Comment 5

[Andreea Pavel [:apavel]]

There are 88 total failures in the last 7 days on

• windows10-64-ccov-qr opt
• windows10-64-qr opt
• windows7-32-qr opt
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=344904868&repo=autoland&lineNumber=2881

[task 2021-07-11T19:37:12.724Z] 19:37:12 INFO - TEST-START | browser/base/content/test/performance/browser_startup_mainthreadio.js
[task 2021-07-11T19:37:12.795Z] 19:37:12 INFO - TEST-INFO | started process screenshot
[task 2021-07-11T19:37:12.852Z] 19:37:12 INFO - TEST-INFO | screenshot: exit 0
[task 2021-07-11T19:37:12.852Z] 19:37:12 INFO - <snipped 14 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2021-07-11T19:37:12.853Z] 19:37:12 INFO - Buffered messages logged at 19:37:12
[task 2021-07-11T19:37:12.854Z] 19:37:12 INFO - (PoisonIOInterposer) write - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\compatibility.ini
[task 2021-07-11T19:37:12.854Z] 19:37:12 INFO - (PoisonIOInterposer) write - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\compatibility.ini
[task 2021-07-11T19:37:12.855Z] 19:37:12 INFO - (PoisonIOInterposer) write - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\compatibility.ini
[task 2021-07-11T19:37:12.855Z] 19:37:12 INFO - (PoisonIOInterposer) write - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\compatibility.ini
[task 2021-07-11T19:37:12.856Z] 19:37:12 INFO - (PoisonIOInterposer) write - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\compatibility.ini
[task 2021-07-11T19:37:12.856Z] 19:37:12 INFO - (PoisonIOInterposer) read - Z:\task_1626029820\build\application\firefox\defaults\pref\channel-prefs.js
[task 2021-07-11T19:37:12.857Z] 19:37:12 INFO - (PoisonIOInterposer) stat - Z:\task_1626029820\build\application\firefox\browser\defaults\preferences
[task 2021-07-11T19:37:12.857Z] 19:37:12 INFO - (PoisonIOInterposer) read - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\prefs.js
[task 2021-07-11T19:37:12.858Z] 19:37:12 INFO - (PoisonIOInterposer) read - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\user.js

[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - known main thread IO paths during before first paint:
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - C:\Users\task_1626029820\AppData\Local\Mozilla\updates\68E85287C2C56A63 - condition: true, stat: 1, listedPath: OldUpdRootD:
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - C:\ProgramData\Mozilla\updates\68E85287C2C56A63\updates\0\update.status - condition: true, stat: 1, listedPath: UpdRootD:updates/0/update.status
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - Z:\task_1626029820\build\application\firefox\distribution\distribution.ini - condition: true, stat: 1, listedPath: XREAppDist:distribution.ini
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - *Fonts\StaticCache.dat - condition: true, ignoreIfUnused: true, read: 1, listedPath: Fonts/StaticCache.dat
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - C:\Windows\system32\spool\drivers\color* - condition: true, read: 1, listedPath: SysD:spool/drivers/color/
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - Z:\task_1626029820\AppData\Roaming\Mozilla\Firefox - ignoreIfUnused: true, condition: true, stat: 1, listedPath: UAppData:
[task 2021-07-11T19:37:12.914Z] 19:37:12 INFO - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\d3d11layers.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:d3d11layers.guard
[task 2021-07-11T19:37:12.915Z] 19:37:12 INFO - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\glcontext.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:glcontext.guard
[task 2021-07-11T19:37:12.915Z] 19:37:12 INFO - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\wmfvpxvideo.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:wmfvpxvideo.guard
[task 2021-07-11T19:37:12.915Z] 19:37:12 INFO - (PoisonIOInterposer) stat - C:\Users\task_1626029820\AppData\Local\Mozilla\updates\68E85287C2C56A63
[task 2021-07-11T19:37:12.916Z] 19:37:12 INFO - (PoisonIOInterposer) stat - C:\ProgramData\Mozilla\updates\68E85287C2C56A63\updates\0\update.status
[task 2021-07-11T19:37:12.916Z] 19:37:12 INFO - (PoisonIOInterposer) stat - Z:\task_1626029820\build\application\firefox\distribution\distribution.ini
[task 2021-07-11T19:37:12.916Z] 19:37:12 INFO - (PoisonIOInterposer) read - C:\Windows\system32\spool\drivers\color\sRGB Color Space Profile.icm
[task 2021-07-11T19:37:12.917Z] 19:37:12 INFO - Buffered messages finished
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected read on C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\pkcs11.txt before first paint -
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - Stack trace:
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - nsNSSComponent::InitializeNSS
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - GetServiceByContractID @mozilla.org/psm;1
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - fetchLatestChanges (resource://services-settings/Utils.jsm:198:26)
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - sync (resource://services-settings/RemoteSettingsClient.jsm:491:12)
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - InterpretGeneratorResume (self-hosted:1482:33)
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - AsyncFunctionNext (self-hosted:692:26)
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - promise callback
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - EventDispatcher::Dispatch message
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - EventDispatcher::Dispatch
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - Task Worker debuggee queue
[task 2021-07-11T19:37:12.918Z] 19:37:12 INFO - XREMain::XRE_main
[task 2021-07-11T19:37:12.919Z] 19:37:12 INFO - (PoisonIOInterposer) read - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\pkcs11.txt
[task 2021-07-11T19:37:12.919Z] 19:37:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-11T19:37:12.920Z] 19:37:12 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected read on C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\pkcs11.txt before first paint -
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - Stack trace:
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - nsNSSComponent::InitializeNSS
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - GetServiceByContractID @mozilla.org/psm;1
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - fetchLatestChanges (resource://services-settings/Utils.jsm:198:26)
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - sync (resource://services-settings/RemoteSettingsClient.jsm:491:12)
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - InterpretGeneratorResume (self-hosted:1482:33)
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - AsyncFunctionNext (self-hosted:692:26)
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - promise callback
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - EventDispatcher::Dispatch message
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - EventDispatcher::Dispatch
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - Task Worker debuggee queue
[task 2021-07-11T19:37:12.921Z] 19:37:12 INFO - XREMain::XRE_main
[task 2021-07-11T19:37:12.922Z] 19:37:12 INFO - (PoisonIOInterposer) read - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\pkcs11.txt
[task 2021-07-11T19:37:12.922Z] 19:37:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected stat on C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\cert9.db before first paint -
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - Stack trace:
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - nsNSSComponent::InitializeNSS
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - GetServiceByContractID @mozilla.org/psm;1
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - fetchLatestChanges (resource://services-settings/Utils.jsm:198:26)
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - sync (resource://services-settings/RemoteSettingsClient.jsm:491:12)
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - InterpretGeneratorResume (self-hosted:1482:33)
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - AsyncFunctionNext (self-hosted:692:26)
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - promise callback
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - EventDispatcher::Dispatch message
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - EventDispatcher::Dispatch
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - Task Worker debuggee queue
[task 2021-07-11T19:37:12.924Z] 19:37:12 INFO - XREMain::XRE_main
[task 2021-07-11T19:37:12.925Z] 19:37:12 INFO - (PoisonIOInterposer) stat - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\cert9.db
[task 2021-07-11T19:37:12.925Z] 19:37:12 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-07-11T19:37:12.926Z] 19:37:12 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected stat on C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\cert9.db before first paint -
[task 2021-07-11T19:37:12.926Z] 19:37:12 INFO - Stack trace:
[task 2021-07-11T19:37:12.926Z] 19:37:12 INFO - nsNSSComponent::InitializeNSS
[task 2021-07-11T19:37:12.926Z] 19:37:12 INFO - GetServiceByContractID @mozilla.org/psm;1
[task 2021-07-11T19:37:12.926Z] 19:37:12 INFO - fetchLatestChanges (resource://services-settings/Utils.jsm:198:26)
[task 2021-07-11T19:37:12.926Z] 19:37:12 INFO - sync (resource://services-settings/RemoteSettingsClient.jsm:491:12)
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - InterpretGeneratorResume (self-hosted:1482:33)
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - AsyncFunctionNext (self-hosted:692:26)
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - promise callback
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - EventDispatcher::Dispatch message
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - EventDispatcher::Dispatch
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - Task Worker debuggee queue
[task 2021-07-11T19:37:12.927Z] 19:37:12 INFO - XREMain::XRE_main
[task 2021-07-11T19:37:12.928Z] 19:37:12 INFO - (PoisonIOInterposer) stat - C:\Users\task_1626029820\AppData\Local\Temp\tmpcdqyw54t.mozrunner\cert9.db
[task 2021-07-11T19:37:12.928Z] 19:37:12 INFO - Not taking screenshot here: see the one that was previously logged

Tim, please take a look.

Comment 6

[Intermittent Failures Robot]

92 failures in 2387 pushes (0.039 failures/push) were associated with this bug in the last 7 days.

This is the #5 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 60
• mozilla-central: 32

Platform and build breakdown:

• windows10-64-qr: 41
  • opt: 41
• windows10-64-shippable-qr: 24
  • opt: 24
• windows7-32-qr: 10
  • opt: 10
• windows10-64-ccov-qr: 15
  • opt: 15
• windows7-32-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-05&endday=2021-07-11&tree=all

Comment 7

[Intermittent Failures Robot]

22 failures in 405 pushes (0.054 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 18
• mozilla-central: 4

Platform and build breakdown:

• windows10-64-qr: 11
  • opt: 11
• windows10-64-ccov-qr: 2
  • opt: 2
• windows10-64-shippable-qr: 5
  • opt: 5
• windows7-32-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-12&endday=2021-07-12&tree=all

Comment 8

[Tim Huang[:timhuang]]

Attachment: Bug 1719447 - Delay the initialization of URLQueryStringListService in the parent process. r?pbz

Comment 9

[Intermittent Failures Robot]

27 failures in 470 pushes (0.057 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 23
• mozilla-central: 4

Platform and build breakdown:

• windows10-64-qr: 14
  • opt: 14
• windows7-32-qr: 3
  • opt: 3
• windows10-64-shippable-qr: 6
  • opt: 6
• windows10-64-ccov-qr: 3
  • opt: 3
• windows10-32-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-13&endday=2021-07-13&tree=all

Comment 10

[Tim Huang[:timhuang]]

Attachment: Bug 1719447 - Add an init function to the URLQueryStrippingListService. r?pbz

Depends on D119733

Comment 11

[Intermittent Failures Robot]

18 failures in 352 pushes (0.051 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 15
• mozilla-central: 3

Platform and build breakdown:

• windows10-64-qr: 10
  • opt: 10
• windows7-32-qr: 2
  • opt: 2
• windows10-64-shippable-qr: 4
  • opt: 4
• windows10-64-ccov-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-14&endday=2021-07-14&tree=all

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/015c550a3b78
Delay the initialization of URLQueryStringListService in the parent process. r=pbz
https://hg.mozilla.org/integration/autoland/rev/aea6d142059e
Add an init function to the URLQueryStrippingListService. r=pbz

Comment 13

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/015c550a3b78
https://hg.mozilla.org/mozilla-central/rev/aea6d142059e

Comment 14

[Intermittent Failures Robot]

68 failures in 2224 pushes (0.031 failures/push) were associated with this bug in the last 7 days.

This is the #11 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 57
• mozilla-central: 11

Platform and build breakdown:

• windows10-64-qr: 36
  • opt: 36
• windows10-64-ccov-qr: 7
  • opt: 7
• windows10-64-shippable-qr: 15
  • opt: 15
• windows7-32-qr: 9
  • opt: 9
• windows10-32-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1719447&startday=2021-07-12&endday=2021-07-18&tree=all

Comment 15

[BugBot [:suhaib / :marco]]

The patch landed in nightly and beta is affected.
:timhuang, is this bug important enough to require an uplift?
If not please set status_beta to wontfix.

For more information, please visit auto_nag documentation.

Comment 16

[Tim Huang[:timhuang]]

I don't think it's very important given that this doesn't affect user's behavior.