1819903 - Intermittent TSAN dom/webauthn/tests/browser/browser_abort_visibility.js | application terminated with exit code -6 | after ERROR: ThreadSanitizer failed to allocate 0xffad43bbe000 (281119630811136) bytes at address 800625cf000 (errno: 12)

Status: RESOLVED DUPLICATE of bug 1770595

(Core :: Sanitizers, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-03-02T12:43:13.058Z] 12:43:13     INFO - TEST-START | dom/webauthn/tests/browser/browser_abort_visibility.js
[task 2023-03-02T12:43:14.019Z] 12:43:14     INFO - GECKO(5495) | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-03-02T12:43:15.629Z] 12:43:15     INFO - GECKO(5495) | [ERROR authenticator::ctap2_capi] Error when receiving status update: RecvError
[task 2023-03-02T12:43:16.436Z] 12:43:16     INFO - GECKO(5495) | [ERROR authenticator::ctap2_capi] Error when receiving status update: RecvError
[task 2023-03-02T12:43:17.370Z] 12:43:17     INFO - GECKO(5495) | ==5495==ERROR: ThreadSanitizer failed to allocate 0xffad43bbe000 (281119630811136) bytes at address 800625cf000 (errno: 12)
[task 2023-03-02T12:43:17.412Z] 12:43:17     INFO - GECKO(5495) | Exiting due to channel error.
[task 2023-03-02T12:43:17.415Z] 12:43:17     INFO - GECKO(5495) | Exiting due to channel error.
[task 2023-03-02T12:43:17.416Z] 12:43:17     INFO - GECKO(5495) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=3.06892) Exiting due to channel error.
[task 2023-03-02T12:43:17.417Z] 12:43:17     INFO - GECKO(5495) | Exiting due to channel error.
[task 2023-03-02T12:43:17.417Z] 12:43:17     INFO - GECKO(5495) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=3.64004) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=1.58452) Exiting due to channel error.
[task 2023-03-02T12:43:17.418Z] 12:43:17     INFO - GECKO(5495) | Exiting due to channel error.
[task 2023-03-02T12:43:17.419Z] 12:43:17     INFO - GECKO(5495) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=8.13116) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=14.3658) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=13.6941) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2023-03-02T12:43:17.419Z] 12:43:17     INFO - GECKO(5495) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=6.49163) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2023-03-02T12:43:17.420Z] 12:43:17     INFO - GECKO(5495) | Exiting due to channel error.
[task 2023-03-02T12:43:17.420Z] 12:43:17     INFO - GECKO(5495) | Exiting due to channel error.
[task 2023-03-02T12:43:18.448Z] 12:43:18     INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2023-03-02T12:43:18.448Z] 12:43:18     INFO - Buffered messages logged at 12:43:13
[task 2023-03-02T12:43:18.449Z] 12:43:18     INFO - Entering test bound test_setup
[task 2023-03-02T12:43:18.449Z] 12:43:18     INFO - Leaving test bound test_setup
[task 2023-03-02T12:43:18.449Z] 12:43:18     INFO - Entering test bound test_switch_tab
[task 2023-03-02T12:43:18.450Z] 12:43:18     INFO - Buffered messages logged at 12:43:14
[task 2023-03-02T12:43:18.450Z] 12:43:18     INFO - Console message: [JavaScript Error: "Error: Can't find profile directory." {file: "resource://gre/modules/XULStore.jsm" line: 58}]
[task 2023-03-02T12:43:18.450Z] 12:43:18     INFO - load@resource://gre/modules/XULStore.jsm:58:15
[task 2023-03-02T12:43:18.450Z] 12:43:18     INFO - XULStore@resource://gre/modules/XULStore.jsm:17:10
[task 2023-03-02T12:43:18.450Z] 12:43:18     INFO - 
[task 2023-03-02T12:43:18.451Z] 12:43:18     INFO - Attempting to create credential for origin: https://example.com
[task 2023-03-02T12:43:18.451Z] 12:43:18     INFO - visbility state: visible
[task 2023-03-02T12:43:18.451Z] 12:43:18     INFO - active: true
[task 2023-03-02T12:43:18.452Z] 12:43:18     INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request pending - 
[task 2023-03-02T12:43:18.452Z] 12:43:18     INFO - Buffered messages logged at 12:43:15
[task 2023-03-02T12:43:18.453Z] 12:43:18     INFO - visbility state: hidden
[task 2023-03-02T12:43:18.453Z] 12:43:18     INFO - active: false
[task 2023-03-02T12:43:18.453Z] 12:43:18     INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request pending - 
[task 2023-03-02T12:43:18.454Z] 12:43:18     INFO - Attempting to get credential for origin: https://example.com
[task 2023-03-02T12:43:18.455Z] 12:43:18     INFO - expecting aborted, visbility state: hidden
[task 2023-03-02T12:43:18.455Z] 12:43:18     INFO - expecting aborted, active: false
[task 2023-03-02T12:43:18.456Z] 12:43:18     INFO - visbility state: hidden
[task 2023-03-02T12:43:18.456Z] 12:43:18     INFO - active: false
[task 2023-03-02T12:43:18.457Z] 12:43:18     INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request aborted - 
[task 2023-03-02T12:43:18.457Z] 12:43:18     INFO - visbility state: visible
[task 2023-03-02T12:43:18.458Z] 12:43:18     INFO - active: true
[task 2023-03-02T12:43:18.458Z] 12:43:18     INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request pending - 
[task 2023-03-02T12:43:18.459Z] 12:43:18     INFO - Attempting to get credential for origin: https://example.com
[task 2023-03-02T12:43:18.459Z] 12:43:18     INFO - aborted: AbortError: The operation was aborted. 
[task 2023-03-02T12:43:18.460Z] 12:43:18     INFO - expecting aborted, visbility state: visible
[task 2023-03-02T12:43:18.460Z] 12:43:18     INFO - expecting aborted, active: true
[task 2023-03-02T12:43:18.460Z] 12:43:18     INFO - visbility state: visible
[task 2023-03-02T12:43:18.461Z] 12:43:18     INFO - active: true
[task 2023-03-02T12:43:18.461Z] 12:43:18     INFO - TEST-PASS | dom/webauthn/tests/browser/browser_abort_visibility.js | webauthn request aborted - 
[task 2023-03-02T12:43:18.462Z] 12:43:18     INFO - Buffered messages logged at 12:43:16
[task 2023-03-02T12:43:18.462Z] 12:43:18     INFO - Leaving test bound test_switch_tab
[task 2023-03-02T12:43:18.462Z] 12:43:18     INFO - Entering test bound test_new_window_make
[task 2023-03-02T12:43:18.463Z] 12:43:18     INFO - Buffered messages logged at 12:43:17
[task 2023-03-02T12:43:18.463Z] 12:43:18     INFO - Attempting to create credential for origin: https://example.com
[task 2023-03-02T12:43:18.464Z] 12:43:18     INFO - Buffered messages finished
[task 2023-03-02T12:43:18.465Z] 12:43:18    ERROR - TEST-UNEXPECTED-FAIL | dom/webauthn/tests/browser/browser_abort_visibility.js | application terminated with exit code -6
[task 2023-03-02T12:43:18.465Z] 12:43:18     INFO - runtests.py | Application ran for: 0:00:20.242008

Comment 1

[Iulian Moraru]

Hi Daniel! Can you please take a look at this? I think this might be the same issue as in Bug 1770595, or at least it looks that way.
If this is the case, we should probably make that bug more general(now it only covers reftest) and close this as duplicate for that one.
Thank you!

Comment 2

[Iulian Moraru]

Also, please take a look at Bug 1819917. It is a bug filed for a failed wpt test, in the failure log there I found the same lines as here ERROR: ThreadSanitizer failed to allocate 0x<...> and Browser exited with return code -6.

Comment 4

[Daniel Holbert [:dholbert]]

Yes, I think they're all the same issue, and it's not really reftest-harness-specific. Thanks!