1669692 - Intermittent browser/base/content/test/plugins/browser_pluginnotification.js | Uncaught exception - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved

Status: RESOLVED INCOMPLETE

(Core Graveyard :: Plug-ins, defect, P5)

Description

[Treeherder Bug Filer]

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

---

"INFO - GECKO(2006) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-10-07T11:42:21.593Z] 11:42:21     INFO - TEST-START | browser/base/content/test/plugins/browser_pluginnotification.js
[task 2020-10-07T11:42:21.615Z] 11:42:21     INFO - GECKO(2006) | [Child 2208: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe430d9e800 == 2 [pid = 2208] [id = 16]
[task 2020-10-07T11:42:21.616Z] 11:42:21     INFO - GECKO(2006) | [Child 2208: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fe44d5936f0) [pid = 2208] [serial = 36] [outer = (nil)]
[task 2020-10-07T11:42:21.618Z] 11:42:21     INFO - GECKO(2006) | [Child 2208: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7fe430d9fc00) [pid = 2208] [serial = 37] [outer = 0x7fe44d5936f0]
[task 2020-10-07T11:42:21.675Z] 11:42:21     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: NS_ENSURE_TRUE(GetOwner() && !mCanceled) failed: file /builds/worker/checkouts/gecko/image/imgRequestProxy.cpp:908
[task 2020-10-07T11:42:21.675Z] 11:42:21     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: NS_ENSURE_TRUE(GetOwner() && !mCanceled) failed: file /builds/worker/checkouts/gecko/image/imgRequestProxy.cpp:908
[task 2020-10-07T11:42:21.717Z] 11:42:21     INFO - GECKO(2006) | [Child 3559: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7fd9f90ea400 == 0 [pid = 3559] [id = 0] [url = http://127.0.0.1:8888/browser/browser/base/content/test/plugins/plugin_test.html]
[task 2020-10-07T11:42:21.733Z] 11:42:21     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: NS_ENSURE_TRUE(GetOwner() && !mCanceled) failed: file /builds/worker/checkouts/gecko/image/imgRequestProxy.cpp:908
[task 2020-10-07T11:42:21.733Z] 11:42:21     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: NS_ENSURE_TRUE(GetOwner() && !mCanceled) failed: file /builds/worker/checkouts/gecko/image/imgRequestProxy.cpp:908
[task 2020-10-07T11:42:21.749Z] 11:42:21     INFO - GECKO(2006) | [NPAPI 3676, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4287
[task 2020-10-07T11:42:21.749Z] 11:42:21     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: '!aObserver', file /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:236
...
11:42:24     INFO - TEST-INFO | started process screentopng
[task 2020-10-07T11:42:24.854Z] 11:42:24     INFO - TEST-INFO | screentopng: exit 0
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - Buffered messages logged at 11:42:21
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - Leaving test bound 
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - Wait tab event: load
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - Tab event received: load
[task 2020-10-07T11:42:24.855Z] 11:42:24     INFO - *** loading blocklist: http://127.0.0.1:8888/browser/browser/base/content/test/plugins/blockNoPlugins
[task 2020-10-07T11:42:24.859Z] 11:42:24     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html></html>" line: 0}]
[task 2020-10-07T11:42:24.860Z] 11:42:24     INFO - Loaded http://127.0.0.1:8888/browser/browser/base/content/test/plugins/blockNoPlugins-plugins.json
[task 2020-10-07T11:42:24.860Z] 11:42:24     INFO - *** waiting on local load
[task 2020-10-07T11:42:24.860Z] 11:42:24     INFO - *** waiting on remote load
[task 2020-10-07T11:42:24.861Z] 11:42:24     INFO - *** blocklist loaded.
[task 2020-10-07T11:42:24.863Z] 11:42:24     INFO - Leaving test bound 
[task 2020-10-07T11:42:24.864Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.865Z] 11:42:24     INFO - Wait tab event: load
[task 2020-10-07T11:42:24.866Z] 11:42:24     INFO - Buffered messages logged at 11:42:22
[task 2020-10-07T11:42:24.870Z] 11:42:24     INFO - Tab event received: load
[task 2020-10-07T11:42:24.872Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | Test 1a, plugin fallback type should be PLUGIN_UNSUPPORTED - 
[task 2020-10-07T11:42:24.873Z] 11:42:24     INFO - Leaving test bound 
[task 2020-10-07T11:42:24.876Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.879Z] 11:42:24     INFO - Wait tab event: load
[task 2020-10-07T11:42:24.880Z] 11:42:24     INFO - Tab event received: load
[task 2020-10-07T11:42:24.881Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | click-to-play-plugins notification appeared - 
[task 2020-10-07T11:42:24.882Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | Plugin should not be activated - 
[task 2020-10-07T11:42:24.887Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | Plugin should be activated - 
[task 2020-10-07T11:42:24.889Z] 11:42:24     INFO - Buffered messages logged at 11:42:23
[task 2020-10-07T11:42:24.892Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | Plugin should not be activated - 
[task 2020-10-07T11:42:24.895Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | A plugin notification should be shown. - 
[task 2020-10-07T11:42:24.896Z] 11:42:24     INFO - removing permission:http://127.0.0.1:8888 plugin:libnptest
[task 2020-10-07T11:42:24.897Z] 11:42:24     INFO - 
[task 2020-10-07T11:42:24.898Z] 11:42:24     INFO - Leaving test bound 
[task 2020-10-07T11:42:24.901Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.908Z] 11:42:24     INFO - Wait tab event: load
[task 2020-10-07T11:42:24.910Z] 11:42:24     INFO - Tab event received: load
[task 2020-10-07T11:42:24.912Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | click-to-play-plugins notification appeared - 
[task 2020-10-07T11:42:24.915Z] 11:42:24     INFO - Wait tab event: load
[task 2020-10-07T11:42:24.918Z] 11:42:24     INFO - Tab event received: load
[task 2020-10-07T11:42:24.919Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | Test 11b, Should not have a click-to-play notification - 
[task 2020-10-07T11:42:24.920Z] 11:42:24     INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "data:text/html,<html>hi</html>" line: 0}]
[task 2020-10-07T11:42:24.921Z] 11:42:24     INFO - Buffered messages logged at 11:42:24
[task 2020-10-07T11:42:24.921Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | click-to-play-plugins notification appeared - 
[task 2020-10-07T11:42:24.922Z] 11:42:24     INFO - Leaving test bound 
[task 2020-10-07T11:42:24.923Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.924Z] 11:42:24     INFO - Wait tab event: load
[task 2020-10-07T11:42:24.946Z] 11:42:24     INFO - Tab event received: load
[task 2020-10-07T11:42:24.947Z] 11:42:24     INFO - TEST-PASS | browser/base/content/test/plugins/browser_pluginnotification.js | click-to-play-plugins notification appeared - 
[task 2020-10-07T11:42:24.948Z] 11:42:24     INFO - Buffered messages finished
[task 2020-10-07T11:42:24.949Z] 11:42:24     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/plugins/browser_pluginnotification.js | Uncaught exception - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved
[task 2020-10-07T11:42:24.949Z] 11:42:24     INFO - Leaving test bound 
[task 2020-10-07T11:42:24.950Z] 11:42:24     INFO - Entering test bound 
[task 2020-10-07T11:42:24.951Z] 11:42:24     INFO - Wait tab event: load
...
11:42:29     INFO - GECKO(2006) | MEMORY STAT | vsize 3097MB | residentFast 409MB | heapAllocated 141MB
[task 2020-10-07T11:42:29.398Z] 11:42:29     INFO - TEST-OK | browser/base/content/test/plugins/browser_pluginnotification.js | took 7805ms
[task 2020-10-07T11:42:29.406Z] 11:42:29     INFO - GECKO(2006) | [Child 2208: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fe430d9bc00 == 1 [pid = 2208] [id = 17]
[task 2020-10-07T11:42:29.406Z] 11:42:29     INFO - GECKO(2006) | [Child 2208: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fe44d593180) [pid = 2208] [serial = 39] [outer = (nil)]
[task 2020-10-07T11:42:29.406Z] 11:42:29     INFO - GECKO(2006) | [Child 2208: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7fe430d9dc00) [pid = 2208] [serial = 40] [outer = 0x7fe44d593180]
[task 2020-10-07T11:42:29.433Z] 11:42:29     INFO - checking window state
[task 2020-10-07T11:42:29.449Z] 11:42:29     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: NS_ENSURE_TRUE(GetOwner() && !mCanceled) failed: file /builds/worker/checkouts/gecko/image/imgRequestProxy.cpp:908
[task 2020-10-07T11:42:29.450Z] 11:42:29     INFO - GECKO(2006) | [Parent 2006, Main Thread] WARNING: NS_ENSURE_TRUE(GetOwner() && !mCanceled) failed: file /builds/worker/checkouts/gecko/image/imgRequestProxy.cpp:908
[task 2020-10-07T11:42:29.470Z] 11:42:29     INFO - GECKO(2006) | [Child 3595, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-07T11:42:29.472Z] 11:42:29     INFO - GECKO(2006) | [Child 3595, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-07T11:42:29.472Z] 11:42:29     INFO - GECKO(2006) | [Child 3595, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-07T11:42:29.473Z] 11:42:29     INFO - GECKO(2006) | [Child 3595, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-07T11:42:29.477Z] 11:42:29     INFO - GECKO(2006) | [Child 3595, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:511
[task 2020-10-07T11:42:29.518Z] 11:42:29     INFO - TEST-START | browser/base/content/test/plugins/browser_private_browsing_eme_persistent_state.js
[task 2020-10-07T11:42:29.535Z] 11:42:29     INFO - GECKO(2006) | [Parent 2006: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fb552b07400 == 12 [pid = 2006] [id = 15]"```

Comment 1

[Intermittent Failures Robot]

1 failures in 4130 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1669692&startday=2020-10-05&endday=2020-10-11&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.