1733272 - Intermittent devtools/client/webconsole/test/browser/browser_console_error_source_click.js | Cleanup function threw an exception - at resource://devtools/shared/protocol/Front.js:106 - Error: Connection closed, pending request to server20.conn0.contentPro

Reporter

Description

•

3 years ago

treeherder

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

[task 2021-09-29T21:33:01.332Z] 21:33:01     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_console_error_source_click.js
[task 2021-09-29T21:33:01.415Z] 21:33:01     INFO - GECKO(1531) | [Child 1615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fe11a915000 == 3 [pid = 1615] [id = 14]
[task 2021-09-29T21:33:01.417Z] 21:33:01     INFO - GECKO(1531) | [Child 1615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (7fe11a90a740) [pid = 1615] [serial = 36] [outer = 0]
[task 2021-09-29T21:33:01.419Z] 21:33:01     INFO - GECKO(1531) | [Child 1615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (7fe11a916400) [pid = 1615] [serial = 37] [outer = 7fe11a90a740]
[task 2021-09-29T21:33:01.658Z] 21:33:01     INFO - GECKO(1531) | [Child 1615: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (7fe11b851800) [pid = 1615] [serial = 38] [outer = 7fe11a90a740]
[task 2021-09-29T21:33:02.311Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fe5778ab400 == 7 [pid = 1531] [id = 27]
[task 2021-09-29T21:33:02.314Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (7fe576415740) [pid = 1531] [serial = 59] [outer = 0]
[task 2021-09-29T21:33:02.316Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (7fe5778ab800) [pid = 1531] [serial = 60] [outer = 7fe576415740]
[task 2021-09-29T21:33:02.330Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4240
[task 2021-09-29T21:33:02.334Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1306
[task 2021-09-29T21:33:02.397Z] 21:33:02     INFO - GECKO(1531) | [GLX] window 220022d has VisualID 0x41
[task 2021-09-29T21:33:02.401Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2021-09-29T21:33:02.403Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:988
[task 2021-09-29T21:33:02.403Z] 21:33:02     INFO - GECKO(1531) | [Parent 1531, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2021-09-29T21:33:02.405Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-09-29T21:33:02.662Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-09-29T21:33:02.664Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-09-29T21:33:02.719Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect1", count: 4, kind: F32 } is not found in the shader composite. Expected at 6, found at -1
[task 2021-09-29T21:33:02.719Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect2", count: 4, kind: F32 } is not found in the shader composite. Expected at 7, found at -1
[task 2021-09-29T21:33:02.719Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aColor", count: 4, kind: F32 } is not found in the shader composite. Expected at 3, found at -1
[task 2021-09-29T21:33:02.719Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect1", count: 4, kind: F32 } is not found in the shader composite. Expected at 6, found at -1
[task 2021-09-29T21:33:02.719Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect2", count: 4, kind: F32 } is not found in the shader composite. Expected at 7, found at -1
[task 2021-09-29T21:33:02.791Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-09-29T21:33:02.791Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-09-29T21:33:02.792Z] 21:33:02     INFO - GECKO(1531) | [2021-09-29T21:33:02Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-09-29T21:33:04.028Z] 21:33:04     INFO - GECKO(1531) | [Child 1615: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe11a91a000 == 2 [pid = 1615] [id = 12] [url = data:text/html;charset=utf8,<p>browser_console_devtools_loader_exception.js</p>]
[task 2021-09-29T21:33:04.028Z] 21:33:04     INFO - GECKO(1531) | [Child 1615: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe11a912000 == 1 [pid = 1615] [id = 13] [url = about:blank]
[task 2021-09-29T21:33:05.227Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 8, found at -1
[task 2021-09-29T21:33:05.229Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 10, found at -1
[task 2021-09-29T21:33:05.230Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 11, found at -1
[task 2021-09-29T21:33:05.230Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 12, found at -1
[task 2021-09-29T21:33:05.230Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 13, found at -1
[task 2021-09-29T21:33:05.230Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 14, found at -1
[task 2021-09-29T21:33:05.230Z] 21:33:05     INFO - GECKO(1531) | [2021-09-29T21:33:05Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 15, found at -1
[task 2021-09-29T21:33:05.447Z] 21:33:05     INFO - GECKO(1531) | JavaScript warning: resource://devtools/shared/builtin-modules.js, line 201: debuggee 'resource://devtools/shared/base-loader.js:289' would run
[task 2021-09-29T21:33:05.487Z] 21:33:05     INFO - GECKO(1531) | JavaScript error: data:text/html;charset=utf8,<p>hello world<button onclick='foobar.explode()'>click!</button>, line 1: ReferenceError: foobar is not defined
[task 2021-09-29T21:33:07.201Z] 21:33:07     INFO - GECKO(1531) | [Parent 1531: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fe577777c00 == 6 [pid = 1531] [id = 26] [url = chrome://devtools/content/webconsole/index.html]
[task 2021-09-29T21:33:07.709Z] 21:33:07     INFO - GECKO(1531) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2b1cro8d.mozrunner/runtests_leaks_tab_pid2062.log
[task 2021-09-29T21:33:07.710Z] 21:33:07     INFO - GECKO(1531) | [2062, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-09-29T21:33:07.787Z] 21:33:07     INFO - GECKO(1531) | [Child 2062, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:642
[task 2021-09-29T21:33:08.227Z] 21:33:08     INFO - GECKO(1531) | [Child 1672: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffae4a71000 == 2 [pid = 1672] [id = 10] [url = data:text/html;charset=utf8,]
[task 2021-09-29T21:33:08.227Z] 21:33:08     INFO - GECKO(1531) | [Child 1672: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7ffae4a91c00 == 1 [pid = 1672] [id = 9] [url = about:blank]
[task 2021-09-29T21:33:08.290Z] 21:33:08     INFO - GECKO(1531) | JavaScript warning: resource://devtools/shared/builtin-modules.js, line 201: debuggee 'resource://devtools/shared/base-loader.js:289' would run
[task 2021-09-29T21:33:08.366Z] 21:33:08     INFO - GECKO(1531) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f8531628400 == 1 [pid = 2062] [id = 0]
[task 2021-09-29T21:33:08.368Z] 21:33:08     INFO - GECKO(1531) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f854b4643c0) [pid = 2062] [serial = 1] [outer = 0]
[task 2021-09-29T21:33:08.369Z] 21:33:08     INFO - GECKO(1531) | [Child 2062: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f8531629400) [pid = 2062] [serial = 2] [outer = 7f854b4643c0]
[task 2021-09-29T21:33:10.000Z] 21:33:09     INFO - GECKO(1531) | [Child 1672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (7ffae4af2580) [pid = 1672] [serial = 22] [outer = 0] [url = about:blank]
[task 2021-09-29T21:33:10.002Z] 21:33:10     INFO - GECKO(1531) | [Child 1672: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (7ffae4af2740) [pid = 1672] [serial = 24] [outer = 0] [url = data:text/html;charset=utf8,]
[task 2021-09-29T21:33:10.823Z] 21:33:10     INFO - GECKO(1531) | [Child 2062, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1306
[task 2021-09-29T21:33:11.273Z] 21:33:11     INFO - GECKO(1531) | JavaScript error: resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm, line 347: Error: Trying to destroy a target actor that doesn't exists, or has already been destroyed. Watcher Actor ID:server20.conn0.watcher24
[task 2021-09-29T21:33:11.327Z] 21:33:11     INFO - GECKO(1531) | console.error: "Failed to start [network-event] legacy listener for target null" (new Error("Connection closed, pending request to server20.conn0.contentProcess2062/console2, type startListeners failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:292:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\nstartListeners@resource://devtools/client/fronts/webconsole.js:307:34\nmodule.exports@resource://devtools/shared/commands/resource/legacy-listeners/network-events.js:149:25\nasync*_watchResourcesForTarget@resource://devtools/shared/commands/resource/resource-command.js:1013:42\n_onTargetAvailable@resource://devtools/shared/commands/resource/resource-command.js:441:20\nAsync*_emit@resource://devtools/shared/event-emitter.js:244:34\nemitAsync@resource://devtools/shared/event-emitter.js:194:25\nemitAsync@resource://devtools/shared/event-emitter.js:346:25\n_onTargetAvailable@resource://devtools/shared/commands/target/target-command.js:203:33\nAsync*_emit@resource://devtools/shared/event-emitter.js:244:34\nemit@resource://devtools/shared/event-emitter.js:190:18\nemit@resource://devtools/shared/event-emitter.js:342:18\n_onTargetAvailable@resource://devtools/client/fronts/watcher.js:60:10\n_emit@resource://devtools/shared/event-emitter.js:244:34\nemit@resource://devtools/shared/event-emitter.js:190:18\nemit@resource://devtools/shared/event-emitter.js:342:18\nonPacket@resource://devtools/shared/protocol/Front.js:336:13\nonPacket@resource://devtools/client/devtools-client.js:482:13\nsend/<@resource://devtools/shared/transport/local-transport.js:68:25\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:54:21\nsend@resource://devtools/shared/transport/local-transport.js:56:21\nsend@resource://devtools/server/devtools-server-connection.js:99:20\n_sendEvent@resource://devtools/shared/protocol/Actor.js:72:15\ninitialize/<@resource://devtools/shared/protocol/Actor.js:46:16\n_emit@resource://devtools/shared/event-emitter.js:244:34\nemit@resource://devtools/shared/event-emitter.js:190:18\nemit@resource://devtools/shared/event-emitter.js:342:18\nnotifyTargetAvailable@resource://devtools/server/actors/watcher.js:250:12\nonContentProcessActorCreated@resource://devtools/server/actors/watcher/target-helpers/process-helper.js:77:11\nMessageListener.receiveMessage*maybeRegisterMessageListeners@resource://devtools/server/actors/watcher/target-helpers/process-helper.js:135:19\n", "resource://devtools/shared/protocol/Front.js", 106))
[task 2021-09-29T21:33:11.352Z] 21:33:11     INFO - TEST-INFO | started process screentopng
[task 2021-09-29T21:33:11.799Z] 21:33:11     INFO - TEST-INFO | screentopng: exit 0
[task 2021-09-29T21:33:11.800Z] 21:33:11     INFO - Buffered messages logged at 21:33:01
[task 2021-09-29T21:33:11.801Z] 21:33:11     INFO - Entering test bound 
[task 2021-09-29T21:33:11.802Z] 21:33:11     INFO - Adding a new tab with URL: data:text/html;charset=utf8,<p>hello world<button onclick='foobar.explode()'>click!</button>
[task 2021-09-29T21:33:11.803Z] 21:33:11     INFO - Tab added and finished loading
[task 2021-09-29T21:33:11.804Z] 21:33:11     INFO - Buffered messages logged at 21:33:05
[task 2021-09-29T21:33:11.806Z] 21:33:11     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | browser console opened - 
[task 2021-09-29T21:33:11.806Z] 21:33:11     INFO - generate exception and wait for the message
[task 2021-09-29T21:33:11.806Z] 21:33:11     INFO - Buffered messages logged at 21:33:07
[task 2021-09-29T21:33:11.807Z] 21:33:11     INFO - Console message: [JavaScript Warning: "The Web Console logging API (console.log, console.info, console.warn, console.error) has been disabled by a script on this page."]
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - Console message: [JavaScript Warning: "debuggee 'resource://devtools/shared/base-loader.js:289' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 201}]
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - Console message: [JavaScript Error: "ReferenceError: foobar is not defined" {file: "data:text/html;charset=utf8,<p>hello world<button onclick='foobar.explode()'>click!</button>" line: 1}]
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - onclick@data:text/html;charset=utf8,<p>hello world<button onclick='foobar.explode()'>click!</button>:1:1
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - @chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_console_error_source_click.js:34:12
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - execute@resource://specialpowers/SpecialPowersSandbox.jsm:141:12
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - _spawnTask@resource://specialpowers/SpecialPowersChild.jsm:1656:15
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - receiveMessage@resource://specialpowers/SpecialPowersChild.jsm:310:21
[task 2021-09-29T21:33:11.811Z] 21:33:11     INFO - JSActor query*receiveMessage@resource://specialpowers/SpecialPowersParent.jsm:1241:14
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - JSActor query*spawn@resource://specialpowers/SpecialPowersChild.jsm:1587:17
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - @chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_console_error_source_click.js:31:17
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1091:34
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1131:11
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:939:14
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2021-09-29T21:33:11.812Z] 21:33:11     INFO - 
[task 2021-09-29T21:33:11.814Z] 21:33:11     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | Message found: "ReferenceError: foobar is not defined" - 
[task 2021-09-29T21:33:11.815Z] 21:33:11     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | Message location link element found - 
[task 2021-09-29T21:33:11.815Z] 21:33:11     INFO - Buffered messages logged at 21:33:10
[task 2021-09-29T21:33:11.817Z] 21:33:11     INFO - Console message: [JavaScript Warning: "debuggee 'resource://devtools/shared/base-loader.js:289' would run" {file: "resource://devtools/shared/builtin-modules.js" line: 201}]
[task 2021-09-29T21:33:11.818Z] 21:33:11     INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | The view source tab was opened in response to clicking the link - 
[task 2021-09-29T21:33:11.819Z] 21:33:11     INFO - Leaving test bound 
[task 2021-09-29T21:33:11.819Z] 21:33:11     INFO - Buffered messages logged at 21:33:11
[task 2021-09-29T21:33:11.836Z] 21:33:11     INFO - Clear the browser console output
[task 2021-09-29T21:33:11.837Z] 21:33:11     INFO - Browser console cleared
[task 2021-09-29T21:33:11.838Z] 21:33:11     INFO - Wait for all Browser Console targets to be attached
[task 2021-09-29T21:33:11.840Z] 21:33:11     INFO - Waiting 1 seconds.
[task 2021-09-29T21:33:11.841Z] 21:33:11     INFO - Close the Browser Console
[task 2021-09-29T21:33:11.842Z] 21:33:11     INFO - Buffered messages finished
[task 2021-09-29T21:33:11.857Z] 21:33:11     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | Cleanup function threw an exception - at resource://devtools/shared/protocol/Front.js:106 - Error: Connection closed, pending request to server20.conn0.contentProcess2062/console2, type setPreferences failed
[task 2021-09-29T21:33:11.858Z] 21:33:11     INFO - 
[task 2021-09-29T21:33:11.858Z] 21:33:11     INFO - Request stack:
[task 2021-09-29T21:33:11.859Z] 21:33:11     INFO - request@resource://devtools/shared/protocol/Front.js:292:14
[task 2021-09-29T21:33:11.860Z] 21:33:11     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19
[task 2021-09-29T21:33:11.861Z] 21:33:11     INFO - setSaveRequestAndResponseBodies@resource://devtools/client/webconsole/webconsole-connection-proxy.js:124:33
[task 2021-09-29T21:33:11.861Z] 21:33:11     INFO - connect/connection<@resource://devtools/client/webconsole/webconsole-connection-proxy.js:75:20
[task 2021-09-29T21:33:11.862Z] 21:33:11     INFO - async*connect@resource://devtools/client/webconsole/webconsole-connection-proxy.js:79:7
[task 2021-09-29T21:33:11.863Z] 21:33:11     INFO - _onTargetAvailable@resource://devtools/client/webconsole/webconsole-ui.js:558:17
[task 2021-09-29T21:33:11.863Z] 21:33:11     INFO - _emit@resource://devtools/shared/event-emitter.js:244:34
[task 2021-09-29T21:33:11.864Z] 21:33:11     INFO - emitAsync@resource://devtools/shared/event-emitter.js:194:25
[task 2021-09-29T21:33:11.865Z] 21:33:11     INFO - emitAsync@resource://devtools/shared/event-emitter.js:346:25
...
[task 2021-09-29T21:33:12.243Z] 21:33:12     INFO - @chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_console_error_source_click.js:21:43
[task 2021-09-29T21:33:12.244Z] 21:33:12     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1091:34
[task 2021-09-29T21:33:12.245Z] 21:33:12     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1131:11
[task 2021-09-29T21:33:12.246Z] 21:33:12     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:939:14
[task 2021-09-29T21:33:12.247Z] 21:33:12     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2021-09-29T21:33:12.248Z] 21:33:12     INFO - " {file: "resource://devtools/shared/protocol/Front.js" line: 106}]
[task 2021-09-29T21:33:12.249Z] 21:33:12     INFO - GECKO(1531) | MEMORY STAT | vsize 3811MB | residentFast 682MB | heapAllocated 313MB
[task 2021-09-29T21:33:12.250Z] 21:33:12     INFO - TEST-OK | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | took 10091ms
[task 2021-09-29T21:33:12.251Z] 21:33:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-09-29T21:33:12.252Z] 21:33:12     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | Found an unexpected tab at the end of test run: view-source:data:text/html;charset=utf8,<p>hello world<button onclick='foobar.explode()'>click!</button> - 
[task 2021-09-29T21:33:12.254Z] 21:33:12     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-09-29T21:33:12.254Z] 21:33:12     INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_console_error_source_click.js | Found an unexpected tab at the end of test run: data:text/html;charset=utf8,<p>hello world<button onclick='foobar.explode()'>click!</button> - 
[task 2021-09-29T21:33:12.256Z] 21:33:12     INFO - GECKO(1531) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2b1cro8d.mozrunner/runtests_leaks_tab_pid2088.log
[task 2021-09-29T21:33:12.256Z] 21:33:12     INFO - GECKO(1531) | [2088, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:251
[task 2021-09-29T21:33:12.257Z] 21:33:12     INFO - GECKO(1531) | [Child 2088, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:642
[task 2021-09-29T21:33:12.258Z] 21:33:12     INFO - checking window state
[task 2021-09-29T21:33:12.259Z] 21:33:12     INFO - GECKO(1531) | must wait for focus
[task 2021-09-29T21:33:12.260Z] 21:33:12     INFO - GECKO(1531) | [Child 2088: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7fac95516800 == 1 [pid = 2088] [id = 0]
[task 2021-09-29T21:33:12.261Z] 21:33:12     INFO - GECKO(1531) | [Child 2088: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7facaf4643c0) [pid = 2088] [serial = 1] [outer = 0]
[task 2021-09-29T21:33:12.262Z] 21:33:12     INFO - GECKO(1531) | [Child 2088: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7fac95518000) [pid = 2088] [serial = 2] [outer = 7facaf4643c0]
[task 2021-09-29T21:33:12.544Z] 21:33:12     INFO - TEST-START | devtools/client/webconsole/test/browser/browser_console_filters.js

Cristina Cozmuta (:CrissCozmuta)

Updated

•

3 years ago

Regressed by: 1731792

BMO Automation

Updated

•

3 years ago

Has Regression Range: --- → yes

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

3 years ago

status-firefox92: --- → unaffected

status-firefox93: --- → unaffected

status-firefox94: --- → affected

status-firefox-esr78: --- → unaffected

status-firefox-esr91: --- → unaffected

Flags: needinfo?(nika)

BugBot [:suhaib / :marco/ :calixte]

Updated

•

3 years ago

Keywords: regression

Nika Layzell [:nika] (ni? for response)

Comment 1

•

3 years ago

Redirecting to the devtools team who has graciously offered to look into this issue :-)

Flags: needinfo?(nika) → needinfo?(jdescottes)

Julian Descottes [:jdescottes]

Assignee

Comment 2

•

3 years ago

Thanks, I will take the bug!

Assignee: nobody → jdescottes

Status: NEW → ASSIGNED

Flags: needinfo?(jdescottes)

Julian Descottes [:jdescottes]

Assignee

Comment 3

•

3 years ago

Let's see if a try/catch is enough to silence the intermittent:

Julian Descottes [:jdescottes]

Assignee

Comment 4

•

3 years ago

Attached file Bug 1733272 - [devtools] Fix intermittent errors during browser console destroy — Details

Comment hidden (Intermittent Failures Robot)

Julian Descottes [:jdescottes]

Assignee

Comment 6

•

3 years ago

The root cause of the issue is actually that the webconsole proxy's connect method creates an async method which is then used to build several promises which have no catch handler. I think the most straightforward fix here will be to use safeAsyncMethod to swallow errors when the target of the proxy has been destroyed (a proxy instance has the same lifecycle as the target).

Julian Descottes [:jdescottes]

Assignee

Comment 7

•

3 years ago

New patch seems to fix the issue:

Pulsebot

Comment 8

•

3 years ago

Pushed by jdescottes@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/ebd8d0a44d34
[devtools] Fix intermittent errors during browser console destroy r=nchevobbe

Noemi Erli[:noemi_erli]

Comment 9

•

3 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/ebd8d0a44d34

Status: ASSIGNED → RESOLVED

Closed: 3 years ago

status-firefox95: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → 95 Branch

BugBot [:suhaib / :marco/ :calixte]

Comment 10

•

3 years ago

The patch landed in nightly and beta is affected.
:jdescottes, is this bug important enough to require an uplift?
If not please set status_beta to wontfix.

For more information, please visit auto_nag documentation.

Flags: needinfo?(jdescottes)

Julian Descottes [:jdescottes]

Assignee

Comment 11

•

3 years ago

This mostly failed on debug platforms. If I understand https://treeherder.mozilla.org/jobs?repo=mozilla-beta correctly, we don't run devtools mochitests in debug for beta. So no need to uplift IMO.

status-firefox94: affected → wontfix

Flags: needinfo?(jdescottes)

Comment hidden (Intermittent Failures Robot)