1913040 - Perma Windows Shippable browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected stat on C:\Users\task_172358606955883\AppData\Local\Temp\firefox-nightly\parent.lock before profile selection -

Status: RESOLVED FIXED

(Toolkit :: Performance Monitoring, defect)

Description

[Treeherder Bug Filer]

Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=470289712&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TBHVvJwuSMWB-H6NYvyzzA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2024-08-13T22:51:50.406Z] 22:51:50     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | The IO interposer should be enabled in builds that are not RELEASE_OR_BETA - 
[task 2024-08-13T22:51:50.410Z] 22:51:50     INFO - known main thread IO paths during before profile selection:
[task 2024-08-13T22:51:50.410Z] 22:51:50     INFO -   D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\Crash Reports\InstallTime20* - condition: true, stat: 1, read: 1, write: 2, close: 1, listedPath: UAppData:Crash Reports/InstallTime20*
[task 2024-08-13T22:51:50.410Z] 22:51:50     INFO -   D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\Crash Reports\LastCrash - condition: true, stat: 1, read: 1, listedPath: UAppData:Crash Reports/LastCrash
[task 2024-08-13T22:51:50.410Z] 22:51:50     INFO -   D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\profiles.ini - condition: true, ignoreIfUnused: true, read: 1, stat: 1, listedPath: UAppData:profiles.ini
[task 2024-08-13T22:51:50.410Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner - condition: true, stat: 1, listedPath: ProfD:
[task 2024-08-13T22:51:50.411Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini - write: 18, close: 1, listedPath: ProfLD:compatibility.ini
[task 2024-08-13T22:51:50.411Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\parent.lock - condition: true, stat: 1, listedPath: ProfD:parent.lock
[task 2024-08-13T22:51:50.411Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\minidumps - condition: true, stat: 1, listedPath: ProfD:minidumps
[task 2024-08-13T22:51:50.411Z] 22:51:50     INFO -   D:\task_172358606955883\build\application\firefox\browser\defaults\preferences - condition: true, stat: 1, listedPath: XCurProcD:defaults/preferences
[task 2024-08-13T22:51:50.411Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache-child-current.bin - condition: true, stat: 1, listedPath: ProfLDS:startupCache/scriptCache-child-current.bin
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache-child.bin - condition: true, stat: 1, listedPath: ProfLDS:startupCache/scriptCache-child.bin
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache-current.bin - condition: true, stat: 1, listedPath: ProfLDS:startupCache/scriptCache-current.bin
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache.bin - condition: true, stat: 1, listedPath: ProfLDS:startupCache/scriptCache.bin
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   D:\task_172358606955883\build\application\firefox\defaults\pref\channel-prefs.js - condition: true, stat: 1, read: 1, close: 1, listedPath: PrfDef:channel-prefs.js
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\prefs.js - stat: 1, read: 1, close: 1, listedPath: PrefD:prefs.js
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\user.js - stat: 1, read: 1, close: 1, listedPath: PrefD:user.js
[task 2024-08-13T22:51:50.412Z] 22:51:50     INFO -   C:\Users\task_172358606955883\AppData\Local\Temp\firefox-default\parent.lock - condition: true, stat: 1, listedPath: TmpD:firefox-default/parent.lock
[task 2024-08-13T22:51:50.413Z] 22:51:50     INFO - (PoisonIOInterposer) stat - D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\Crash Reports\InstallTime20240813202804
[task 2024-08-13T22:51:50.413Z] 22:51:50     INFO - (PoisonIOInterposer) read - D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\Crash Reports\InstallTime20240813202804
[task 2024-08-13T22:51:50.414Z] 22:51:50     INFO - (PoisonIOInterposer) stat - D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\Crash Reports\LastCrash
[task 2024-08-13T22:51:50.414Z] 22:51:50     INFO - Buffered messages finished
[task 2024-08-13T22:51:50.415Z] 22:51:50     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected stat on C:\Users\task_172358606955883\AppData\Local\Temp\firefox-nightly\parent.lock before profile selection - 
[task 2024-08-13T22:51:50.416Z] 22:51:50     INFO - Stack trace:
[task 2024-08-13T22:51:50.416Z] 22:51:50     INFO -   0x7ffaaf70ad75
[task 2024-08-13T22:51:50.416Z] 22:51:50     INFO -   nsLocalFile::ResolveAndStat
[task 2024-08-13T22:51:50.416Z] 22:51:50     INFO -   XREMain::XRE_main
[task 2024-08-13T22:51:50.416Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\firefox-nightly\parent.lock
[task 2024-08-13T22:51:50.417Z] 22:51:50     INFO - (PoisonIOInterposer) stat - D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\profiles.ini
[task 2024-08-13T22:51:50.418Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner
[task 2024-08-13T22:51:50.418Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\parent.lock
[task 2024-08-13T22:51:50.419Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\minidumps
[task 2024-08-13T22:51:50.420Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.421Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.421Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.422Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.423Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.424Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.424Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.425Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.426Z] 22:51:50     INFO - (PoisonIOInterposer) write - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\compatibility.ini
[task 2024-08-13T22:51:50.426Z] 22:51:50     INFO - (PoisonIOInterposer) read - D:\task_172358606955883\build\application\firefox\defaults\pref\channel-prefs.js
[task 2024-08-13T22:51:50.427Z] 22:51:50     INFO - (PoisonIOInterposer) stat - D:\task_172358606955883\build\application\firefox\browser\defaults\preferences
[task 2024-08-13T22:51:50.428Z] 22:51:50     INFO - (PoisonIOInterposer) read - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\prefs.js
[task 2024-08-13T22:51:50.429Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\prefs.js
[task 2024-08-13T22:51:50.429Z] 22:51:50     INFO - (PoisonIOInterposer) read - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\user.js
[task 2024-08-13T22:51:50.430Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache-child.bin
[task 2024-08-13T22:51:50.431Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache-child-current.bin
[task 2024-08-13T22:51:50.432Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache.bin
[task 2024-08-13T22:51:50.432Z] 22:51:50     INFO - (PoisonIOInterposer) stat - C:\Users\task_172358606955883\AppData\Local\Temp\tmplqvgt6g0.mozrunner\startupCache\scriptCache-current.bin
[task 2024-08-13T22:51:50.433Z] 22:51:50     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | stat on D:\task_172358606955883\AppData\Roaming\Mozilla\Firefox\Crash Reports\InstallTime20* as many times as expected before profile selection - 0 >= 0 - 

Comment 1

[Serban Stanca [:SerbanS]]

This popped up on mozilla-central after the latest merge from autoland to central, as it can be seen here.

Hi Dave! Could you please take a look at this? It looks to have started with Bug 1906260.

Thank you!

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1906260

Comment 3

[Dave Townsend [:mossop]]

Attachment: Bug 1913040: Update main I/O stat to use the right filename on different update channels.

Comment 4

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/9566e1f8990c
Update main I/O stat to use the right filename on different update channels. r=florian

Comment 5

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/9566e1f8990c

Comment 6

[Intermittent Failures Robot]

5 failures in 2154 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5

Platform and build breakdown:

• windows11-64-2009-shippable-qr: 5
  • opt: 5

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