Closed Bug 1906631 Opened 2 years ago Closed 1 year ago

Intermittent [Parent 1131, IPC I/O Parent] WARNING: Process 2020 hanging at shutdown; attempting crash report (fatal error).: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:207

Categories

(Core :: Storage: IndexedDB, defect)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2024-07-08T10:41:09.324Z] 10:41:09     INFO - TEST-START | dom/media/autoplay/test/mochitest/test_streams_autoplay.html
[task 2024-07-08T10:41:09.534Z] 10:41:09     INFO - GECKO(1131) | MEMORY STAT | vsize 2823MB | residentFast 326MB | heapAllocated 15MB
[task 2024-07-08T10:41:09.765Z] 10:41:09     INFO - TEST-OK | dom/media/autoplay/test/mochitest/test_streams_autoplay.html | took 441ms
[task 2024-07-08T10:41:09.788Z] 10:41:09     INFO - GECKO(1131) | JavaScript error: resource://gre/actors/PictureInPictureChild.sys.mjs, line 342: TypeError: can't access dead object
[task 2024-07-08T10:41:09.842Z] 10:41:09     INFO - TEST-START | Shutdown
[task 2024-07-08T10:41:09.843Z] 10:41:09     INFO - Passed:  547
[task 2024-07-08T10:41:09.844Z] 10:41:09     INFO - Failed:  0
[task 2024-07-08T10:41:09.847Z] 10:41:09     INFO - Todo:    0
[task 2024-07-08T10:41:09.848Z] 10:41:09     INFO - Mode:    e10s
[task 2024-07-08T10:41:09.848Z] 10:41:09     INFO - Slowest: 12377ms - /tests/dom/media/autoplay/test/mochitest/test_autoplay_policy_activation.html
[task 2024-07-08T10:41:09.848Z] 10:41:09     INFO - SimpleTest FINISHED
[task 2024-07-08T10:41:09.849Z] 10:41:09     INFO - TEST-INFO | Ran 1 Loops
[task 2024-07-08T10:41:09.849Z] 10:41:09     INFO - SimpleTest FINISHED
[task 2024-07-08T10:41:09.956Z] 10:41:09     INFO - GECKO(1131) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'SpecialPowers'
[task 2024-07-08T10:41:09.958Z] 10:41:09     INFO - GECKO(1131) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2024-07-08T10:41:09.960Z] 10:41:09     INFO - GECKO(1131) | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 569: NotFoundError: No such JSWindowActor 'AppTestDelegate'
[task 2024-07-08T10:41:10.628Z] 10:41:10     INFO - GECKO(1131) | 1720435270627	Marionette	TRACE	Received observer notification quit-application
[task 2024-07-08T10:41:10.630Z] 10:41:10     INFO - GECKO(1131) | 1720435270628	Marionette	INFO	Stopped listening on port 2828
[task 2024-07-08T10:41:10.631Z] 10:41:10     INFO - GECKO(1131) | 1720435270630	Marionette	DEBUG	Marionette stopped listening
[task 2024-07-08T10:41:24.279Z] 10:41:24     INFO - GECKO(1131) | [Parent 1131, IPC I/O Parent] WARNING: Process 2020 may be hanging at shutdown; will wait for up to 80000ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:184
[task 2024-07-08T10:42:44.320Z] 10:42:44     INFO - GECKO(1131) | [Parent 1131, IPC I/O Parent] WARNING: Process 2020 hanging at shutdown; attempting crash report (fatal error).: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:207
[task 2024-07-08T10:42:44.577Z] 10:42:44     INFO - GECKO(1131) | [Parent 1131, IPC I/O Parent] WARNING: process 2020 exited on signal 6: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_posix.cc:335
[task 2024-07-08T10:42:44.920Z] 10:42:44     INFO - TEST-INFO | Main app process: exit 0
[task 2024-07-08T10:42:44.920Z] 10:42:44     INFO - runtests.py | Application ran for: 0:02:15.574131
[task 2024-07-08T10:42:44.921Z] 10:42:44     INFO - zombiecheck | Reading PID log: /tmp/tmpizv833yepidlog
[task 2024-07-08T10:42:44.921Z] 10:42:44     INFO - ==> process 1131 launched child process 1257
[task 2024-07-08T10:42:44.922Z] 10:42:44     INFO - ==> process 1131 launched child process 1263
[task 2024-07-08T10:42:44.922Z] 10:42:44     INFO - ==> process 1131 launched child process 1338
[task 2024-07-08T10:42:44.923Z] 10:42:44     INFO - ==> process 1131 launched child process 1348
[task 2024-07-08T10:42:44.923Z] 10:42:44     INFO - ==> process 1131 launched child process 1370
[task 2024-07-08T10:42:44.923Z] 10:42:44     INFO - ==> process 1131 launched child process 1371
[task 2024-07-08T10:42:44.924Z] 10:42:44     INFO - ==> process 1131 launched child process 1373
[task 2024-07-08T10:42:44.924Z] 10:42:44     INFO - ==> process 1131 launched child process 1464
[task 2024-07-08T10:42:44.924Z] 10:42:44     INFO - ==> process 1131 launched child process 1485
[task 2024-07-08T10:42:44.924Z] 10:42:44     INFO - ==> process 1131 launched child process 2020
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2021
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2027
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2117
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2187
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2192
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2249
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2287
[task 2024-07-08T10:42:44.927Z] 10:42:44     INFO - ==> process 1131 launched child process 2338
[task 2024-07-08T10:42:44.928Z] 10:42:44     INFO - ==> process 1131 launched child process 2382
[task 2024-07-08T10:42:44.928Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2187
[task 2024-07-08T10:42:44.928Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2192
[task 2024-07-08T10:42:44.928Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2338
[task 2024-07-08T10:42:44.928Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1464
[task 2024-07-08T10:42:44.928Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1338
[task 2024-07-08T10:42:44.929Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1348
[task 2024-07-08T10:42:44.929Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2117
[task 2024-07-08T10:42:44.929Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2249
[task 2024-07-08T10:42:44.929Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1485
[task 2024-07-08T10:42:44.929Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2382
[task 2024-07-08T10:42:44.929Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1370
[task 2024-07-08T10:42:44.930Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1371
[task 2024-07-08T10:42:44.930Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1373
[task 2024-07-08T10:42:44.930Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2020
[task 2024-07-08T10:42:44.930Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2021
[task 2024-07-08T10:42:44.930Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1257
[task 2024-07-08T10:42:44.931Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2027
[task 2024-07-08T10:42:44.931Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 1263
[task 2024-07-08T10:42:44.931Z] 10:42:44     INFO - zombiecheck | Checking for orphan process with PID: 2287
[task 2024-07-08T10:42:44.932Z] 10:42:44     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dWaICsKwRv6D3IAvMqKogw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2024-07-08T10:42:54.021Z] 10:42:54     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmp8_km9fg6/2790e4e1-de39-ea0e-216d-b80067e7c316.trace /tmp/tmph2eijw4c.mozrunner/minidumps/2790e4e1-de39-ea0e-216d-b80067e7c316.dmp /tmp/tmpxx5zpjb2
[task 2024-07-08T10:42:58.050Z] 10:42:58     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/2790e4e1-de39-ea0e-216d-b80067e7c316.dmp
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - PROCESS-CRASH | application crashed [@ libc.so.6 + 0x0000000000114cf9] | dom/media/autoplay/test/mochitest/mochitest.toml 
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Process type: unknown
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Process pid: 2020
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Crash dump filename: /tmp/tmph2eijw4c.mozrunner/minidumps/2790e4e1-de39-ea0e-216d-b80067e7c316.dmp
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Operating system: Linux
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO -                   4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - CPU: amd64
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO -      family 6 model 85 stepping 7
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO -      4 CPUs
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - 
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Crash reason:  SIGABRT
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Crash address: 0x0000000000000000
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Crashing instruction: `cmp rax, -0x1000`
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - No memory accessed by instruction
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Process uptime: not available
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - 
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO - Thread 0 Isolated Web Co (crashed)
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO -  0  libc.so.6 + 0x114cf9
[task 2024-07-08T10:42:58.122Z] 10:42:58     INFO -      rax = 0xfffffffffffffffc    rdx = 0x00000000ffffffff
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rcx = 0x00007fbc98997cf9    rbx = 0x00007fbc757e99e0
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsi = 0x0000000000000002    rdi = 0x00007fbc757e99e0
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rbp = 0x0000000000000002    rsp = 0x00007ffda87e06c0
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -       r8 = 0x0000000000000000     r9 = 0x0000000000000000
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r10 = 0x000000000000001e    r11 = 0x0000000000000293
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r12 = 0x00000000ffffffff    r13 = 0x00000000ffffffff
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r14 = 0x00007fbc757e99e0    r15 = 0x0000000000000000
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rip = 0x00007fbc98997cf9
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: given as instruction pointer in context
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  1  libxul.so!PollWrapper(_GPollFD*, unsigned int, int) [nsAppShell.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 81 + 0xc]
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e06f0    rip = 0x00007fbc81c1096e
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  1  libxul.so!PollWrapper(_GPollFD*, unsigned int, int) [nsAppShell.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 81 + 0xc]
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e06f0    rip = 0x00007fbc81c1096e
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  2  libglib-2.0.so.0 + 0x4c6e8
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rbx = 0x00007fbc702172f0    rbp = 0x0000000000000002
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0740    r12 = 0x00007fbc757e99e0
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r13 = 0x00000000ffffffff    r14 = 0x00007fbc81c108a0
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r15 = 0x0000000000000002    rip = 0x00007fbc917de6e9
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  3  libglib-2.0.so.0 + 0x4c7fb
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e07a0    rip = 0x00007fbc917de7fc
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  4  libxul.so!nsAppShell::ProcessNextNativeEvent(bool) + 0x22
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e07c0    rip = 0x00007fbc81c10f33
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  5  libxul.so!DoProcessNextNativeEvent [nsBaseAppShell.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 131]
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  6  libxul.so!nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool) [nsBaseAppShell.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 267 + 0x30]
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e07d0    rip = 0x00007fbc81af5f4c
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  7  libxul.so!{virtual override thunk({offset(-8)}, nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool))} + 0x9
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rbx = 0x00007fbc98661240    rbp = 0x0000000000000001
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0830    r12 = 0x0000000000000001
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r13 = 0x0000000000000001    r14 = 0x00007ffda87e0960
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r15 = 0x00007fbc9863f408    rip = 0x00007fbc81af617a
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -  8  libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 1119 + 0x23]
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rbx = 0x00007fbc98661240    rbp = 0x0000000000000001
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0840    r12 = 0x0000000000000001
[task 2024-07-08T10:42:58.123Z] 10:42:58     INFO -      r13 = 0x0000000000000001    r14 = 0x00007ffda87e0960
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r15 = 0x00007fbc9863f408    rip = 0x00007fbc7b04a1bf
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -  9  libxul.so!NS_ProcessNextEvent [nsThreadUtils.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 480]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 10  libxul.so!SpinEventLoopUntil<(mozilla::ProcessFailureBehavior)1, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:699:61)> [SpinEventLoopUntil.h:d17c727b8dd28811d11817390883cbb554eb2347 : 176]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 11  libxul.so!nsThreadManager::SpinEventLoopUntilInternal(nsTSubstring<char> const&, nsINestedEventLoopCondition*, mozilla::ShutdownPhase) [nsThreadManager.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 699 + 0x368]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rbx = 0x00007fbc6f11c080    rbp = 0x0000000000000000
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0960    r12 = 0x00007ffda87e0960
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r15 = 0x00007fbc98661240    rip = 0x00007fbc7b050a22
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 12  libxul.so!NS_InvokeByIndex + 0x8d
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rbx = 0x00007fbc76368e20    rbp = 0x00007ffda87e0b00
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0ae0    r12 = 0x00007fbc89bb7d00
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r13 = 0x000000000000000a    r14 = 0x0000000000000002
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r15 = 0x0000000000000000    rip = 0x00007fbc7b088b82
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: call frame info
ul.so!Call [XPCWrappedNative.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 1174]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 15  libxul.so!XPCWrappedNative::CallMethod(XPCCallContext&, XPCWrappedNative::CallMode) [XPCWrappedNative.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 1120 + 0x1e6c]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rbx = 0x00007fbc76368e20    rbp = 0x00007fbc89eb2cc0
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0b10    r12 = 0x0000000000000002
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r13 = 0x00007fbc76368d30    r14 = 0x0000000000000002
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r15 = 0x0000000000000000    rip = 0x00007fbc7c01e3ff
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 16  libxul.so!XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) [XPCWrappedNativeJSOps.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 966 + 0x7]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rbx = 0x00007fbc6f16fc38    rbp = 0x000016d27c6478d0
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0d30    r12 = 0x00007fbc873a8e18
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r13 = 0x000016d27c6478a8    r14 = 0x00007ffda87e0d68
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r15 = 0x00007ffda87e0d48    rip = 0x00007fbc7c02137b
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 17  libxul.so!CallJSNative [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 491]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 18  libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 585 + 0x18c]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rbx = 0x0000000000000000    rbp = 0x00007ffda87e0f10
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0e50    r12 = 0x00003ef5476eeb50
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r13 = 0x00007fbc7040f800    r14 = 0x00007ffda87e12d0
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -      r15 = 0x00007fbc70434100    rip = 0x00007fbc833175c7
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO - 19  libxul.so!InternalCall [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 652]
[task 2024-07-08T10:42:58.124Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 20  libxul.so!CallFromStack [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 657]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 21  libxul.so!js::Interpret(JSContext*, js::RunState&) [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 3394 + 0x6]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x00000000000029da    rbp = 0x0000000000000077
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rsp = 0x00007ffda87e0f20    r12 = 0xfffe000000000000
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r13 = 0x00007fbc88feef90    r14 = 0x0000000000000000
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r15 = 0x00007fbc6fc690a8    rip = 0x00007fbc833327ae
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 22  libxul.so!MaybeEnterInterpreterTrampoline [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 405]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 23  libxul.so!js::RunScript(JSContext*, js::RunState&) [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 463 + 0x268]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x00007fbc70434100    rbp = 0x00007ffda87e1400
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1350    r12 = 0x0000000000000000
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r13 = 0x00007ffda87e1488    r14 = 0x00007ffda87e1470
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r15 = 0x0000005449bc3cb6    rip = 0x00007fbc83316550
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 24  libxul.so!js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 617 + 0xc]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x0000000000000000    rbp = 0x00007ffda87e14d0
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1410    r12 = 0x00007fbc7040f800
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r13 = 0x00000000000041fc    r14 = 0x00007ffda87e1590
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r15 = 0x00007fbc70434100    rip = 0x00007fbc833179de
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 25  libxul.so!InternalCall [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 652]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 26  libxul.so!js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) [Interpreter.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 684 + 0xb]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x0000000000002dbb    rbp = 0x0000000000000000
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rsp = 0x00007ffda87e14e0    r12 = 0x0000000000001dc1
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r13 = 0x00000000000034f5    r14 = 0x00007ffda87e1590
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r15 = 0x00007ffda87e1718    rip = 0x00007fbc83318de9
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 27  libxul.so!JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) [CallAndConstruct.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 55 + 0x1e]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x00007ffda87e1718    rbp = 0x00007ffda87e16f0
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1560    r12 = 0x00007ffda87e16f0
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r13 = 0x0000000000000003    r14 = 0x00007fbc70434100
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r15 = 0x00007ffda87e15a0    rip = 0x00007fbc83455de8
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 28  libxul.so!nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) [XPCWrappedJSClass.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 918 + 0x1f]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x00007ffda87e1b38    rbp = 0x00007ffda87e16f0
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1650    r12 = 0x00007fbc76392927
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r13 = 0x00007fbc7021a000    r14 = 0x00007fbc7637a850
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      r15 = 0x00007ffda87e1980    rip = 0x00007fbc7c014b3b
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO - 29  libxul.so!PrepareAndDispatch [xptcstubs_x86_64_linux.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 115 + 0x27]
[task 2024-07-08T10:42:58.125Z] 10:42:58     INFO -      rbx = 0x000000000000001e    rbp = 0x000000000000000f
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1a10    r12 = 0x00007ffda87e1c50
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r13 = 0x0000000000000003    r14 = 0x000000000000002c
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r15 = 0x00000000000000ff    rip = 0x00007fbc7b08a163
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO - 30  libxul.so!SharedStub + 0x5a
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rbx = 0x0000000000000000    rbp = 0x00007ffda87e1c40
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1bd0    r12 = 0x0000000000000000
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r13 = 0x00007ffda87e1cb0    r14 = 0x00007fbc75f67b4b
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r15 = 0x0000000000000000    rip = 0x00007fbc7b088f8f
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO - 31  libxul.so!nsObserverList::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverList.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 71 + 0xe]
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rdx = 0x199ea7b500000010    rcx = 0x00007fbc6f15f200
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rbx = 0x0000000000000000    rsi = 0xe5e5e5e5006e776f
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rdi = 0x0000000000000000    rbp = 0x00007ffda87e1ce8
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1c50     r8 = 0xffffffffff000000
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -       r9 = 0xffffffffffffffff    r12 = 0x0000000000000000
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r13 = 0x00007ffda87e1cb0    r14 = 0x00007fbc75f67b4b
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r15 = 0x0000000000000000    rip = 0x00007fbc7af5bf5b
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO - 32  libxul.so!nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) [nsObserverService.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 289 + 0x18]
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rbx = 0x00007ffda87e1c98    rbp = 0x00007ffda87e1ce8
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1c90    r12 = 0x00007fbc986b78f8
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r13 = 0x00007ffda87e1cb0    r14 = 0x00007fbc75f67b4b
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r15 = 0x0000000000000000    rip = 0x00007fbc7af5e1c4
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO - 33  libxul.so!mozilla::AppShutdown::AdvanceShutdownPhaseInternal(mozilla::ShutdownPhase, bool, char16_t const*, nsCOMPtr<nsISupports> const&) [AppShutdown.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 433 + 0xe]
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rbx = 0x00007fbc98661240    rbp = 0x0000000000000007
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1da0    r12 = 0x00007fbc75f67b4b
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r13 = 0x00007ffda87e1e00    r14 = 0x0000000000000000
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r15 = 0x00007fbc986b78d0    rip = 0x00007fbc7aea17ba
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO - 34  libxul.so!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 614 + 0xb]
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rbx = 0x0000000000000000    rbp = 0x00000000986bfd01
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1df0    r12 = 0x00007fbc986bf900
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r13 = 0x00007ffda87e3260    r14 = 0x00007fbc98661240
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      r15 = 0x0000000000000013    rip = 0x00007fbc7b0a1347
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO - 35  libxul.so!XRE_InitChildProcess(int, char**, XREChildData const*) [nsEmbedFunctions.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 653 + 0x5]
[task 2024-07-08T10:42:58.126Z] 10:42:58     INFO -      rbx = 0x00000000986fa801    rbp = 0x00000000986bfd01
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e1e80    r12 = 0x00007fbc986bf900
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      r13 = 0x00007ffda87e3260    r14 = 0x00007ffda87e3268
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      r15 = 0x0000000000000013    rip = 0x00007fbc831304c1
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 36  firefox-bin!content_process_main [plugin-container.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 57]
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: inlining
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 37  firefox-bin!main [nsBrowserApp.cpp:d17c727b8dd28811d11817390883cbb554eb2347 : 378 + 0x21]
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rbx = 0x0000000000000016    rbp = 0x0000000000000017
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e2130    r12 = 0x00007ffda87e3268
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      r13 = 0x00007ffda87e3260    r14 = 0x00007ffda87e3268
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      r15 = 0x00007fbc986036d0    rip = 0x0000565387b29629
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 38  libc.so.6 + 0x21b96
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rbx = 0x0000000000000000    rbp = 0x0000565387c2c620
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e3190    r12 = 0x0000565387b28d90
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      r13 = 0x00007ffda87e3260    r14 = 0x0000000000000000
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      r15 = 0x0000000000000000    rip = 0x00007fbc988a4b97
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: call frame info
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 39  ld-linux-x86-64.so.2 + 0x10782
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e3210    rip = 0x00007fbc999e6783
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 40  libdl.so.2 + 0x202d7f
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e3218    rip = 0x00007fbc999d4d80
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 41  libpthread.so.0 + 0x219baf
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e3220    rip = 0x00007fbc98e8dbb0
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 42  firefox-bin!_start + 0x28
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -      rsp = 0x00007ffda87e3250    rip = 0x0000565387b28db9
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO -     Found by: stack scanning
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - 
[task 2024-07-08T10:42:58.127Z] 10:42:58     INFO - Thread 1 IPC I/O Child

Group: core-security

Jens believed this can be duped of something else...

Flags: needinfo?(jstutte)

So from the SIGABRT and the earlier "Process 2020 hanging at shutdown; attempting crash report" message we see that this is an instance of bug 1279293 happening in our CI.

The stack trace of the content process shows us, that we are there in ShutdownPhase::XPCOMWillShutdown and that notifies a JS observer that in turn spins an event loop. That could just be the JS async shutdown handling for that phase (I see only one other JS observer for "xpcom-will-shutdown" in DownloadIntegration.sys.mjs). So we are waiting probably for one out of these when the parent process decides to kill us.

It seems as if we did not yet dupe any such intermittent to bug 1279293, so not sure if we should. However we have plenty of similar intermittent bugs, we should probably start to dupe them somewhere.

Flags: needinfo?(jstutte)
See Also: → IPCError_ShutDownKill

S3 because we should not ignore these.

Severity: -- → S3
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.