1697972 - Frequent Assertion failure: mShutdownBlocker, at /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerManager.cpp:461 - WindowsUIUtils::UpdateTabletModeState creates hidden window racing xpcshell shutdown

Status: RESOLVED INCOMPLETE

(Core :: Widget: Win32, defect, P2)

Description

[Treeherder Bug Filer]

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

---

[task 2021-03-12T01:24:44.410Z] 01:24:44     INFO -  PID 4672 | Assertion failure: mShutdownBlocker, at /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerManager.cpp:461
[task 2021-03-12T01:24:44.410Z] 01:24:44     INFO -  PID 4672 | [Child 13632, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4377
[task 2021-03-12T01:24:44.410Z] 01:24:44     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #01: mozilla::dom::Document::DispatchContentLoadedEvents() [dom/base/Document.cpp:7550]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #02: mozilla::detail::RunnableMethodImpl<mozilla::dom::Document *,void (mozilla::dom::Document::*)(),1,mozilla::RunnableKind::Standard>::Run() [xpcom/threads/nsThreadUtils.h:1204]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #03: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:473]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #04: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:760]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #05: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:611]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #06: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:395]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #07: mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:133:7'>::Run() [xpcom/threads/nsThreadUtils.h:535]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #08: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1162]
[task 2021-03-12T01:24:53.985Z] 01:24:53     INFO -  PID 4672 | #09: NS_ProcessPendingEvents(nsIThread*, unsigned int) [xpcom/threads/nsThreadUtils.cpp:496]
[task 2021-03-12T01:24:53.986Z] 01:24:53     INFO -  PID 4672 | #10: mozilla::ShutdownXPCOM(nsIServiceManager*) [xpcom/build/XPCOMInit.cpp:620]
[task 2021-03-12T01:24:53.986Z] 01:24:53     INFO -  PID 4672 | #11: XRE_XPCShellMain(int, char**, char**, XREShellData const*) [js/xpconnect/src/XPCShellImpl.cpp:1384]
[task 2021-03-12T01:24:54.029Z] 01:24:54     INFO -  PID 4672 | #12: NS_internal_main(int, char**, char**) [js/xpconnect/shell/xpcshell.cpp:78]
[task 2021-03-12T01:24:54.029Z] 01:24:54     INFO -  PID 4672 | #13: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2021-03-12T01:24:54.029Z] 01:24:54     INFO -  PID 4672 | #14: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/js/xpconnect/shell/f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2021-03-12T01:24:54.034Z] 01:24:54     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\\Windows\\System32\\KERNEL32.DLL`
[task 2021-03-12T01:24:54.034Z] 01:24:54     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-03-12T01:24:54.034Z] 01:24:54     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-03-12T01:24:54.034Z] 01:24:54     INFO -  PID 4672 | #15: BaseThreadInitThunk [C:\\Windows\\System32\\KERNEL32.DLL + 0x13034]
[task 2021-03-12T01:24:54.036Z] 01:24:54     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\\Windows\\SYSTEM32\\ntdll.dll`
[task 2021-03-12T01:24:54.036Z] 01:24:54     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-03-12T01:24:54.036Z] 01:24:54     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-03-12T01:24:54.036Z] 01:24:54     INFO -  PID 4672 | #16: RtlUserThreadStart [C:\\Windows\\SYSTEM32\\ntdll.dll + 0x71461]
[task 2021-03-12T01:24:54.036Z] 01:24:54     INFO -  <<<<<<<```

Comment 1

[Natalia Csoregi [:nataliaCs]]

R/b: https://treeherder.mozilla.org/jobs?repo=autoland&fromchange=2bfef9531b91b0a24e259abd9021623056800f43&searchStr=windows%2C10%2Cx64%2Cdebug%2Cxpcshell%2Ctests%2Ctest-windows10-64%2Fdebug-xpcshell-e10s%2Cx1&tochange=4da72bfa14c0739a0cad9ac4dd48316ffcc1f1a1

Comment 2

[Intermittent Failures Robot]

19 failures in 838 pushes (0.023 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 19

Platform and build breakdown:

• windows10-64: 19
  • debug: 19

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

Comment 3

[Alexandru Michis [:malexandru]]

Closing this as fixed since Bug 1689884 was backed out (backout link).

Comment 4

[Alexandru Michis [:malexandru]]

This was not fixed by backing out Bug 1689884, the assertion failures still appear on the backout push.

When backfilling in search for the culprit, the failures stopped at Bug 1689884 (see backfills).

Andrew, could you please take a look? What could cause this behavior?

Comment 5

[Andrew Sutherland [:asuth] (he/him)]

So, the ServiceWorkerManager is asserting because mozilla::dom::Document::DispatchContentLoadedEvents is trying to initialize it after "profile-change-teardown". It's confusing why the various tests that are failing would be loading documents at this point. But it's likely the explanation for why this now seems to be happening all the time is that some kind of timing change happened.

Comment 6

[Andrew Sutherland [:asuth] (he/him)]

We discussed/investigated this somewhat in #DOM on matrix here (Thanks very much, :nika!) and the next investigation step is to run the test with MOZ_LOG=DocLoader:5 in the environment or equivalent, like setting the preference logging.DocLoader to (numeric value I think) 5. I tried to follow the steps at https://wiki.mozilla.org/ReleaseEngineering/TryServer#Re-running_tasks_with_custom_parameters_from_Treeherder using custom actions but it seems like there's only "retrigger" available, not "retrigger-custom".

Comment 7

[Intermittent Failures Robot]

44 failures in 4622 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #28 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: 37
• mozilla-central: 6
• comm-central: 1

Platform and build breakdown:

• windows10-64: 43
  • debug: 43
• linux1804-64-qr: 1
  • opt: 1

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

Comment 8

[Intermittent Failures Robot]

18 failures in 943 pushes (0.019 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 8
• comm-central: 5
• mozilla-central: 3
• try: 2

Platform and build breakdown:

• windows10-64: 18
  • debug: 18

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

Comment 9

[Intermittent Failures Robot]

25 failures in 797 pushes (0.031 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 14
• mozilla-central: 6
• comm-central: 5

Platform and build breakdown:

• windows10-64: 23
  • debug: 23
• windows10-64-qr: 2
  • debug: 2

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

Comment 10

[Sandor Molnar[:smolnar]]

There have been 100 total failures in the last 7 days:

• windows10 debug

Recent failure log

Comment 11

[Andreea Pavel [:apavel]]

Andrew are there any other updates here/

Comment 12

[Intermittent Failures Robot]

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

This is the #8 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: 54
• comm-central: 16
• mozilla-central: 19
• try: 3

Platform and build breakdown:

• windows10-64: 89
  • debug: 89
• windows10-64-qr: 3
  • debug: 3

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

Comment 13

[Andrew Sutherland [:asuth] (he/him)]

Something is creating a hidden window in an xpcshell test log error line:
[task 2021-03-22T23:31:20.770Z] 23:31:20 INFO - PID 12532 | [Parent 12532: Main Thread]: D/DocLoader DocLoader:2031f1bf000: OnStartRequest[2031f50be40](documentchannel:resource://gre-resources/hiddenWindow.html) mIsLoadingDocument=false, 1 active URLs

I'll modify CreateHiddenWindow to crash under xpcshell and hopefully that points at a useful call stack.

Comment 14

[Andrew Sutherland [:asuth] (he/him)]

The xpcshell subprocess spawning is causing the hidden window to come into existence on windows because it wants to check if it's in tablet mode log from here:

[task 2021-03-23T00:13:49.485Z] 00:13:49     INFO -  PID 2128 | #01: WindowsUIUtils::GetInTabletMode(bool*) [widget/windows/WindowsUIUtils.cpp:205]
[task 2021-03-23T00:13:49.486Z] 00:13:49     INFO -  PID 2128 | #02: mozilla::widget::IsTabletDevice() [widget/windows/WinUtils.cpp:1857]
[task 2021-03-23T00:13:49.487Z] 00:13:49     INFO -  PID 2128 | #03: static mozilla::widget::WinUtils::GetPrimaryPointerCapabilities() [widget/windows/WinUtils.cpp:1911]
[task 2021-03-23T00:13:49.487Z] 00:13:49     INFO -  PID 2128 | #04: nsLookAndFeel::NativeGetInt(mozilla::LookAndFeel::IntID, int&) [widget/windows/nsLookAndFeel.cpp:615]
[task 2021-03-23T00:13:49.488Z] 00:13:49     INFO -  PID 2128 | #05: std::_Func_impl_no_alloc<`lambda at /builds/worker/checkouts/gecko/widget/RemoteLookAndFeel.cpp:170:39',void,const mozilla::widget::LookAndFeelTheme &>::_Do_call(mozilla::widget::LookAndFeelTheme const&) [Z:\\task_1616457057\\build\\application\\firefox\\xul.dll + 0x4632fbf]
[task 2021-03-23T00:13:49.488Z] 00:13:49     INFO -  PID 2128 | #06: nsXPLookAndFeel::WithThemeConfiguredForContent(std::function<void (const mozilla::widget::LookAndFeelTheme &)> const&) [widget/nsXPLookAndFeel.h:69]
[task 2021-03-23T00:13:49.489Z] 00:13:49     INFO -  PID 2128 | #07: static mozilla::widget::RemoteLookAndFeel::ExtractData() [widget/RemoteLookAndFeel.cpp:170]
[task 2021-03-23T00:13:49.489Z] 00:13:49     INFO -  PID 2128 | #08: mozilla::dom::ContentParent::InitInternal(mozilla::hal::ProcessPriority) [dom/ipc/ContentParent.cpp:2923]
[task 2021-03-23T00:13:49.490Z] 00:13:49     INFO -  PID 2128 | #09: mozilla::dom::ContentParent::LaunchSubprocessResolve(bool, mozilla::hal::ProcessPriority) [dom/ipc/ContentParent.cpp:2649]
[task 2021-03-23T00:13:49.490Z] 00:13:49     INFO -  PID 2128 | #10: mozilla::dom::ContentParent::WaitForLaunchSync(mozilla::hal::ProcessPriority) [dom/ipc/ContentParent.cpp:1191]
[task 2021-03-23T00:13:49.491Z] 00:13:49     INFO -  PID 2128 | #11: static mozilla::dom::ContentParent::GetNewOrUsedBrowserProcess(nsTSubstring<char> const&, mozilla::dom::BrowsingContextGroup*, mozilla::hal::ProcessPriority, bool) [dom/ipc/ContentParent.cpp:1140]
[task 2021-03-23T00:13:49.491Z] 00:13:49     INFO -  PID 2128 | #12: XRE_SendTestShellCommand(JSContext*, JSString*, JS::Value*) [toolkit/xre/nsEmbedFunctions.cpp:956]
[task 2021-03-23T00:13:49.492Z] 00:13:49     INFO -  PID 2128 | #13: SendCommand(JSContext*, unsigned int, JS::Value*) [js/xpconnect/src/XPCShellImpl.cpp:465]

Comment 15

[Intermittent Failures Robot]

19 failures in 794 pushes (0.024 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: 17
• comm-central: 1
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 19
  • debug: 19

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

Comment 16

[Intermittent Failures Robot]

64 failures in 3967 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #17 most frequent failure this week.

** 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: 41
• comm-central: 8
• mozilla-central: 8
• try: 7

Platform and build breakdown:

• windows10-64: 64
  • debug: 64

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

Comment 17

[Intermittent Failures Robot]

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

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

Repository breakdown:

• mozilla-central: 1
• autoland: 4
• try: 1

Platform and build breakdown:

• windows10-64: 5
  • debug: 5
• windows10-64-qr: 1
  • debug: 1

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

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• try: 2
• mozilla-central: 2

Platform and build breakdown:

• windows10-64: 9
  • debug: 9

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

Comment 19

[Intermittent Failures Robot]

11 failures in 4255 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 3
• comm-central: 1

Platform and build breakdown:

• windows10-64: 8
  • debug: 8
• windows10-64-qr: 3
  • debug: 3

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

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• comm-central: 2
• mozilla-central: 1
• autoland: 2

Platform and build breakdown:

• windows10-64: 5
  • debug: 5

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

Comment 21

[Intermittent Failures Robot]

3 failures in 3706 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1697972&startday=2021-04-26&endday=2021-05-02&tree=all

Comment 22

[Intermittent Failures Robot]

2 failures in 3279 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows10-64: 2
  • debug: 2

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

Comment 23

[Intermittent Failures Robot]

2 failures in 3061 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 2
  • debug: 2

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

Comment 24

[Intermittent Failures Robot]

3 failures in 2908 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform and build breakdown:

• windows10-32: 1
  • debug: 1
• windows10-64: 2
  • debug: 2

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

Comment 25

[Intermittent Failures Robot]

2 failures in 3065 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• comm-central: 1

Platform and build breakdown:

• windows10-32: 1
  • debug: 1
• windows10-64: 1
  • debug: 1

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

Comment 26

[Intermittent Failures Robot]

2 failures in 3502 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 1
  • debug: 1
• windows10-32: 1
  • debug: 1

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

Comment 27

[Intermittent Failures Robot]

14 failures in 2888 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• comm-central: 1
• autoland: 11
• mozilla-central: 2

Platform and build breakdown:

• windows10-64: 7
  • debug: 7
• windows10-64-qr: 2
  • debug: 2
• windows10-32: 5
  • debug: 5

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

Comment 28

[Intermittent Failures Robot]

17 failures in 3115 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 12
• mozilla-central: 5

Platform and build breakdown:

• windows10-32: 2
  • debug: 2
• windows10-32-qr: 9
  • debug: 9
• windows10-64: 5
  • debug: 5
• windows10-64-qr: 1
  • debug: 1

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

Comment 29

[Intermittent Failures Robot]

3 failures in 723 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform and build breakdown:

• windows10-64: 1
  • debug: 1
• windows10-32-qr: 2
  • debug: 2

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

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 8
• autoland: 10

Platform and build breakdown:

• windows10-64: 2
  • debug: 2
• windows10-32-qr: 5
  • debug: 5
• windows10-64-qr: 11
  • debug: 11

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

Comment 31

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 14
• comm-central: 2
• mozilla-central: 5
• mozilla-esr91: 1

Platform and build breakdown:

• windows10-64-qr: 11
  • debug: 11
• windows10-32-qr: 11
  • debug: 11

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

Comment 32

[Jens Stutte [:jstutte]]

(In reply to Andrew Sutherland [:asuth] (he/him) from comment #14)

The xpcshell subprocess spawning is causing the hidden window to come into existence on windows because it wants to check if it's in tablet mode log from here:

I assume, this happens inside WindowsUIUtils::UpdateTabletModeState.

If this is the cause, then probably we should move this to component Widget: Win32 ?

Comment 33

[Andreea Pavel [:apavel]]

There are 23 total failures in the last 7 days on

• windows10-64-qr debug
• windows10-32-qr debug
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=345942550&repo=mozilla-central&lineNumber=6009

[task 2021-07-21T22:24:41.908Z] 22:24:41 INFO - TEST-START | toolkit/components/telemetry/tests/unit/test_ChildScalars.js
[task 2021-07-21T22:24:43.255Z] 22:24:43 WARNING - TEST-UNEXPECTED-FAIL | toolkit/components/telemetry/tests/unit/test_ChildScalars.js | xpcshell return code: 1
[task 2021-07-21T22:24:43.255Z] 22:24:43 INFO - TEST-INFO took 1346ms
[task 2021-07-21T22:24:43.255Z] 22:24:43 INFO - >>>>>>>
[task 2021-07-21T22:24:43.256Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-21T22:24:43.256Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-21T22:24:43.257Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-21T22:24:43.257Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:120
[task 2021-07-21T22:24:43.257Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2948
[task 2021-07-21T22:24:43.257Z] 22:24:43 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-07-21T22:24:43.258Z] 22:24:43 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-07-21T22:24:43.258Z] 22:24:43 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-07-21T22:24:43.258Z] 22:24:43 INFO - running event loop
[task 2021-07-21T22:24:43.258Z] 22:24:43 INFO - toolkit/components/telemetry/tests/unit/test_ChildScalars.js | Starting
[task 2021-07-21T22:24:43.259Z] 22:24:43 INFO - (xpcshell/head.js) | test pending (2)
[task 2021-07-21T22:24:43.259Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-07-21T22:24:43.259Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-07-21T22:24:43.260Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-07-21T22:24:43.260Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: This method is lossy. Use GetCanonicalPath !: file /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileWin.cpp:3352
[task 2021-07-21T22:24:43.260Z] 22:24:43 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2021-07-21T22:24:43.260Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282263 addons.manager DEBUG Application has been upgraded"
[task 2021-07-21T22:24:43.261Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282264 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/XPIProvider.jsm: ["XPIProvider", "XPIInternal"]"
[task 2021-07-21T22:24:43.261Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282273 addons.manager DEBUG Loaded provider scope for resource://gre/modules/addons/GMPProvider.jsm"
[task 2021-07-21T22:24:43.261Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282273 addons.manager DEBUG Starting provider: XPIProvider"
[task 2021-07-21T22:24:43.262Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282273 addons.xpi DEBUG startup"
[task 2021-07-21T22:24:43.262Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282275 addons.xpi INFO SystemAddonLocation directory is missing"
[task 2021-07-21T22:24:43.262Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282290 addons.xpi INFO Removing all system add-on upgrades."
[task 2021-07-21T22:24:43.262Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282291 addons.xpi DEBUG checkForChanges"
[task 2021-07-21T22:24:43.262Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282292 addons.xpi DEBUG Loaded add-on state: ${}"
[task 2021-07-21T22:24:43.263Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282294 addons.xpi DEBUG New add-on [e-mail] in app-system-defaults"
[task 2021-07-21T22:24:43.263Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282295 addons.xpi WARN Can't get modified time of C:\Users\task_1626904070\AppData\Local\Temp\xpc-profile-pqy74jb8\sysfeatures\app0\[e-mail]"
[task 2021-07-21T22:24:43.263Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282296 addons.xpi DEBUG scanForChanges changed: true, state: {}"
[task 2021-07-21T22:24:43.264Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282308 addons.xpi-utils DEBUG Synchronously loading the add-ons database"
[task 2021-07-21T22:24:43.264Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282309 addons.xpi-utils DEBUG Starting async load of XPI database C:\Users\task_1626904070\AppData\Local\Temp\xpc-profile-pqy74jb8\extensions.json"
[task 2021-07-21T22:24:43.264Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282317 addons.xpi-utils DEBUG New add-on [e-mail] installed in app-system-defaults"
[task 2021-07-21T22:24:43.265Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282320 addons.xpi-utils WARN addMetadata: Add-on [e-mail] is invalid: [Exception... "Component returned failure code: 0x80520012 (NS_ERROR_FILE_NOT_FOUND) [nsIFile.isFile]" nsresult: "0x80520012 (NS_ERROR_FILE_NOT_FOUND)" location: "JS frame :: resource://gre/modules/addons/XPIInstall.jsm :: get :: line 232" data: no] Stack trace: get()@resource://gre/modules/addons/XPIInstall.jsm:232
[task 2021-07-21T22:24:43.265Z] 22:24:43 INFO - syncLoadManifest()@resource://gre/modules/addons/XPIInstall.jsm:760
[task 2021-07-21T22:24:43.265Z] 22:24:43 INFO - addMetadata()@resource://gre/modules/addons/XPIDatabase.jsm:2827
[task 2021-07-21T22:24:43.265Z] 22:24:43 INFO - processFileChanges()@resource://gre/modules/addons/XPIDatabase.jsm:3292
[task 2021-07-21T22:24:43.266Z] 22:24:43 INFO - checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3020
[task 2021-07-21T22:24:43.266Z] 22:24:43 INFO - startup()@resource://gre/modules/addons/XPIProvider.jsm:2471
[task 2021-07-21T22:24:43.266Z] 22:24:43 INFO - callProvider()@resource://gre/modules/AddonManager.jsm:230
[task 2021-07-21T22:24:43.266Z] 22:24:43 INFO - _startProvider()@resource://gre/modules/AddonManager.jsm:590
[task 2021-07-21T22:24:43.266Z] 22:24:43 INFO - startup()@resource://gre/modules/AddonManager.jsm:814
[task 2021-07-21T22:24:43.266Z] 22:24:43 INFO - startup()@resource://gre/modules/AddonManager.jsm:3511
[task 2021-07-21T22:24:43.267Z] 22:24:43 INFO - observe()@resource://gre/modules/addonManager.js:81
[task 2021-07-21T22:24:43.267Z] 22:24:43 INFO - promiseStartupManager()@resource://testing-common/AddonTestUtils.jsm:891
[task 2021-07-21T22:24:43.267Z] 22:24:43 INFO - loadAddonManager()@Z:/task_1626904070/build/tests/xpcshell/tests/toolkit/components/telemetry/tests/unit/head.js:295
[task 2021-07-21T22:24:43.267Z] 22:24:43 INFO - _do_main()@Z:\task_1626904070\build\tests\xpcshell\head.js:240
[task 2021-07-21T22:24:43.267Z] 22:24:43 INFO - _execute_test()@Z:\task_1626904070\build\tests\xpcshell\head.js:597
[task 2021-07-21T22:24:43.268Z] 22:24:43 INFO - -e:1"
[task 2021-07-21T22:24:43.268Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282323 addons.xpi-utils WARN Could not uninstall invalid item from locked install location"
[task 2021-07-21T22:24:43.268Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282326 addons.xpi-utils DEBUG Updating add-on states"
[task 2021-07-21T22:24:43.268Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282327 addons.xpi-utils DEBUG Synchronously loading the add-ons database"
[task 2021-07-21T22:24:43.268Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282331 addons.manager DEBUG Registering shutdown blocker for XPIProvider"
[task 2021-07-21T22:24:43.269Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282331 addons.manager DEBUG Provider finished startup: XPIProvider"
[task 2021-07-21T22:24:43.269Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282331 addons.manager DEBUG Completed startup sequence"
[task 2021-07-21T22:24:43.269Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282359 addons.xpi-utils DEBUG XPI Database saved, setting schema version preference to 33"
[task 2021-07-21T22:24:43.269Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282407 Toolkit.Telemetry TRACE TelemetryController::setupTelemetry"
[task 2021-07-21T22:24:43.270Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282407 Toolkit.Telemetry TRACE TelemetryController::registerJsProbes - registering builtin JS probes"
[task 2021-07-21T22:24:43.270Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282407 Toolkit.Telemetry TRACE TelemetryController::registerScalarProbes - registering scalar builtin JS probes"
[task 2021-07-21T22:24:43.270Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282408 Toolkit.Telemetry TRACE TelemetryController::loadProbeDefinitions - no builtin JS probe file ScalarArtifactDefinitions.json"
[task 2021-07-21T22:24:43.270Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282411 Toolkit.Telemetry TRACE TelemetryReportingPolicy::setup"
[task 2021-07-21T22:24:43.271Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282411 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true"
[task 2021-07-21T22:24:43.271Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282411 Toolkit.Telemetry TRACE TelemetrySession::earlyInit"
[task 2021-07-21T22:24:43.271Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282413 Toolkit.Telemetry TRACE UpdatePing::init - enabled: true"
[task 2021-07-21T22:24:43.271Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282413 Toolkit.Telemetry TRACE TelemetryController::registerEventProbes - registering builtin JS Event probes"
[task 2021-07-21T22:24:43.272Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282414 Toolkit.Telemetry TRACE TelemetryController::loadProbeDefinitions - no builtin JS probe file EventArtifactDefinitions.json"
[task 2021-07-21T22:24:43.272Z] 22:24:43 INFO - PID 680 | [Parent 680, Main Thread] WARNING: Couldn't get the user appdata directory, crash dumps will go in an unusual location: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:3007
[task 2021-07-21T22:24:43.272Z] 22:24:43 INFO - PID 680 | DLL blocklist was unable to intercept AppInit DLLs.
[task 2021-07-21T22:24:43.272Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282417 Toolkit.Telemetry TRACE TelemetryEnvironment::constructor"
[task 2021-07-21T22:24:43.273Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282442 Toolkit.Telemetry TRACE TelemetryEnvironment::_getGFXData - Only one display adapter detected."
[task 2021-07-21T22:24:43.273Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282448 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateSearchEngine - ignoring early call"
[task 2021-07-21T22:24:43.273Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (info) 1626906282449 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons"
[task 2021-07-21T22:24:43.274Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "readUpdateConfig: Unable to read app update configuration file. Exception: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.get]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/FileUtils.jsm :: FileUtils_getDir :: line 74" data: no]" {file: "resource://gre/modules/UpdateUtils.jsm" line: 861}]
[task 2021-07-21T22:24:43.274Z] 22:24:43 INFO - readUpdateConfig@resource://gre/modules/UpdateUtils.jsm:861:10
[task 2021-07-21T22:24:43.274Z] 22:24:43 INFO - _do_main@Z:\task_1626904070\build\tests\xpcshell\head.js:240:6
[task 2021-07-21T22:24:43.274Z] 22:24:43 INFO - _execute_test@Z:\task_1626904070\build\tests\xpcshell\head.js:597:5
[task 2021-07-21T22:24:43.274Z] 22:24:43 INFO - @-e:1:1
[task 2021-07-21T22:24:43.275Z] 22:24:43 INFO - "
[task 2021-07-21T22:24:43.275Z] 22:24:43 INFO - "CONSOLE_MESSAGE: (error) [JavaScript Error: "readUpdateConfig: Unable to read app update configuration file. Exception: [Exception... "Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.get]" nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource://gre/modules/FileUtils.jsm :: FileUtils_getDir :: line 74" data: no]" {file: "resource://gre/modules/UpdateUtils.jsm" line: 861}]
[task 2021-07-21T22:24:43.275Z] 22:24:43 INFO - readUpdateConfig@resource://gre/modules/UpdateUtils.jsm:861:10
[task 2021-07-21T22:24:43.275Z] 22:24:43 INFO - _do_main@Z:\task_1626904070\build\tests\xpcshell\head.js:240:6
[task 2021-07-21T22:24:43.276Z] 22:24:43 INFO - _execute_test@Z:\task_1626904070\build\tests\xpcshell\head.js:597:5
[task 2021-07-21T22:24:43.276Z] 22:24:43 INFO - @-e:1:1
[task 2021-07-21T22:24:43.276Z] 22:24:43 INFO - "

Jim, can you please take a look?

Comment 34

[Intermittent Failures Robot]

25 failures in 2940 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 15
• mozilla-central: 10

Platform and build breakdown:

• windows10-64-qr: 12
  • debug: 12
• windows10-32-qr: 13
  • debug: 13

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

Comment 35

[Iulian Moraru]

Update:
There have been 28 failures within the last 7 days:
• 12 failures on Windows 10 x86 WebRender debug
• 16 failures on Windows 10 x64 WebRender debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=347017732&repo=autoland&lineNumber=5794

Comment 36

[Intermittent Failures Robot]

30 failures in 3131 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

** 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: 19
• mozilla-central: 11

Platform and build breakdown:

• windows10-64-qr: 15
  • debug: 15
• windows10-32-qr: 14
  • debug: 14
• windows10-64-2004-qr: 1
  • debug: 1

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

Comment 37

[Intermittent Failures Robot]

17 failures in 2829 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 13

Platform and build breakdown:

• windows10-64-2004-qr: 6
  • debug: 6
• windows10-32-qr: 11
  • debug: 11

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

Comment 38

[Marian-Vasile Laza]

There have been 36 total failures in the last 7 days, recent failure log.
Affected platforms are:

• windows10-32-qr
• windows10-64-2004-qr

Comment 39

[Intermittent Failures Robot]

15 failures in 85 pushes (0.176 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 12
• mozilla-central: 3

Platform and build breakdown:

• windows10-64-2004-qr: 10
  • debug: 10
• windows10-32-qr: 5
  • debug: 5

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

Comment 40

[Intermittent Failures Robot]

52 failures in 3025 pushes (0.017 failures/push) were associated with this bug in the last 7 days.

This is the #17 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: 39
• mozilla-esr91: 2
• mozilla-central: 9
• try: 2

Platform and build breakdown:

• windows10-32-qr: 25
  • debug: 25
• windows10-64-2004-qr: 27
  • debug: 27

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

Comment 41

[Atila Butkovits]

There have been 58 failures in the last 7 days.
Happens on windows10-32-qr and windows10-64-2004-qr debug build type.
Recent failure log

Comment 42

[Intermittent Failures Robot]

59 failures in 2899 pushes (0.02 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: 41
• mozilla-central: 14
• mozilla-esr91: 2
• comm-central: 1
• try: 1

Platform and build breakdown:

• windows10-32-qr: 38
  • debug: 38
• windows10-64-2004-qr: 18
  • debug: 18
• windows10-64-qr: 3
  • debug: 3

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

Comment 43

[Intermittent Failures Robot]

17 failures in 518 pushes (0.033 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 15
• mozilla-central: 2

Platform and build breakdown:

• windows10-32-qr: 9
  • debug: 9
• windows10-64-2004-qr: 8
  • debug: 8

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

Comment 44

[Jens Stutte [:jstutte]]

So looking at another random instance, and I see in the log:

[task 2021-08-23T23:40:05.431Z] 23:40:05     INFO -  PID 12664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 718: Error: Phase "profile-change-teardown" is finished, it is too late to register completion condition "ServiceWorkerShutdownBlocker: shutting down Service Workers"
[task 2021-08-23T23:40:05.431Z] 23:40:05     INFO -  PID 12664 | [Parent 12664, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp:108
[task 2021-08-23T23:40:05.431Z] 23:40:05     INFO -  PID 12664 | Assertion failure: mShutdownBlocker, at /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerManager.cpp:467
[task 2021-08-23T23:40:05.431Z] 23:40:05     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-08-23T23:40:15.303Z] 23:40:15     INFO -  PID 12664 | #01: mozilla::dom::ServiceWorkerManager::Init(mozilla::dom::ServiceWorkerRegistrar*) [dom/serviceworkers/ServiceWorkerManager.cpp:467]
[task 2021-08-23T23:40:15.305Z] 23:40:15     INFO -  PID 12664 | #02: static mozilla::dom::ServiceWorkerManager::GetInstance() [dom/serviceworkers/ServiceWorkerManager.cpp:1397]
[task 2021-08-23T23:40:15.305Z] 23:40:15     INFO -  PID 12664 | #03: mozilla::dom::Document::DispatchContentLoadedEvents() [dom/base/Document.cpp:7887]
[task 2021-08-23T23:40:15.306Z] 23:40:15     INFO -  PID 12664 | #04: mozilla::detail::RunnableMethodImpl<mozilla::dom::Document *,void (mozilla::dom::Document::*)(),1,mozilla::RunnableKind::Standard>::Run() [xpcom/threads/nsThreadUtils.h:1204]
[task 2021-08-23T23:40:15.306Z] 23:40:15     INFO -  PID 12664 | #05: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:503]
[task 2021-08-23T23:40:15.307Z] 23:40:15     INFO -  PID 12664 | #06: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:805]

IIUC, we try to register a new shutdown blocker even after we already initiated shutdown. It seems, ServiceWorkerShutdownBlocker::CreateAndRegisterOn returns nullptr in this case (and produces the error from AsyncShutdown.jsm in the log we see) and we then assert on it but do not bail out in release builds.

Thus it seems that ServiceWorkerManager::GetInstance() is not protected against ressurrection during shutdown and there is potential for this to happen ?

I'll spin off an extra bug for this.

Comment 45

[Jens Stutte [:jstutte]]

So this intermittent appeared after we changed some things around how shutdown phases are advanced in bug 1689953. And in another occasion I recently realized that shutdown phases are not advanced as expected in xpcshell tests and filed bug 1726813. Could it be that we hit some edge case here that is normally avoided by some shutdown phase we do not go through explicitely in xpcshell tests?

Comment 46

[Andrew Sutherland [:asuth] (he/him)]

(In reply to Jens Stutte [:jstutte] from comment #45)

Could it be that we hit some edge case here that is normally avoided by some shutdown phase we do not go through explicitely in xpcshell tests?

It didn't seem like this directly involved control flow paths that were gated by shutdown checks. It seemed like there are API calls that are explicitly "create a hidden window if it already existed" and "give me the hidden window if it already exists, but don't create it", with code sometimes using the former code but after checking if the hidden window exists or not. Going from memory, it seemed like this is a case where it's not appropriate to to bring the hidden window into existence, at least for xpcshell tests.

Comment 47

[Andreea Pavel [:apavel]]

There are 47 total failures in the last 7 days on

• windows10-32-qr debug
• windows10-64-2004-qr debug
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=349793004&repo=mozilla-central&lineNumber=5927

[task 2021-08-27T22:20:21.967Z] 22:20:21     INFO -  TEST-START | dom/tests/unit/test_geolocation_reset_accuracy_wrap.js
[task 2021-08-27T22:20:22.796Z] 22:20:22  WARNING -  TEST-UNEXPECTED-FAIL | dom/tests/unit/test_geolocation_reset_accuracy_wrap.js | xpcshell return code: 1
[task 2021-08-27T22:20:22.798Z] 22:20:22     INFO -  TEST-INFO took 828ms
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  >>>>>>>
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  PID 468 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2940
[task 2021-08-27T22:20:22.801Z] 22:20:22     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Couldn't get the user appdata directory, crash dumps will go in an unusual location: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2999
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | JavaScript error: Z:\task_1630101606\build\tests\xpcshell\head.js, line 333: NS_NOINTERFACE:
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | DLL blocklist was unable to intercept AppInit DLLs.
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, kKnownEsrVersion) failed with result 0x80004002 (NS_NOINTERFACE): file /builds/worker/checkouts/gecko/toolkit/components/resistfingerprinting/nsRFPService.cpp:557
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.802Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.803Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.803Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.803Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.803Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.803Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.804Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.804Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.805Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.805Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.805Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.806Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.806Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.806Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.807Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windCowsouldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-08-27T22:20:22.807Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/do/m/media/CubebUtils.cpp:385
[task 2021-08-27T22:20:22.807Z] 22:20:22     INFO -  PID 468 | nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.808Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.808Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.809Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.809Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.810Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.810Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.810Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.811Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.811Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.811Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.812Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.812Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.813Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.813Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.813Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.814Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.814Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.815Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.815Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.815Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.816Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.816Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.817Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.817Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.817Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.818Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.818Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.819Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.819Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.819Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.820Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.820Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.821Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.821Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.822Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.822Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.822Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.823Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.823Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.823Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.824Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.824Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.824Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.825Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.825Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.826Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.826Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.826Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.827Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.827Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.828Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.828Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.828Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.829Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.829Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.830Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.831Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.831Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.832Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.832Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Unknown color for nsLookAndFeel: file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:348
[task 2021-08-27T22:20:22.832Z] 22:20:22     INFO -  PID 468 | [Parent 468, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1623
[task 2021-08-27T22:20:22.832Z] 22:20:22     INFO -  (xpcshell/head.js) | test run in child pending (2)
[task 2021-08-27T22:20:22.832Z] 22:20:22     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-08-27T22:20:22.832Z] 22:20:22     INFO -  running event loop
[task 2021-08-27T22:20:22.833Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:385
[task 2021-08-27T22:20:22.833Z] 22:20:22     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NS_NOINTERFACE: " {file: "Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js" line: 333}]
[task 2021-08-27T22:20:22.833Z] 22:20:22     INFO -  QueryInterface@Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js:333:22
[task 2021-08-27T22:20:22.834Z] 22:20:22     INFO -  createInstance@Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js:309:31
[task 2021-08-27T22:20:22.834Z] 22:20:22     INFO -  do_load_child_test_harness@Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js:1401:14
[task 2021-08-27T22:20:22.834Z] 22:20:22     INFO -  run_test_in_child/<@Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js:1428:5
[task 2021-08-27T22:20:22.835Z] 22:20:22     INFO -  run_test_in_child@Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js:1418:10
[task 2021-08-27T22:20:22.835Z] 22:20:22     INFO -  run_test@Z:/task_1630101606/build/tests/xpcshell/tests/dom/tests/unit/test_geolocation_reset_accuracy_wrap.js:59:20
[task 2021-08-27T22:20:22.835Z] 22:20:22     INFO -  _execute_test@Z:\\task_1630101606\\build\\tests\\xpcshell\\head.js:591:7
[task 2021-08-27T22:20:22.835Z] 22:20:22     INFO -  @-e:1:1
[task 2021-08-27T22:20:22.836Z] 22:20:22     INFO -  "
[task 2021-08-27T22:20:22.836Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4233
[task 2021-08-27T22:20:22.836Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1348
[task 2021-08-27T22:20:22.837Z] 22:20:22     INFO -  CHILD-TEST-STARTED
[task 2021-08-27T22:20:22.837Z] 22:20:22     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-08-27T22:20:22.837Z] 22:20:22     INFO -  (xpcshell/head.js) | test pending (2)
[task 2021-08-27T22:20:22.837Z] 22:20:22     INFO -  (xpcshell/head.js) | test pending (3)
[task 2021-08-27T22:20:22.838Z] 22:20:22     INFO -  (xpcshell/head.js) | test MAIN run_test finished (3)
[task 2021-08-27T22:20:22.838Z] 22:20:22     INFO -  running event loop
[task 2021-08-27T22:20:22.838Z] 22:20:22     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "Z:/task_1630101606/build/tests/xpcshell/head.js" line: 372}]"
[task 2021-08-27T22:20:22.839Z] 22:20:22     INFO -  "CONSOLE_MESSAGE: (warn) [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "Z:/task_1630101606/build/tests/xpcshell/head.js" line: 372}]"
[task 2021-08-27T22:20:22.839Z] 22:20:22     INFO -  (xpcshell/head.js) | test finished (2)
[task 2021-08-27T22:20:22.839Z] 22:20:22     INFO -  (xpcshell/head.js) | test finished (1)
[task 2021-08-27T22:20:22.839Z] 22:20:22     INFO -  exiting test
[task 2021-08-27T22:20:22.839Z] 22:20:22     INFO -  CHILD-TEST-COMPLETED
[task 2021-08-27T22:20:22.840Z] 22:20:22     INFO -  TEST-PASS | dom/tests/unit/test_geolocation_reset_accuracy_wrap.js | check_results - [check_results : 64] true == true
[task 2021-08-27T22:20:22.840Z] 22:20:22     INFO -  TEST-PASS | dom/tests/unit/test_geolocation_reset_accuracy_wrap.js | check_results - [check_results : 66] true == true
[task 2021-08-27T22:20:22.840Z] 22:20:22     INFO -  (xpcshell/head.js) | test finished (1)
[task 2021-08-27T22:20:22.841Z] 22:20:22     INFO -  exiting test
[task 2021-08-27T22:20:22.841Z] 22:20:22     INFO -  PID 468 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-27T22:20:22.841Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.842Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.842Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.842Z] 22:20:22     INFO -  PID 468 | ###!!! [Child][MessageChannel] Error: (msgtype=0xAC0018,name=PWindowGlobal::Msg_UpdateDocumentHasLoaded) Channel closing: too late to send/recv, messages will be lost
[task 2021-08-27T22:20:22.843Z] 22:20:22     INFO -  PID 468 | ###!!! [Child][MessageChannel] Error: (msgtype=0xAC0030,name=PWindowGlobal::Msg_Destroy) Channel closing: too late to send/recv, messages will be lost
[task 2021-08-27T22:20:22.843Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.844Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-27T22:20:22.844Z] 22:20:22     INFO -  PID 468 | JavaScript error: resource://gre/mo[dules/AsyncShutdoChild 1wn.jsm, li980ne 718: Err4o, Main Threadr] : Phase WARNING"profile-cha: IPC messagen gdei-stcarded:e aractor down" ciasnnot s efndi:n ifshed, it isile /builds/ wtoorok elr/checkouts/gaecko/ipc/glute to registe/ProtocolUtils.er cocpp:527m
[task 2021-08-27T22:20:22.844Z] 22:20:22     INFO -  PID 468 | pletion condition "ServiceWorkerShutdownBlocker: shutting down Service Workers"
[task 2021-08-27T22:20:22.845Z] 22:20:22     INFO -  PID 468 | [Parent 468, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp:108
[task 2021-08-27T22:20:22.845Z] 22:20:22     INFO -  PID 468 | Assertion failure: mShutdownBlocker, at /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerManager.cpp:467
[task 2021-08-27T22:20:22.846Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.846Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.846Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-27T22:20:22.847Z] 22:20:22     INFO -  PID 468 | [Child 19804, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-27T22:20:22.847Z] 22:20:22     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-08-27T22:20:34.580Z] 22:20:34     INFO -  PID 468 | #01: mozilla::dom::ServiceWorkerManager::Init(mozilla::dom::ServiceWorkerRegistrar*) [dom/serviceworkers/ServiceWorkerManager.cpp:467]
[task 2021-08-27T22:20:34.581Z] 22:20:34     INFO -  PID 468 | #02: static mozilla::dom::ServiceWorkerManager::GetInstance() [dom/serviceworkers/ServiceWorkerManager.cpp:1397]
[task 2021-08-27T22:20:34.581Z] 22:20:34     INFO -  PID 468 | #03: mozilla::dom::Document::DispatchContentLoadedEvents() [dom/base/Document.cpp:7887]
[task 2021-08-27T22:20:34.581Z] 22:20:34     INFO -  PID 468 | #04: mozilla::detail::RunnableMethodImpl<mozilla::dom::Document *,void (mozilla::dom::Document::*)() __attribute__((thiscall)),1,mozilla::RunnableKind::Standard>::Run() [xpcom/threads/nsThreadUtils.h:1204]
[task 2021-08-27T22:20:34.582Z] 22:20:34     INFO -  PID 468 | #05: mozilla::RunnableTask::Run() [xpcom/threads/TaskController.cpp:503]
[task 2021-08-27T22:20:34.582Z] 22:20:34     INFO -  PID 468 | #06: mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:805]
[task 2021-08-27T22:20:34.582Z] 22:20:34     INFO -  PID 468 | #07: mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [xpcom/threads/TaskController.cpp:641]
[task 2021-08-27T22:20:34.583Z] 22:20:34     INFO -  PID 468 | #08: mozilla::TaskController::ProcessPendingMTTask(bool) [xpcom/threads/TaskController.cpp:425]
[task 2021-08-27T22:20:34.583Z] 22:20:34     INFO -  PID 468 | #09: mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:135:7'>::Run() [xpcom/threads/nsThreadUtils.h:533]
[task 2021-08-27T22:20:34.583Z] 22:20:34     INFO -  PID 468 | #10: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1152]
[task 2021-08-27T22:20:34.584Z] 22:20:34     INFO -  PID 468 | #11: NS_ProcessPendingEvents(nsIThread*, unsigned int) [xpcom/threads/nsThreadUtils.cpp:431]
[task 2021-08-27T22:20:34.584Z] 22:20:34     INFO -  PID 468 | #12: mozilla::ShutdownXPCOM(nsIServiceManager*) [xpcom/build/XPCOMInit.cpp:621]
[task 2021-08-27T22:20:34.585Z] 22:20:34     INFO -  PID 468 | #13: XRE_XPCShellMain(int, char**, char**, XREShellData const*) [js/xpconnect/src/XPCShellImpl.cpp:1432]
[task 2021-08-27T22:20:34.585Z] 22:20:34     INFO -  PID 468 | #14: mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) [toolkit/xre/Bootstrap.cpp:54]
[task 2021-08-27T22:20:34.635Z] 22:20:34     INFO -  PID 468 | #15: NS_internal_main(int, char**, char**) [js/xpconnect/shell/xpcshell.cpp:82]
[task 2021-08-27T22:20:34.635Z] 22:20:34     INFO -  PID 468 | #16: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2021-08-27T22:20:34.636Z] 22:20:34     INFO -  PID 468 | #17: __scrt_common_main_seh() [/builds/worker/workspace/obj-build/js/xpconnect/shell/f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2021-08-27T22:20:34.638Z] 22:20:34     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2021-08-27T22:20:34.638Z] 22:20:34     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-08-27T22:20:34.639Z] 22:20:34     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-08-27T22:20:34.639Z] 22:20:34     INFO -  PID 468 | #18: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x18484]
[task 2021-08-27T22:20:34.643Z] 22:20:34     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2021-08-27T22:20:34.643Z] 22:20:34     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-08-27T22:20:34.643Z] 22:20:34     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-08-27T22:20:34.643Z] 22:20:34     INFO -  PID 468 | #19: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6305a]
[task 2021-08-27T22:20:34.644Z] 22:20:34     INFO -  PID 468 | #20: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6302a]
[task 2021-08-27T22:20:34.644Z] 22:20:34     INFO -  <<<<<<<
[task 2021-08-27T22:20:47.455Z] 22:20:47  WARNING -  PROCESS-CRASH | dom/tests/unit/test_geolocation_reset_accuracy_wrap.js | application crashed [@ static mozilla::dom::ServiceWorkerManager::GetInstance()]
[task 2021-08-27T22:20:47.456Z] 22:20:47     INFO -  Mozilla crash reason: MOZ_ASSERT(mShutdownBlocker)
[task 2021-08-27T22:20:47.456Z] 22:20:47     INFO -  Crash dump filename: C:\Users\task_1630101606\AppData\Local\Temp\xpc-other-1qr958it\772d51b9-8f57-42ca-bd1a-cfb475f93271.dmp
[task 2021-08-27T22:20:47.456Z] 22:20:47     INFO -  Operating system: Windows NT
[task 2021-08-27T22:20:47.457Z] 22:20:47     INFO -                    10.0.17134
[task 2021-08-27T22:20:47.457Z] 22:20:47     INFO -  CPU: x86
[task 2021-08-27T22:20:47.457Z] 22:20:47     INFO -       GenuineIntel family 6 model 85 stepping 4
[task 2021-08-27T22:20:47.457Z] 22:20:47     INFO -       8 CPUs
[task 2021-08-27T22:20:47.458Z] 22:20:47     INFO -  GPU: UNKNOWN
[task 2021-08-27T22:20:47.458Z] 22:20:47     INFO -  Crash reason:  EXCEPTION_BREAKPOINT
[task 2021-08-27T22:20:47.458Z] 22:20:47     INFO -  Crash address: 0x62db19bc
[task 2021-08-27T22:20:47.458Z] 22:20:47     INFO -  Process uptime: 1 seconds
[task 2021-08-27T22:20:47.459Z] 22:20:47     INFO -  Thread 0 tid 17148 (crashed) - GeckoMain 0  xul.dll!mozilla::dom::ServiceWorkerManager::Init(mozilla::dom::ServiceWorkerRegistrar*) [ServiceWorkerManager.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 467 + 0x0]
[task 2021-08-27T22:20:47.459Z] 22:20:47     INFO -      eip = 0x62db19bc   esp = 0x0523e9f0   ebp = 0x0523ea10   ebx = 0x104564c0
[task 2021-08-27T22:20:47.459Z] 22:20:47     INFO -      esi = 0x05ed0050   edi = 0x05ed0050   eax = 0x6a48c62c   ecx = 0x00000000
[task 2021-08-27T22:20:47.460Z] 22:20:47     INFO -      edx = 0x00000048   efl = 0x00200216
[task 2021-08-27T22:20:47.460Z] 22:20:47     INFO -      Found by: given as instruction pointer in context
[task 2021-08-27T22:20:47.460Z] 22:20:47     INFO -   1  xul.dll!static mozilla::dom::ServiceWorkerManager::GetInstance() [ServiceWorkerManager.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 1396 + 0x6]
[task 2021-08-27T22:20:47.460Z] 22:20:47     INFO -      eip = 0x62dad92e   esp = 0x0523ea18   ebp = 0x0523ea24   ebx = 0x05e21800
[task 2021-08-27T22:20:47.461Z] 22:20:47     INFO -      esi = 0x104564c0   edi = 0x0ef04600
[task 2021-08-27T22:20:47.461Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.461Z] 22:20:47     INFO -   2  xul.dll!mozilla::dom::Document::DispatchContentLoadedEvents() [Document.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 7887 + 0x6]
[task 2021-08-27T22:20:47.461Z] 22:20:47     INFO -      eip = 0x60b685b2   esp = 0x0523ea2c   ebp = 0x0523eaa4   esi = 0x0523ea60
[task 2021-08-27T22:20:47.462Z] 22:20:47     INFO -      edi = 0x05e29800
[task 2021-08-27T22:20:47.462Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.462Z] 22:20:47     INFO -   3  xul.dll!mozilla::detail::RunnableMethodImpl<mozilla::dom::Document *,void (mozilla::dom::Document::*)() __attribute__((thiscall)),1,mozilla::RunnableKind::Standard>::Run() [nsThreadUtils.h:15fdb071da984afa7541a63cfb00d615d1a09c05 : 1201 + 0x12]
[task 2021-08-27T22:20:47.463Z] 22:20:47     INFO -      eip = 0x60bcb0a1   esp = 0x0523eaac   ebp = 0x0523eab4   ebx = 0x05e13f00
[task 2021-08-27T22:20:47.463Z] 22:20:47     INFO -      esi = 0x05e21800   edi = 0x60b68230
[task 2021-08-27T22:20:47.463Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.464Z] 22:20:47     INFO -   4  xul.dll!mozilla::RunnableTask::Run() [TaskController.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 502 + 0xe]
[task 2021-08-27T22:20:47.464Z] 22:20:47     INFO -      eip = 0x5f4aeb00   esp = 0x0523eabc   ebp = 0x0523ef18   esi = 0x05e13f10
[task 2021-08-27T22:20:47.464Z] 22:20:47     INFO -      edi = 0x05eac880
[task 2021-08-27T22:20:47.464Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.465Z] 22:20:47     INFO -   5  xul.dll!mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [TaskController.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 805 + 0x11]
[task 2021-08-27T22:20:47.465Z] 22:20:47     INFO -      eip = 0x5f49070d   esp = 0x0523ef20   ebp = 0x0523f1bc   ebx = 0x05e13f10
[task 2021-08-27T22:20:47.465Z] 22:20:47     INFO -      esi = 0x5f4aea10   edi = 0x05e0bfc8
[task 2021-08-27T22:20:47.465Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.466Z] 22:20:47     INFO -   6  xul.dll!mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [TaskController.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 641 + 0x8]
[task 2021-08-27T22:20:47.466Z] 22:20:47     INFO -      eip = 0x5f48ee71   esp = 0x0523f1c4   ebp = 0x0523f20c   ebx = 0x05e00100
[task 2021-08-27T22:20:47.466Z] 22:20:47     INFO -      esi = 0x05e0bf00   edi = 0x0523f2f0
[task 2021-08-27T22:20:47.466Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.467Z] 22:20:47     INFO -   7  xul.dll!mozilla::TaskController::ProcessPendingMTTask(bool) [TaskController.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 425 + 0x8]
[task 2021-08-27T22:20:47.467Z] 22:20:47     INFO -      eip = 0x5f48f116   esp = 0x0523f214   ebp = 0x0523f238   ebx = 0x05e00100
[task 2021-08-27T22:20:47.467Z] 22:20:47     INFO -      esi = 0x05e0bf00   edi = 0x0523f2f0
[task 2021-08-27T22:20:47.468Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.468Z] 22:20:47     INFO -   8  xul.dll!mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:135:7'>::Run() [nsThreadUtils.h:15fdb071da984afa7541a63cfb00d615d1a09c05 : 532 + 0x11]
[task 2021-08-27T22:20:47.468Z] 22:20:47     INFO -      eip = 0x5f4b2a54   esp = 0x0523f240   ebp = 0x0523f244   ebx = 0x05e00100
[task 2021-08-27T22:20:47.468Z] 22:20:47     INFO -      esi = 0x05e025a0   edi = 0x0523f2f0
[task 2021-08-27T22:20:47.469Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.469Z] 22:20:47     INFO -   9  xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 1148 + 0xe]
[task 2021-08-27T22:20:47.469Z] 22:20:47     INFO -      eip = 0x5f4a0d8b   esp = 0x0523f24c   ebp = 0x0523f378
[task 2021-08-27T22:20:47.469Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.470Z] 22:20:47     INFO -  10  xul.dll!NS_ProcessPendingEvents(nsIThread*, unsigned int) [nsThreadUtils.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 431 + 0x14]
[task 2021-08-27T22:20:47.470Z] 22:20:47     INFO -      eip = 0x5f49e1c5   esp = 0x0523f380   ebp = 0x0523f3a0   ebx = 0x00000000
[task 2021-08-27T22:20:47.470Z] 22:20:47     INFO -      esi = 0x05e6a2f0   edi = 0x00123bad
[task 2021-08-27T22:20:47.470Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.471Z] 22:20:47     INFO -  11  xul.dll!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 621 + 0xa]
[task 2021-08-27T22:20:47.471Z] 22:20:47     INFO -      eip = 0x5f4e50e4   esp = 0x0523f3a8   ebp = 0x0523f444   ebx = 0x0523f3d0
[task 2021-08-27T22:20:47.471Z] 22:20:47     INFO -      esi = 0x0523f3b0   edi = 0x05e175c0
[task 2021-08-27T22:20:47.471Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.472Z] 22:20:47     INFO -  12  xul.dll!XRE_XPCShellMain(int, char**, char**, XREShellData const*) [XPCShellImpl.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 1432 + 0x7]
[task 2021-08-27T22:20:47.472Z] 22:20:47     INFO -      eip = 0x602d079a   esp = 0x0523f44c   ebp = 0x0523f700   ebx = 0x05e14040
[task 2021-08-27T22:20:47.472Z] 22:20:47     INFO -      esi = 0x0523f450   edi = 0x00000001
[task 2021-08-27T22:20:47.472Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.473Z] 22:20:47     INFO -  13  xul.dll!mozilla::BootstrapImpl::XRE_XPCShellMain(int, char**, char**, XREShellData const*) [Bootstrap.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 54 + 0x9]
[task 2021-08-27T22:20:47.473Z] 22:20:47     INFO -      eip = 0x64a0c963   esp = 0x0523f708   ebp = 0x0523f718   ebx = 0x0531e380
[task 2021-08-27T22:20:47.473Z] 22:20:47     INFO -      esi = 0x05e06120   edi = 0x64a0c950
[task 2021-08-27T22:20:47.473Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.474Z] 22:20:47     INFO -  14  xpcshell.exe!NS_internal_main(int, char**, char**) [xpcshell.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 82 + 0x1f]
[task 2021-08-27T22:20:47.474Z] 22:20:47     INFO -      eip = 0x00a5127e   esp = 0x0523f720   ebp = 0x0523f74c
[task 2021-08-27T22:20:47.474Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.475Z] 22:20:47     INFO -  15  xpcshell.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:15fdb071da984afa7541a63cfb00d615d1a09c05 : 131 + 0xf]
[task 2021-08-27T22:20:47.475Z] 22:20:47     INFO -      eip = 0x00a5119b   esp = 0x0523f754   ebp = 0x0523f778   ebx = 0x05e03d80
[task 2021-08-27T22:20:47.475Z] 22:20:47     INFO -      esi = 0x0000001c   edi = 0x05e03e80
[task 2021-08-27T22:20:47.475Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.476Z] 22:20:47     INFO -  16  xpcshell.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x1c]
[task 2021-08-27T22:20:47.476Z] 22:20:47     INFO -      eip = 0x00ac1859   esp = 0x0523f780   ebp = 0x0523f7c0   ebx = 0x04ec3000
[task 2021-08-27T22:20:47.476Z] 22:20:47     INFO -      esi = 0x73e9f0b0   edi = 0x0531c1f0
[task 2021-08-27T22:20:47.476Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.477Z] 22:20:47     INFO -  17  kernel32.dll!BaseThreadInitThunk + 0x24
[task 2021-08-27T22:20:47.477Z] 22:20:47     INFO -      eip = 0x755d8484   esp = 0x0523f7c8   ebp = 0x0523f7d4
[task 2021-08-27T22:20:47.477Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.477Z] 22:20:47     INFO -  18  ntdll.dll!AslPathWildcardFindFirst + 0x218
[task 2021-08-27T22:20:47.477Z] 22:20:47     INFO -      eip = 0x773f305a   esp = 0x0523f7dc   ebp = 0x0523f81c
[task 2021-08-27T22:20:47.478Z] 22:20:47     INFO -      Found by: call frame info
[task 2021-08-27T22:20:47.478Z] 22:20:47     INFO -  19  ntdll.dll!AslPathWildcardFindFirst + 0x1e8
[task 2021-08-27T22:20:47.479Z] 22:20:47     INFO -      eip = 0x773f302a   esp = 0x0523f824   ebp = 0x0523f82c
[task 2021-08-27T22:20:47.479Z] 22:20:47     INFO -      Found by: call frame info

Hi Andrew, I saw you renamed this bug earlier, however with the new title it won't show up as a suggestion in TH. Could the assertion be added again in the title?

Comment 48

[Intermittent Failures Robot]

42 failures in 2580 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #30 most frequent failure this week.

** 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: 27
• mozilla-central: 15

Platform and build breakdown:

• windows10-32-qr: 21
  • debug: 21
• windows10-64-2004-qr: 21
  • debug: 21

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

Comment 49

[Intermittent Failures Robot]

15 failures in 585 pushes (0.026 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 10
• mozilla-central: 5

Platform and build breakdown:

• windows10-32-qr: 7
  • debug: 7
• windows10-64-2004-qr: 8
  • debug: 8

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

Comment 50

[Jens Stutte [:jstutte]]

In bug 1727243 we want to hardenServiceWorkerManager against resurrection during shutdown, using probably AppShutdown::GetCurrentShutdownPhase. However, this intermittent happens during xpcshell tests where we know to not advance the shutdown phases as in normal operations (see bug 1726813), so this alone would not help here.

Comment 51

[Intermittent Failures Robot]

38 failures in 3084 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #25 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: 27
• mozilla-central: 10
• comm-central: 1

Platform and build breakdown:

• windows10-64-2004-qr: 15
  • debug: 15
• windows10-32-qr: 22
  • debug: 22
• windows10-64-qr: 1
  • debug: 1

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

Comment 52

[Intermittent Failures Robot]

16 failures in 3266 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 12

Platform and build breakdown:

• windows10-32-qr: 16
  • debug: 16

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

Comment 53

[Jens Stutte [:jstutte]]

The patch in bug 1726813 should avoid the crashes now. I assume, this will result in a new intermittent failure. But it might not be easy to see the connection, though. I'd propose to just close this bug after a while.

Comment 54

[Intermittent Failures Robot]

3 failures in 3375 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• windows10-32-2004-qr: 3
  • debug: 3

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

Comment 55

[Jens Stutte [:jstutte]]

The remaining 3 cases were all pre-patch.

Jim, I'd propose to spin-off a bug that investigates if WindowsUIUtils::UpdateTabletModeState should not try to create a hidden window in the case described by Andrew in comment 13 and comment 14 and close this one, as we won't see any new instances come in here.

Comment 56

[Intermittent Failures Robot]

4 failures in 2873 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 1
• mozilla-esr91: 3

Platform and build breakdown:

• windows10-64-2004-qr: 3
  • debug: 3
• windows10-32-2004-qr: 1
  • debug: 1

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

Comment 57

[Jim Mathies [:jimm]]

This is ancient code that no owner. Seems like it might be a pretty easy fix, maybe by checking for a hidden window first. Not sure who can work on this. Marking it as P2 though so it stands out in future triage.

Comment 58

[Stephen A Pohl [:spohl] (OOO until 2/27)]

Closing this since there seem to be no more recent failures.