1723846 - Intermittent docshell/test/unit/test_URIFixup_info.js | application crashed [@ nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*)]

Reporter

Description

•

3 years ago

treeherder

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

[task 2021-08-03T21:37:53.283Z] 21:37:53     INFO -  TEST-PASS | docshell/test/unit/test_URIFixup_info.js | run_test - [run_test : 1026] should mirror original input - "/a" == "/a"
[task 2021-08-03T21:37:53.283Z] 21:37:53     INFO -  (xpcshell/head.js) | test run_next_test 2 pending (2)
[task 2021-08-03T21:37:53.283Z] 21:37:53     INFO -  (xpcshell/head.js) | test run_test finished (2)
[task 2021-08-03T21:37:53.283Z] 21:37:53     INFO -  (xpcshell/head.js) | test run_next_test 2 finished (1)
[task 2021-08-03T21:37:53.283Z] 21:37:53     INFO -  exiting test
[task 2021-08-03T21:37:53.284Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (info) 1628026669786	addons.manager	DEBUG	before shutdown"
[task 2021-08-03T21:37:53.284Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (info) 1628026669787	addons.manager	DEBUG	shutdown"
[task 2021-08-03T21:37:53.284Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (info) 1628026669787	addons.manager	DEBUG	Calling shutdown blocker for XPIProvider"
[task 2021-08-03T21:37:53.284Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (info) 1628026669787	addons.xpi	DEBUG	shutdown"
[task 2021-08-03T21:37:53.284Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (info) 1628026669787	addons.xpi-utils	DEBUG	shutdown"
[task 2021-08-03T21:37:53.285Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (info) 1628026669792	addons.manager	DEBUG	Async provider shutdown done"
[task 2021-08-03T21:37:53.285Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.285Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.286Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.286Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.286Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: idb: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.287Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: cache: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.287Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: sdb: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.287Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, IPDL Background] WARNING: ls: '0.000000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-03T21:37:53.287Z] 21:37:53     INFO -  "CONSOLE_MESSAGE: (error) [JavaScript Error: "NetworkError when attempting to fetch resource." {file: "Z:\\task_162802556809931\\build\\tests\\xpcshell\\head.js" line: 701}]
[task 2021-08-03T21:37:53.288Z] 21:37:53     INFO -  _execute_test@Z:\\task_162802556809931\\build\\tests\\xpcshell\\head.js:701:19
[task 2021-08-03T21:37:53.288Z] 21:37:53     INFO -  @-e:1:1
[task 2021-08-03T21:37:53.288Z] 21:37:53     INFO -  "
[task 2021-08-03T21:37:53.288Z] 21:37:53     INFO -  PID 4132 | [Parent 4132, Main Thread] WARNING: unable to post SHUTDOWN message: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:153
[task 2021-08-03T21:37:53.289Z] 21:37:53     INFO -  PID 4132 | Storage connection not closed: cookies.sqliteHit MOZ_CRASH() at /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:712
[task 2021-08-03T21:37:53.289Z] 21:37:53     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-08-03T21:38:02.972Z] 21:38:02     INFO -  PID 4132 | #01: mozilla::storage::Service::Observe(nsISupports*, char const*, char16_t const*) [storage/mozStorageService.cpp:712]
[task 2021-08-03T21:38:02.972Z] 21:38:02     INFO -  PID 4132 | #02: nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [xpcom/ds/nsObserverList.cpp:69]
[task 2021-08-03T21:38:02.973Z] 21:38:02     INFO -  PID 4132 | #03: nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [xpcom/ds/nsObserverService.cpp:291]
[task 2021-08-03T21:38:02.973Z] 21:38:02     INFO -  PID 4132 | #04: mozilla::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) [xpcom/base/AppShutdown.cpp:333]
[task 2021-08-03T21:38:02.973Z] 21:38:02     INFO -  PID 4132 | #05: mozilla::ShutdownXPCOM(nsIServiceManager*) [xpcom/build/XPCOMInit.cpp:624]
[task 2021-08-03T21:38:02.974Z] 21:38:02     INFO -  PID 4132 | #06: XRE_XPCShellMain(int, char**, char**, XREShellData const*) [js/xpconnect/src/XPCShellImpl.cpp:1432]
[task 2021-08-03T21:38:03.019Z] 21:38:03     INFO -  PID 4132 | #07: NS_internal_main(int, char**, char**) [js/xpconnect/shell/xpcshell.cpp:82]
[task 2021-08-03T21:38:03.020Z] 21:38:03     INFO -  PID 4132 | #08: wmain(int, wchar_t**) [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2021-08-03T21:38:03.020Z] 21:38:03     INFO -  PID 4132 | #09: __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-03T21:38:03.024Z] 21:38:03     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2021-08-03T21:38:03.024Z] 21:38:03     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-08-03T21:38:03.024Z] 21:38:03     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-08-03T21:38:03.024Z] 21:38:03     INFO -  PID 4132 | #10: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x17034]
[task 2021-08-03T21:38:03.040Z] 21:38:03     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2021-08-03T21:38:03.040Z] 21:38:03     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-08-03T21:38:03.040Z] 21:38:03     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-08-03T21:38:03.041Z] 21:38:03     INFO -  PID 4132 | #11: RtlUserThreadStart [C:\Windows\SYSTEM32\ntdll.dll + 0x52651]
[task 2021-08-03T21:38:03.041Z] 21:38:03     INFO -  <<<<<<<
[task 2021-08-03T21:38:08.841Z] 21:38:08  WARNING -  PROCESS-CRASH | docshell/test/unit/test_URIFixup_info.js | application crashed [@ nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*)]
[task 2021-08-03T21:38:08.842Z] 21:38:08     INFO -  Mozilla crash reason: MOZ_CRASH()
[task 2021-08-03T21:38:08.842Z] 21:38:08     INFO -  Crash dump filename: C:\Users\task_162802556809931\AppData\Local\Temp\xpc-other-bes4omyj\cc9e8dbf-c368-41aa-99b9-70a9d705282e.dmp
[task 2021-08-03T21:38:08.842Z] 21:38:08     INFO -  Operating system: Windows NT
[task 2021-08-03T21:38:08.842Z] 21:38:08     INFO -                    10.0.19041
[task 2021-08-03T21:38:08.842Z] 21:38:08     INFO -  CPU: amd64
[task 2021-08-03T21:38:08.843Z] 21:38:08     INFO -       family 6 model 85 stepping 7
[task 2021-08-03T21:38:08.843Z] 21:38:08     INFO -       8 CPUs
[task 2021-08-03T21:38:08.843Z] 21:38:08     INFO -  GPU: UNKNOWN
[task 2021-08-03T21:38:08.843Z] 21:38:08     INFO -  Crash reason:  EXCEPTION_BREAKPOINT
[task 2021-08-03T21:38:08.843Z] 21:38:08     INFO -  Crash address: 0x19bd758d
[task 2021-08-03T21:38:08.843Z] 21:38:08     INFO -  Process uptime: 4 seconds
[task 2021-08-03T21:38:08.844Z] 21:38:08     INFO -  Thread 0 (crashed) 0  xul.dll!mozilla::storage::Service::Observe(nsISupports*, char const*, char16_t const*) [mozStorageService.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 712 + 0x0]
[task 2021-08-03T21:38:08.844Z] 21:38:08     INFO -      rax = 0x00007ffd2176ea3e   rdx = 0x0000000000000000
[task 2021-08-03T21:38:08.844Z] 21:38:08     INFO -      rcx = 0x00007ffd2e9f8880   rbx = 0x0000006b203ff230
[task 2021-08-03T21:38:08.844Z] 21:38:08     INFO -      rsi = 0x0000006b203ff230   rdi = 0x0000006b203ff240
[task 2021-08-03T21:38:08.844Z] 21:38:08     INFO -      rbp = 0x0000000000000000   rsp = 0x0000006b203ff210
[task 2021-08-03T21:38:08.845Z] 21:38:08     INFO -       r8 = 0x0000006b203ffc50    r9 = 0x00007ffd56170000
[task 2021-08-03T21:38:08.845Z] 21:38:08     INFO -      r10 = 0x00007ffd561c2651   r11 = 0x0000006b203febd0
[task 2021-08-03T21:38:08.845Z] 21:38:08     INFO -      r12 = 0x0000018c7251d160   r13 = 0x00007ffd217be810
[task 2021-08-03T21:38:08.845Z] 21:38:08     INFO -      r14 = 0x0000006b203ff230   r15 = 0x0000018c72524d00
[task 2021-08-03T21:38:08.845Z] 21:38:08     INFO -      rip = 0x00007ffd19bd758d
[task 2021-08-03T21:38:08.846Z] 21:38:08     INFO -      Found by: given as instruction pointer in context
[task 2021-08-03T21:38:08.846Z] 21:38:08     INFO -   1  xul.dll!nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverList.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 70 + 0x16]
[task 2021-08-03T21:38:08.846Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.846Z] 21:38:08     INFO -      rsp = 0x0000006b203ff2a0   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.846Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.847Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ffd18abe276
[task 2021-08-03T21:38:08.847Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.847Z] 21:38:08     INFO -   2  xul.dll!nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverService.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 288 + 0x11]
[task 2021-08-03T21:38:08.847Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.847Z] 21:38:08     INFO -      rsp = 0x0000006b203ff300   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.848Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.848Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ffd18ac9868
[task 2021-08-03T21:38:08.848Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.848Z] 21:38:08     INFO -   3  xul.dll!mozilla::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) [AppShutdown.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 330 + 0x13]
[task 2021-08-03T21:38:08.849Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.849Z] 21:38:08     INFO -      rsp = 0x0000006b203ff4b0   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.849Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.849Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ffd18a35112
[task 2021-08-03T21:38:08.849Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.850Z] 21:38:08     INFO -   4  xul.dll!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 624 + 0xc]
[task 2021-08-03T21:38:08.850Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.850Z] 21:38:08     INFO -      rsp = 0x0000006b203ff510   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.850Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.850Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ffd18bd6ff0
[task 2021-08-03T21:38:08.850Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.851Z] 21:38:08     INFO -   5  xul.dll!XRE_XPCShellMain(int, char**, char**, XREShellData const*) [XPCShellImpl.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 1432 + 0x7]
[task 2021-08-03T21:38:08.851Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.851Z] 21:38:08     INFO -      rsp = 0x0000006b203ff600   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.851Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.851Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ffd19b55dd1
[task 2021-08-03T21:38:08.851Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.852Z] 21:38:08     INFO -   6  xpcshell.exe!NS_internal_main(int, char**, char**) [xpcshell.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 82 + 0x22]
[task 2021-08-03T21:38:08.852Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.852Z] 21:38:08     INFO -      rsp = 0x0000006b203ffa40   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.852Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.852Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ff6888e12b6
[task 2021-08-03T21:38:08.852Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.853Z] 21:38:08     INFO -   7  xpcshell.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:1913dbd50ba35eb568069a3c18d52f1e68e266fd : 131 + 0x13]
[task 2021-08-03T21:38:08.853Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.853Z] 21:38:08     INFO -      rsp = 0x0000006b203ffab0   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.853Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.853Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ff6888e11b4
[task 2021-08-03T21:38:08.853Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.854Z] 21:38:08     INFO -   8  xpcshell.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x22]
[task 2021-08-03T21:38:08.854Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.854Z] 21:38:08     INFO -      rsp = 0x0000006b203ffb60   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.854Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.854Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ff68895e8c8
[task 2021-08-03T21:38:08.854Z] 21:38:08     INFO -      Found by: call frame info
[task 2021-08-03T21:38:08.855Z] 21:38:08     INFO -   9  kernel32.dll!LdrpSnapKernelBaseExtensions + 0x150
[task 2021-08-03T21:38:08.855Z] 21:38:08     INFO -      rbx = 0x0000006b203ff230   rbp = 0x0000000000000000
[task 2021-08-03T21:38:08.855Z] 21:38:08     INFO -      rsp = 0x0000006b203ffba0   r12 = 0x0000018c7251d160
[task 2021-08-03T21:38:08.855Z] 21:38:08     INFO -      r13 = 0x00007ffd217be810   r14 = 0x0000006b203ff230
[task 2021-08-03T21:38:08.855Z] 21:38:08     INFO -      r15 = 0x0000018c72524d00   rip = 0x00007ffd54d67034
[task 2021-08-03T21:38:08.855Z] 21:38:08     INFO -      Found by: call frame info

Mike Hommey [:glandium]

Comment 6

•

3 years ago

Relevant part of the error:

Storage connection not closed: cookies.sqliteHit MOZ_CRASH() at /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:712

Component: Lint and Formatting → Storage

Product: Firefox Build System → Toolkit

Andrew Sutherland [:asuth] (he/him)

Updated

•

3 years ago

Component: Storage → Networking: Cookies

Product: Toolkit → Core

Alexandru Michis [:malexandru]

Updated

•

3 years ago

See Also: → https://bugzilla.mozilla.org/show_bug.cgi?id=1707963

Comment hidden (Intermittent Failures Robot)

Kershaw Chang [:kershaw]

Comment 25

•

2 years ago

Close since there is no crash for more than 3 months.

Status: NEW → RESOLVED

Closed: 2 years ago

Resolution: --- → INCOMPLETE

Bugzilla

Quick Search

Intermittent docshell/test/unit/test_URIFixup_info.js | application crashed [@ nsObserverList::NotifyObservers(nsISupports, char const, char16_t const*)]

Categories

(Core :: Networking: Cookies, defect)

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Comment 6

Updated

Updated

Comment 7

Comment 8

Comment 9

Comment 10

Comment 11

Comment 12

Comment 13

Comment 14

Comment 15

Comment 16

Comment 17

Comment 18

Comment 19

Comment 20

Comment 21

Comment 22

Comment 23

Comment 24

Comment 25