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

INFO - TEST-START | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js
[task 2021-03-25T20:47:47.086Z] 20:47:47     INFO - GECKO(4963) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-03-25T20:47:47.264Z] 20:47:47     INFO - GECKO(4963) | console.error: "Tried to send a 'target-destroyed-form' event on an already destroyed actor 'watcher'"
[task 2021-03-25T20:47:47.393Z] 20:47:47     INFO - GECKO(4963) | ###!!! [Child][MessageChannel] Error: (msgtype=0x3A0144,name=PContent::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost
[task 2021-03-25T20:47:48.075Z] 20:47:48     INFO - GECKO(4963) | console.log: "[WORKER] started" "chrome://mochitests/content/browser/devtools/shared/commands/target/tests/test_worker.js#descriptor" ({postMessage:function postMessage() {
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) | }, close:function close() {
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) | }, get name() {
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) | }, set name() {
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) | }, get onmessage() {
[task 2021-03-25T20:47:48.076Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) | }, set onmessage() {
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) | }, get onmessageerror() {
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) | }, set onmessageerror() {
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) |     [native code]
[task 2021-03-25T20:47:48.077Z] 20:47:48     INFO - GECKO(4963) | }})
[task 2021-03-25T20:47:48.137Z] 20:47:48     INFO - GECKO(4963) | console.log: "Request to connect to ProcessDescriptor \"5344\" failed: Error: Connection closed, pending request to server0.conn18.content-process15/console2, type startListeners failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:285:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\nstartListeners@resource://devtools/client/fronts/webconsole.js:424:34\nattachConsole@resource://devtools/client/fronts/targets/target-mixin.js:432:26\nasync*attach@resource://devtools/client/fronts/targets/content-process.js:50:19\ngetTarget/this._targetFrontPromise<@resource://devtools/client/fronts/descriptors/process.js:86:27\nasync*getTarget@resource://devtools/client/fronts/descriptors/process.js:101:7\nlistAllWorkerTargets/listChildWorkers/processWorkers<@resource://devtools/client/fronts/root.js:172:54\nlistChildWorkers@resource://devtools/client/fronts/root.js:167:19\nasync*listAllWorkerTargets@resource://devtools/client/fronts/root.js:186:7\nlistAllWorkers@resource://devtools/client/fronts/root.js:113:35\ngetWorker@resource://devtools/client/fronts/root.js:273:51\nforWorker@resource://devtools/shared/commands/commands-factory.js:76:46\nasync*testWorker@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:226:42\n@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:25:9\nAsync*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1089:34\nTester_execTest@chrome://mochikit/content/browser-test.js:1129:11\nnextTest/<@chrome://mochikit/content/browser-test.js:949:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n"
[task 2021-03-25T20:47:48.137Z] 20:47:48     INFO - TEST-INFO | started process screentopng
[task 2021-03-25T20:47:48.617Z] 20:47:48     INFO - TEST-INFO | screentopng: exit 0
[task 2021-03-25T20:47:48.617Z] 20:47:48     INFO - Buffered messages logged at 20:47:46
[task 2021-03-25T20:47:48.617Z] 20:47:48     INFO - Entering test bound 
[task 2021-03-25T20:47:48.617Z] 20:47:48     INFO - Test TargetCommand against local tab descriptor (via getTab({ tab }))
[task 2021-03-25T20:47:48.617Z] 20:47:48     INFO - Adding a new tab with URL: https://example.org/document-builder.sjs?html=org
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - Buffered messages logged at 20:47:47
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - Tab added and finished loading
[task 2021-03-25T20:47:48.618Z] 20:47:48     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: "https://example.org/document-builder.sjs?html=org" line: 0}]
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | Got a unique target - 
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | The first is the top level one - 
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | the tab target is of frame type - 
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | This is flagged as top level - 
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - Test TargetCommand against remote tab descriptor (via getTab({ outerWindowID }))
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - Adding a new tab with URL: https://example.org/document-builder.sjs?html=org
[task 2021-03-25T20:47:48.618Z] 20:47:48     INFO - Tab added and finished loading
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: "https://example.org/document-builder.sjs?html=org" line: 0}]
[task 2021-03-25T20:47:48.619Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | Got a unique target - 
[task 2021-03-25T20:47:48.619Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | TargetCommand top target is the same as the first target - 
[task 2021-03-25T20:47:48.619Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | the tab target is of frame type - 
[task 2021-03-25T20:47:48.620Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | This is flagged as top level - 
[task 2021-03-25T20:47:48.621Z] 20:47:48     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: "https://example.com/document-builder.sjs?html=org" line: 0}]
[task 2021-03-25T20:47:48.622Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | Without fission, the top target stays the same - 
[task 2021-03-25T20:47:48.623Z] 20:47:48     INFO - Test TargetCommand against parent process descriptor
[task 2021-03-25T20:47:48.624Z] 20:47:48     INFO - Console message: QM_TRY failure (WARNING): 'Unavailable' at dom/cache/FileUtils.cpp:94 failed with result 0x80520008 (NS_ERROR_FILE_ALREADY_EXISTS)
[task 2021-03-25T20:47:48.625Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | We get many targets when debugging the parent process - 
[task 2021-03-25T20:47:48.626Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | The first is the top level one - 
[task 2021-03-25T20:47:48.627Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | the parent process target is of frame type, because it inherits from BrowsingContextTargetActor - 
[task 2021-03-25T20:47:48.628Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | This is flagged as top level - 
[task 2021-03-25T20:47:48.629Z] 20:47:48     INFO - Test TargetCommand against content process descriptor
[task 2021-03-25T20:47:48.630Z] 20:47:48     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,foo" line: 0}]
[task 2021-03-25T20:47:48.633Z] 20:47:48     INFO - Buffered messages logged at 20:47:48
[task 2021-03-25T20:47:48.634Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | Got a unique target - 
[task 2021-03-25T20:47:48.635Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | The first is the top level one - 
[task 2021-03-25T20:47:48.636Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | the content process target is of process type - 
[task 2021-03-25T20:47:48.638Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | This is flagged as top level - 
[task 2021-03-25T20:47:48.639Z] 20:47:48     INFO - Test TargetCommand against worker descriptor
[task 2021-03-25T20:47:48.640Z] 20:47:48     INFO - TEST-PASS | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | Found the worker Debugger ID - 
[task 2021-03-25T20:47:48.641Z] 20:47:48     INFO - Buffered messages finished
[task 2021-03-25T20:47:48.647Z] 20:47:48     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | Uncaught exception - at resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:28 - Error: Can not send request 'listWorkers' because front 'contentProcessTarget' is already destroyed.
[task 2021-03-25T20:47:48.648Z] 20:47:48     INFO - Stack trace:
[task 2021-03-25T20:47:48.649Z] 20:47:48     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:28:15
[task 2021-03-25T20:47:48.650Z] 20:47:48     INFO - listAllWorkerTargets/listChildWorkers/processWorkers<@resource://devtools/client/fronts/root.js:176:40
[task 2021-03-25T20:47:48.651Z] 20:47:48     INFO - Async*listChildWorkers@resource://devtools/client/fronts/root.js:167:19
[task 2021-03-25T20:47:48.652Z] 20:47:48     INFO - async*listAllWorkerTargets@resource://devtools/client/fronts/root.js:186:7
[task 2021-03-25T20:47:48.653Z] 20:47:48     INFO - listAllWorkers@resource://devtools/client/fronts/root.js:113:35
[task 2021-03-25T20:47:48.654Z] 20:47:48     INFO - getWorker@resource://devtools/client/fronts/root.js:273:51
INFO - forWorker@resource://devtools/shared/commands/commands-factory.js:76:46
[task 2021-03-25T20:47:48.655Z] 20:47:48     INFO - async*testWorker@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:226:42
[task 2021-03-25T20:47:48.656Z] 20:47:48     INFO - @chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:25:9
[task 2021-03-25T20:47:48.657Z] 20:47:48     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1089:34
[task 2021-03-25T20:47:48.658Z] 20:47:48     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1129:11
[task 2021-03-25T20:47:48.659Z] 20:47:48     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:949:14
[task 2021-03-25T20:47:48.660Z] 20:47:48     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23
[task 2021-03-25T20:47:48.661Z] 20:47:48     INFO - Leaving test bound 
[task 2021-03-25T20:47:48.662Z] 20:47:48     INFO - GECKO(4963) | ###!!! [Child][MessageChannel] Error: (msgtype=0x3A0144,name=PContent::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost
[task 2021-03-25T20:47:48.663Z] 20:47:48     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-03-25T20:47:48.666Z] 20:47:48     INFO - TEST-UNEXPECTED-FAIL | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-03-25T20:47:48.671Z] 20:47:48     INFO - Stack trace:
[task 2021-03-25T20:47:48.671Z] 20:47:48     INFO - chrome://mochikit/content/browser-test.js:test_ok:1331
[task 2021-03-25T20:47:48.671Z] 20:47:48     INFO - chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:cleanup:319
[task 2021-03-25T20:47:48.671Z] 20:47:48     INFO - GECKO(4963) | console.log: "Request to connect to ProcessDescriptor \"5038\" failed: Error: Connection closed, pending request to server0.conn18.processDescriptor8, type getTarget failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:285:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ngetTarget/this._targetFrontPromise<@resource://devtools/client/fronts/descriptors/process.js:84:40\ngetTarget@resource://devtools/client/fronts/descriptors/process.js:101:7\nlistAllWorkerTargets/listChildWorkers/processWorkers<@resource://devtools/client/fronts/root.js:172:54\nlistChildWorkers@resource://devtools/client/fronts/root.js:167:19\nasync*listAllWorkerTargets@resource://devtools/client/fronts/root.js:186:7\nlistAllWorkers@resource://devtools/client/fronts/root.js:113:35\ngetWorker@resource://devtools/client/fronts/root.js:273:51\nforWorker@resource://devtools/shared/commands/commands-factory.js:76:46\nasync*testWorker@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:226:42\n@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:25:9\nAsync*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1089:34\nTester_execTest@chrome://mochikit/content/browser-test.js:1129:11\nnextTest/<@chrome://mochikit/content/browser-test.js:949:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n"
[task 2021-03-25T20:47:48.673Z] 20:47:48     INFO - GECKO(4963) | console.log: "Request to connect to ProcessDescriptor \"5114\" failed: Error: Connection closed, pending request to server0.conn18.processDescriptor9, type getTarget failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:285:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ngetTarget/this._targetFrontPromise<@resource://devtools/client/fronts/descriptors/process.js:84:40\ngetTarget@resource://devtools/client/fronts/descriptors/process.js:101:7\nlistAllWorkerTargets/listChildWorkers/processWorkers<@resource://devtools/client/fronts/root.js:172:54\nlistChildWorkers@resource://devtools/client/fronts/root.js:167:19\nasync*listAllWorkerTargets@resource://devtools/client/fronts/root.js:186:7\nlistAllWorkers@resource://devtools/client/fronts/root.js:113:35\ngetWorker@resource://devtools/client/fronts/root.js:273:51\nforWorker@resource://devtools/shared/commands/commands-factory.js:76:46\nasync*testWorker@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:226:42\n@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:25:9\nAsync*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1089:34\nTester_execTest@chrome://mochikit/content/browser-test.js:1129:11\nnextTest/<@chrome://mochikit/content/browser-test.js:949:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n"
[task 2021-03-25T20:47:48.675Z] 20:47:48     INFO - GECKO(4963) | console.log: "Request to connect to ProcessDescriptor \"5237\" failed: Error: Connection closed, pending request to server0.conn18.processDescriptor10, type getTarget failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:285:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ngetTarget/this._targetFrontPromise<@resource://devtools/client/fronts/descriptors/process.js:84:40\ngetTarget@resource://devtools/client/fronts/descriptors/process.js:101:7\nlistAllWorkerTargets/listChildWorkers/processWorkers<@resource://devtools/client/fronts/root.js:172:54\nlistChildWorkers@resource://devtools/client/fronts/root.js:167:19\nasync*listAllWorkerTargets@resource://devtools/client/fronts/root.js:186:7\nlistAllWorkers@resource://devtools/client/fronts/root.js:113:35\ngetWorker@resource://devtools/client/fronts/root.js:273:51\nforWorker@resource://devtools/shared/commands/commands-factory.js:76:46\nasync*testWorker@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:226:42\n@chrome://mochitests/content/browser/devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js:25:9\nAsync*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1089:34\nTester_execTest@chrome://mochikit/content/browser-test.js:1129:11\nnextTest/<@chrome://mochikit/content/browser-test.js:949:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n"
[task 2021-03-25T20:47:48.675Z] 20:47:48     INFO - GECKO(4963) | MEMORY STAT | vsize 2867MB | residentFast 353MB | heapAllocated 123MB
[task 2021-03-25T20:47:48.679Z] 20:47:48     INFO - TEST-OK | devtools/shared/commands/target/tests/browser_target_list_various_descriptors.js | took 1269ms
[task 2021-03-25T20:47:48.679Z] 20:47:48     INFO - checking window state
[task 2021-03-25T20:47:48.680Z] 20:47:48     INFO - GECKO(4963) | console.error: "Tried to send a 'getTarget' method reply on an already destroyed actor 'processDescriptor'"
[task 2021-03-25T20:47:48.680Z] 20:47:48     INFO - GECKO(4963) | console.error: "Tried to send a 'getTarget' method reply on an already destroyed actor 'processDescriptor'"
[task 2021-03-25T20:47:48.681Z] 20:47:48     INFO - GECKO(4963) | console.error: "Tried to send a 'getTarget' method reply on an already destroyed actor 'processDescriptor'"
[task 2021-03-25T20:47:48.681Z] 20:47:48     INFO - TEST-START | devtools/shared/commands/target/tests/browser_target_list_watchTargets.js```