Closed Bug 1678590 Opened 5 years ago Closed 5 years ago

Intermittent browser/base/content/test/about/browser_aboutCertError_mitm.js | Uncaught exception - Loads the MitM error page. - threw exception: AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved

Categories

(Firefox :: Security, defect, P5)

defect

Tracking

()

RESOLVED FIXED
Future
Tracking Status
firefox-esr78 --- unaffected
firefox84 --- unaffected
firefox85 --- wontfix
firefox86 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: johannh)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered][stockwell disabled])

Attachments

(2 files)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=322419549&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DqlWSSoYQnmYw7jQXNTIIg/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DqlWSSoYQnmYw7jQXNTIIg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2020-11-20T11:55:18.627Z] 11:55:18     INFO - TEST-START | browser/base/content/test/about/browser_aboutCertError_mitm.js
[task 2020-11-20T11:55:18.926Z] 11:55:18     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x10fa0e400 == 2 [pid = 1459] [id = 4]
[task 2020-11-20T11:55:18.926Z] 11:55:18     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x10f021c80) [pid = 1459] [serial = 13] [outer = 0x0]
[task 2020-11-20T11:55:18.926Z] 11:55:18     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x10fa17000) [pid = 1459] [serial = 14] [outer = 0x10f021c80]
[task 2020-11-20T11:55:19.832Z] 11:55:19     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (0x123f11740) [pid = 1456] [serial = 13] [outer = 0x0] [url = about:blank]
[task 2020-11-20T11:55:20.043Z] 11:55:20     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x1237d1400) [pid = 1459] [serial = 15] [outer = 0x10f021c80]
[task 2020-11-20T11:55:20.575Z] 11:55:20     INFO - GECKO(1455) | [Child 1459, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-20T11:55:20.575Z] 11:55:20     INFO - GECKO(1455) | JavaScript error: resource://testing-common/ContentEventListenerChild.jsm, line 138: InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment
[task 2020-11-20T11:55:21.727Z] 11:55:21     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x125424c00) [pid = 1459] [serial = 16] [outer = 0x10f021c80]
[task 2020-11-20T11:55:22.153Z] 11:55:22     INFO - GECKO(1455) | [Child 1459, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-20T11:55:22.153Z] 11:55:22     INFO - GECKO(1455) | JavaScript error: resource://testing-common/ContentEventListenerChild.jsm, line 138: InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment
[task 2020-11-20T11:55:22.568Z] 11:55:22     INFO - GECKO(1455) | [Child 1459, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1321
[task 2020-11-20T11:55:22.826Z] 11:55:22     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x123872c00 == 3 [pid = 1459] [id = 5]
[task 2020-11-20T11:55:22.826Z] 11:55:22     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x1254da200) [pid = 1459] [serial = 17] [outer = 0x0]
[task 2020-11-20T11:55:22.826Z] 11:55:22     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 8 (0x123876000) [pid = 1459] [serial = 18] [outer = 0x1254da200]
[task 2020-11-20T11:55:23.955Z] 11:55:23     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (0x125794c00) [pid = 1459] [serial = 19] [outer = 0x1254da200]
[task 2020-11-20T11:55:24.201Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (0x125db9800) [pid = 1456] [serial = 21] [outer = 0x0] [url = about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20]
[task 2020-11-20T11:55:24.201Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (0x125c04400) [pid = 1456] [serial = 20] [outer = 0x0] [url = about:blank]
[task 2020-11-20T11:55:24.201Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (0x123f76400) [pid = 1456] [serial = 17] [outer = 0x0] [url = about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20]
[task 2020-11-20T11:55:24.201Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (0x125db4400) [pid = 1456] [serial = 14] [outer = 0x0] [url = about:blank]
[task 2020-11-20T11:55:24.311Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x11d417400 == 2 [pid = 1456] [id = 4] [url = https://untrusted.example.com/]
[task 2020-11-20T11:55:24.311Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x123f74400 == 1 [pid = 1456] [id = 5] [url = https://untrusted.example.com/]
[task 2020-11-20T11:55:24.311Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x125cfb200) [pid = 1456] [serial = 19] [outer = 0x0] [url = about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20]
[task 2020-11-20T11:55:24.311Z] 11:55:24     INFO - GECKO(1455) | [Child 1456: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x11ca21e40) [pid = 1456] [serial = 15] [outer = 0x0] [url = about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20]
[task 2020-11-20T11:55:24.444Z] 11:55:24     INFO - GECKO(1455) | [Child 1459, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2020-11-20T11:55:24.444Z] 11:55:24     INFO - GECKO(1455) | JavaScript error: resource://testing-common/ContentEventListenerChild.jsm, line 138: InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment
[task 2020-11-20T11:55:25.955Z] 11:55:25     INFO - GECKO(1455) | [Child 1459: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (0x12578e400) [pid = 1459] [serial = 20] [outer = 0x1254da200]
[task 2020-11-20T11:55:26.141Z] 11:55:26     INFO - TEST-INFO | started process screencapture
[task 2020-11-20T11:55:26.225Z] 11:55:26     INFO - TEST-INFO | screencapture: exit 0
[task 2020-11-20T11:55:26.225Z] 11:55:26     INFO - Buffered messages logged at 11:55:18
[task 2020-11-20T11:55:26.225Z] 11:55:26     INFO - Entering test bound checkMitmPriming
[task 2020-11-20T11:55:26.225Z] 11:55:26     INFO - Buffered messages logged at 11:55:20
[task 2020-11-20T11:55:26.225Z] 11:55:26     INFO - Console message: [JavaScript Warning: "Layout was forced before the page was fully loaded. If stylesheets are not yet loaded this may cause a flash of unstyled content." {file: "chrome://browser/content/aboutNetError.js" line: 617}]
[task 2020-11-20T11:55:26.226Z] 11:55:26     INFO - Console message: [JavaScript Error: "InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment" {file: "resource://testing-common/ContentEventListenerChild.jsm" line: 138}]
[task 2020-11-20T11:55:26.226Z] 11:55:26     INFO - listener@resource://testing-common/ContentEventListenerChild.jsm:138:20
[task 2020-11-20T11:55:26.226Z] 11:55:26     INFO - @chrome://browser/content/aboutNetError.js:1305:10
[task 2020-11-20T11:55:26.226Z] 11:55:26     INFO - 
[task 2020-11-20T11:55:26.226Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-trust-unknown-issuer-intro" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.226Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-trust-unknown-issuer" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.227Z] 11:55:26     INFO - Buffered messages logged at 11:55:22
[task 2020-11-20T11:55:26.227Z] 11:55:26     INFO - Console message: [JavaScript Warning: "Layout was forced before the page was fully loaded. If stylesheets are not yet loaded this may cause a flash of unstyled content." {file: "chrome://browser/content/aboutNetError.js" line: 617}]
[task 2020-11-20T11:55:26.227Z] 11:55:26     INFO - Console message: [JavaScript Error: "InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment" {file: "resource://testing-common/ContentEventListenerChild.jsm" line: 138}]
[task 2020-11-20T11:55:26.227Z] 11:55:26     INFO - listener@resource://testing-common/ContentEventListenerChild.jsm:138:20
[task 2020-11-20T11:55:26.227Z] 11:55:26     INFO - @chrome://browser/content/aboutNetError.js:1305:10
[task 2020-11-20T11:55:26.227Z] 11:55:26     INFO - 
[task 2020-11-20T11:55:26.228Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-mitm-intro" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.228Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-mitm-mozilla" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.228Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-mitm-connection" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.228Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | MitM error page has loaded. - "MOZILLA_PKIX_ERROR_MITM_DETECTED" == "MOZILLA_PKIX_ERROR_MITM_DETECTED" - 
[task 2020-11-20T11:55:26.228Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | Successfully loaded the MitM error page. - 
[task 2020-11-20T11:55:26.229Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | Stored the correct issuer - 
[task 2020-11-20T11:55:26.229Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | Potential man in the middle is displayed - true == true - 
[task 2020-11-20T11:55:26.229Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | Shows the name of the issuer. - "Unknown CA" == "Unknown CA" - 
[task 2020-11-20T11:55:26.229Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | Potential man in the middle is displayed - true == true - 
[task 2020-11-20T11:55:26.231Z] 11:55:26     INFO - TEST-PASS | browser/base/content/test/about/browser_aboutCertError_mitm.js | Shows the name of the issuer. - "Unknown CA" == "Unknown CA" - 
[task 2020-11-20T11:55:26.231Z] 11:55:26     INFO - Leaving test bound checkMitmPriming
[task 2020-11-20T11:55:26.231Z] 11:55:26     INFO - Entering test bound checkMitmAutoEnableEnterpriseRoots
[task 2020-11-20T11:55:26.231Z] 11:55:26     INFO - Buffered messages logged at 11:55:24
[task 2020-11-20T11:55:26.231Z] 11:55:26     INFO - Console message: [JavaScript Warning: "Layout was forced before the page was fully loaded. If stylesheets are not yet loaded this may cause a flash of unstyled content." {file: "chrome://browser/content/aboutNetError.js" line: 617}]
[task 2020-11-20T11:55:26.233Z] 11:55:26     INFO - Console message: [JavaScript Error: "InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment" {file: "resource://testing-common/ContentEventListenerChild.jsm" line: 138}]
[task 2020-11-20T11:55:26.233Z] 11:55:26     INFO - listener@resource://testing-common/ContentEventListenerChild.jsm:138:20
[task 2020-11-20T11:55:26.233Z] 11:55:26     INFO - @chrome://browser/content/aboutNetError.js:1305:10
[task 2020-11-20T11:55:26.233Z] 11:55:26     INFO - 
[task 2020-11-20T11:55:26.233Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-trust-unknown-issuer-intro" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.234Z] 11:55:26     INFO - Console message: [JavaScript Warning: "[fluent-dom] While translating an element with fluent ID "cert-error-trust-unknown-issuer" a child element of type "a" was removed. Either the fluent message does not contain markup, or it does not contain markup of this type." {file: "about:certerror?e=nssBadCert&u=https%3A//untrusted.example.com/&c=UTF-8&d=%20" line: 0}]
[task 2020-11-20T11:55:26.234Z] 11:55:26     INFO - Buffered messages finished
[task 2020-11-20T11:55:26.234Z] 11:55:26     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/about/browser_aboutCertError_mitm.js | Uncaught exception - Loads the MitM error page. - threw exception: AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2020-11-20T11:55:26.235Z] 11:55:26     INFO - Leaving test bound checkMitmAutoEnableEnterpriseRoots
[task 2020-11-20T11:55:26.293Z] 11:55:26     INFO - GECKO(1455) | MEMORY STAT | vsize 7693MB | residentFast 308MB | heapAllocated 105MB
[task 2020-11-20T11:55:26.293Z] 11:55:26     INFO - TEST-OK | browser/base/content/test/about/browser_aboutCertError_mitm.js | took 7673ms
[task 2020-11-20T11:55:26.294Z] 11:55:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-11-20T11:55:26.294Z] 11:55:26     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/about/browser_aboutCertError_mitm.js | Found an unexpected tab at the end of test run: https://untrusted.example.com/ - 
[task 2020-11-20T11:55:26.400Z] 11:55:26     INFO - GECKO(1455) | [1484, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2020-11-20T11:55:26.400Z] 11:55:26     INFO - GECKO(1455) | [1484, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2020-11-20T11:55:26.400Z] 11:55:26     INFO - GECKO(1455) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/rg/6n5lsfx928n3jmd_70900br8000017/T/tmpKzrnth.mozrunner/runtests_leaks_tab_pid1484.log
[task 2020-11-20T11:55:26.400Z] 11:55:26     INFO - GECKO(1455) | [1484, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2020-11-20T11:55:26.400Z] 11:55:26     INFO - GECKO(1455) | [1484, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:202
[task 2020-11-20T11:55:26.406Z] 11:55:26     INFO - GECKO(1455) | [1484, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2020-11-20T11:55:26.459Z] 11:55:26     INFO - checking window state```

I think I forgot to update the second test in that file :facepalm:

Let's hope that's what's causing these...

Assignee: nobody → jhofmann
Status: NEW → ASSIGNED
Summary: Intermittent TV browser/base/content/test/about/browser_aboutCertError_mitm.js | Uncaught exception - Loads the MitM error page. - threw exception: AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved → Intermittent browser/base/content/test/about/browser_aboutCertError_mitm.js | Uncaught exception - Loads the MitM error page. - threw exception: AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
Whiteboard: [retriggered]
Whiteboard: [retriggered] → [retriggered][stockwell needswork:owner]
Flags: needinfo?(jhofmann)
Keywords: leave-open
Whiteboard: [retriggered][stockwell needswork:owner] → [retriggered][stockwell disabled]
Pushed by rmaries@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/d1b325527676 Disabled browser_aboutCertError_mitm.js on Windows and Linux. r=intermittent-reviewers,jmaher DONTBUILD
Pushed by jhofmann@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/e2e762cd9d86 Fix all racy checks in browser_aboutCertError_mitm.js. r=prathiksha
Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Flags: needinfo?(jhofmann)
Keywords: leave-open
Resolution: --- → FIXED
Target Milestone: --- → Future
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: