Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=346358596&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ddZ5O7PcRhe5fgukSFPs_Q/runs/0/artifacts/public/logs/live_backing.log
[task 2021-07-26T15:38:04.181Z] 15:38:04 INFO - TEST-START | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js
[task 2021-07-26T15:38:05.335Z] 15:38:05 INFO - GECKO(1559) | console.error: "Task cancelled"
[task 2021-07-26T15:38:06.463Z] 15:38:06 INFO - TEST-INFO | started process screentopng
[task 2021-07-26T15:38:06.776Z] 15:38:06 INFO - TEST-INFO | screentopng: exit 0
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Buffered messages logged at 15:38:04
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Entering test bound
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Initializing a network monitor pane.
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/netmonitor/test/html_cause-test-page.html
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Tab added and finished loading
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Net tab added successfully: http://example.com/browser/devtools/client/netmonitor/test/html_cause-test-page.html
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 75}]
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - Console message: [JavaScript Error: "[Exception... "Favicon at "http://example.com/browser/devtools/client/netmonitor/test/favicon_request" failed to load: Not Found." nsresult: "0x80004005 (NS_ERROR_FAILURE)" location: "JS frame :: resource:///modules/FaviconLoader.jsm :: onStopRequest :: line 253" data: no]"]
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO - onStopRequest@resource:///modules/FaviconLoader.jsm:253:22
[task 2021-07-26T15:38:06.777Z] 15:38:06 INFO -
[task 2021-07-26T15:38:06.778Z] 15:38:06 INFO - Buffered messages logged at 15:38:05
[task 2021-07-26T15:38:06.778Z] 15:38:06 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-26T15:38:06.778Z] 15:38:06 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 511}]
[task 2021-07-26T15:38:06.778Z] 15:38:06 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 75}]
[task 2021-07-26T15:38:06.778Z] 15:38:06 INFO - Console message: [JavaScript Warning: "Relative positioning of table rows and row groups is now supported. This site may need to be updated because it may depend on this feature having no effect." {file: "resource://devtools/client/netmonitor/src/components/request-list/RequestListHeader.js" line: 545}]
[task 2021-07-26T15:38:06.778Z] 15:38:06 INFO - Network monitor pane shown successfully.
[task 2021-07-26T15:38:06.779Z] 15:38:06 INFO - Disabling cache and reloading page.
[task 2021-07-26T15:38:06.780Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/1, PayloadReady: 0/1, EventTimings: 1/0, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent7
[task 2021-07-26T15:38:06.781Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 1/1, PayloadReady: 0/1, EventTimings: 1/1, got NetMonitor:NetworkEvent for server0.conn145.netEvent23
[task 2021-07-26T15:38:06.781Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 1/1, PayloadReady: 1/1, EventTimings: 1/1, got NetMonitor:PayloadReady for server0.conn145.netEvent23
[task 2021-07-26T15:38:06.782Z] 15:38:06 INFO - Got marker: dom-interactive
[task 2021-07-26T15:38:06.783Z] 15:38:06 INFO - Got marker: dom-complete
[task 2021-07-26T15:38:06.784Z] 15:38:06 INFO - Got two timeline markers, done waiting
[task 2021-07-26T15:38:06.785Z] 15:38:06 INFO - Clearing requests in the UI.
[task 2021-07-26T15:38:06.786Z] 15:38:06 INFO - Starting test...
[task 2021-07-26T15:38:06.787Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/8, PayloadReady: 1/8, EventTimings: 0/0, got NetMonitor:PayloadReady for server0.conn145.netEvent42
[task 2021-07-26T15:38:06.788Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/8, PayloadReady: 2/8, EventTimings: 0/0, got NetMonitor:PayloadReady for server0.conn145.netEvent64
[task 2021-07-26T15:38:06.789Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/8, PayloadReady: 3/8, EventTimings: 0/0, got NetMonitor:PayloadReady for server0.conn145.netEvent53
[task 2021-07-26T15:38:06.790Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/8, PayloadReady: 4/8, EventTimings: 0/0, got NetMonitor:PayloadReady for server0.conn145.netEvent96
[task 2021-07-26T15:38:06.790Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/8, PayloadReady: 5/8, EventTimings: 0/0, got NetMonitor:PayloadReady for server0.conn145.netEvent114
[task 2021-07-26T15:38:06.791Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 0/8, PayloadReady: 6/8, EventTimings: 0/0, got NetMonitor:PayloadReady for server0.conn145.netEvent132
[task 2021-07-26T15:38:06.793Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 1/8, PayloadReady: 6/8, EventTimings: 0/1, got NetMonitor:NetworkEvent for server0.conn145.netEvent96
[task 2021-07-26T15:38:06.793Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 2/8, PayloadReady: 6/8, EventTimings: 0/2, got NetMonitor:NetworkEvent for server0.conn145.netEvent114
[task 2021-07-26T15:38:06.793Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 3/8, PayloadReady: 6/8, EventTimings: 0/3, got NetMonitor:NetworkEvent for server0.conn145.netEvent132
[task 2021-07-26T15:38:06.795Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 6/8, EventTimings: 0/4, got NetMonitor:NetworkEvent for server0.conn145.netEvent150
[task 2021-07-26T15:38:06.796Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 0/4, got NetMonitor:PayloadReady for server0.conn145.netEvent150
[task 2021-07-26T15:38:06.797Z] 15:38:06 INFO - Buffered messages logged at 15:38:06
[task 2021-07-26T15:38:06.798Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 1/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent42
[task 2021-07-26T15:38:06.799Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 2/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent53
[task 2021-07-26T15:38:06.800Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 3/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent64
[task 2021-07-26T15:38:06.801Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 4/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent96
[task 2021-07-26T15:38:06.802Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 5/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent114
[task 2021-07-26T15:38:06.803Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 6/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent132
[task 2021-07-26T15:38:06.804Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 7/8, EventTimings: 7/4, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent150
[task 2021-07-26T15:38:06.805Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 4/8, PayloadReady: 8/8, EventTimings: 7/4, got NetMonitor:PayloadReady for server0.conn145.netEvent168
[task 2021-07-26T15:38:06.806Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 5/8, PayloadReady: 8/8, EventTimings: 7/5, got NetMonitor:NetworkEvent for server0.conn145.netEvent168
[task 2021-07-26T15:38:06.807Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 5/8, PayloadReady: 8/8, EventTimings: 8/5, got NetMonitor:NetworkEventUpdated:EventTimings for server0.conn145.netEvent168
[task 2021-07-26T15:38:06.808Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 6/8, PayloadReady: 8/8, EventTimings: 8/6, got NetMonitor:NetworkEvent for server0.conn145.netEvent187
[task 2021-07-26T15:38:06.809Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 7/8, PayloadReady: 8/8, EventTimings: 8/7, got NetMonitor:NetworkEvent for server0.conn145.netEvent198
[task 2021-07-26T15:38:06.810Z] 15:38:06 INFO - > Network event progress: NetworkEvent: 8/8, PayloadReady: 8/8, EventTimings: 8/8, got NetMonitor:NetworkEvent for server0.conn145.netEvent209
[task 2021-07-26T15:38:06.811Z] 15:38:06 INFO - Buffered messages finished
[task 2021-07-26T15:38:06.812Z] 15:38:06 INFO - TEST-UNEXPECTED-FAIL | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | Uncaught exception - at chrome://mochikit/content/tests/SimpleTest/EventUtils.js:327 - TypeError: can't access property "dispatchEvent", aTarget is null
[task 2021-07-26T15:38:06.812Z] 15:38:06 INFO - Stack trace:
[task 2021-07-26T15:38:06.812Z] 15:38:06 INFO - sendMouseEvent@chrome://mochikit/content/tests/SimpleTest/EventUtils.js:327:5
[task 2021-07-26T15:38:06.812Z] 15:38:06 INFO - @chrome://mochitests/content/browser/devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js:37:14
[task 2021-07-26T15:38:06.813Z] 15:38:06 INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1081:34
[task 2021-07-26T15:38:06.813Z] 15:38:06 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1121:11
[task 2021-07-26T15:38:06.813Z] 15:38:06 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:938:14
[task 2021-07-26T15:38:06.814Z] 15:38:06 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2021-07-26T15:38:06.815Z] 15:38:06 INFO - Leaving test bound
[task 2021-07-26T15:38:06.833Z] 15:38:06 INFO - Removing tab.
[task 2021-07-26T15:38:06.834Z] 15:38:06 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-07-26T15:38:06.862Z] 15:38:06 INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-07-26T15:38:06.875Z] 15:38:06 INFO - Tab removed and finished closing
[task 2021-07-26T15:38:06.913Z] 15:38:06 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2021-07-26T15:38:06.915Z] 15:38:06 INFO - finish() was called, cleaning up...
[task 2021-07-26T15:38:06.949Z] 15:38:06 INFO - GECKO(1559) | MEMORY STAT | vsize 3997MB | residentFast 659MB | heapAllocated 295MB
[task 2021-07-26T15:38:06.950Z] 15:38:06 INFO - TEST-OK | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | took 2768ms
[task 2021-07-26T15:38:07.022Z] 15:38:07 INFO - checking window state
Description
•