Closed Bug 1702682 Opened 3 years ago Closed 3 years ago

Intermittent TV devtools/client/framework/test/browser_toolbox_fission_navigation.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Categories

(DevTools :: Framework, defect, P5)

Product:
DevTools
Component:
Framework
Type:
defect

Tracking

(firefox-esr78 unaffected, firefox87 unaffected, firefox88 unaffected, firefox89 affected)

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr78 --- unaffected
firefox87 --- unaffected
firefox88 --- unaffected
firefox89 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335232283&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/K9oAIvVdQ2GeIFXah9-sUw/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/K9oAIvVdQ2GeIFXah9-sUw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2021-04-01T22:53:45.857Z] 22:53:45     INFO - TEST-START | devtools/client/framework/test/browser_toolbox_fission_navigation.js
[task 2021-04-01T22:53:50.023Z] 22:53:50     INFO - GECKO(2250) | 2021-04-01 22:53:50.005 firefox[2250:31764] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightly.savedState/window_1.data: No such file or directory (2)
[task 2021-04-01T22:53:57.780Z] 22:53:57     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:53:57.895Z] 22:53:57     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:53:59.012Z] 22:53:59     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:53:59.012Z] 22:53:59     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:53:59.176Z] 22:53:59     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:53:59.437Z] 22:53:59     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:01.662Z] 22:54:01     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:02.150Z] 22:54:02     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:02.150Z] 22:54:02     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:02.327Z] 22:54:02     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:02.327Z] 22:54:02     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | console.error: (new InvalidStateError("JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment", (void 0), 346))
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment: sendPacket@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:346:10
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | send@resource://devtools/shared/transport/js-window-actor-transport.js:53:25
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | _sendEvent@resource://devtools/shared/protocol/Actor.js:72:15
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | initialize/<@resource://devtools/shared/protocol/Actor.js:46:16
[task 2021-04-01T22:54:02.746Z] 22:54:02     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:02.747Z] 22:54:02     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:02.747Z] 22:54:02     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:02.747Z] 22:54:02     INFO - GECKO(2250) | _emitResourcesForm@resource://devtools/server/actors/targets/target-actor-mixin.js:191:12
[task 2021-04-01T22:54:02.747Z] 22:54:02     INFO - GECKO(2250) | notifyResourceAvailable@resource://devtools/server/actors/targets/target-actor-mixin.js:171:12
[task 2021-04-01T22:54:02.747Z] 22:54:02     INFO - GECKO(2250) | observe@resource://devtools/server/actors/resources/utils/nsi-console-listener-watcher.js:49:20
[task 2021-04-01T22:54:02.748Z] 22:54:02     INFO - GECKO(2250) | console.error: (new InvalidStateError("JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment", (void 0), 346))
[task 2021-04-01T22:54:02.748Z] 22:54:02     INFO - GECKO(2250) | InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment: sendPacket@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:346:10
[task 2021-04-01T22:54:02.748Z] 22:54:02     INFO - GECKO(2250) | send@resource://devtools/shared/transport/js-window-actor-transport.js:53:25
[task 2021-04-01T22:54:02.748Z] 22:54:02     INFO - GECKO(2250) | send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2021-04-01T22:54:02.748Z] 22:54:02     INFO - GECKO(2250) | _sendEvent@resource://devtools/shared/protocol/Actor.js:72:15
[task 2021-04-01T22:54:02.748Z] 22:54:02     INFO - GECKO(2250) | initialize/<@resource://devtools/shared/protocol/Actor.js:46:16
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | onFrameLoad@resource://devtools/server/actors/inspector/walker.js:2512:12
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | EventListener.handleEvent*onFrameLoad@resource://devtools/server/actors/inspector/walker.js:2487:14
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:02.749Z] 22:54:02     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:02.750Z] 22:54:02     INFO - GECKO(2250) | _windowReady@resource://devtools/server/actors/targets/browsing-context.js:1480:10
[task 2021-04-01T22:54:02.750Z] 22:54:02     INFO - GECKO(2250) | DebuggerProgressListener.prototype.onWindowCreated<@resource://devtools/server/actors/targets/browsing-context.js:1818:23
[task 2021-04-01T22:54:02.750Z] 22:54:02     INFO - GECKO(2250) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-04-01T22:54:02.750Z] 22:54:02     INFO - GECKO(2250) | EventListener.handleEvent*watch@resource://devtools/server/actors/targets/browsing-context.js:1717:13
[task 2021-04-01T22:54:02.750Z] 22:54:02     INFO - GECKO(2250) | _watchDocshells@resource://devtools/server/actors/targets/browsing-context.js:685:28
[task 2021-04-01T22:54:02.750Z] 22:54:02     INFO - GECKO(2250) | _attach/<@resource://devtools/server/actors/targets/browsing-context.js:667:42
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:54:21
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | _attach@resource://devtools/server/actors/targets/browsing-context.js:667:19
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | attach@resource://devtools/server/actors/targets/browsing-context.js:1083:10
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | addWatcherDataEntry@resource://devtools/server/actors/targets/target-actor-mixin.js:54:16
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | _createTargetActor@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:239:19
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | instantiate@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:174:14
[task 2021-04-01T22:54:02.751Z] 22:54:02     INFO - GECKO(2250) | handleEvent@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:483:12
[task 2021-04-01T22:54:03.860Z] 22:54:03     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:04.329Z] 22:54:04     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:04.346Z] 22:54:04     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:04.730Z] 22:54:04     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:04.736Z] 22:54:04     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:06.763Z] 22:54:06     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:07.236Z] 22:54:07     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:07.237Z] 22:54:07     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:07.385Z] 22:54:07     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:07.385Z] 22:54:07     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:07.442Z] 22:54:07     INFO - GECKO(2250) | console.error: (new InvalidStateError("JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment", (void 0), 346))
[task 2021-04-01T22:54:07.442Z] 22:54:07     INFO - GECKO(2250) | InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment: sendPacket@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:346:10
[task 2021-04-01T22:54:07.442Z] 22:54:07     INFO - GECKO(2250) | send@resource://devtools/shared/transport/js-window-actor-transport.js:53:25
[task 2021-04-01T22:54:07.442Z] 22:54:07     INFO - GECKO(2250) | send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2021-04-01T22:54:07.442Z] 22:54:07     INFO - GECKO(2250) | _sendEvent@resource://devtools/shared/protocol/Actor.js:72:15
[task 2021-04-01T22:54:07.442Z] 22:54:07     INFO - GECKO(2250) | initialize/<@resource://devtools/shared/protocol/Actor.js:46:16
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | _onReflow@resource://devtools/server/actors/reflow.js:80:12
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:07.443Z] 22:54:07     INFO - GECKO(2250) | _startEventLoop@resource://devtools/server/actors/reflow.js:314:12
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | notify@resource://gre/modules/Timer.jsm:62:17
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | console.error: (new InvalidStateError("JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment", (void 0), 346))
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | InvalidStateError: JSWindowActorChild.sendAsyncMessage: JSWindowActorChild cannot send at the moment: sendPacket@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:346:10
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | send@resource://devtools/shared/transport/js-window-actor-transport.js:53:25
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | send@resource://devtools/server/devtools-server-connection.js:99:20
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | _sendEvent@resource://devtools/shared/protocol/Actor.js:72:15
[task 2021-04-01T22:54:07.444Z] 22:54:07     INFO - GECKO(2250) | initialize/<@resource://devtools/shared/protocol/Actor.js:46:16
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | onFrameLoad@resource://devtools/server/actors/inspector/walker.js:2512:12
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | EventListener.handleEvent*onFrameLoad@resource://devtools/server/actors/inspector/walker.js:2487:14
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:172:18
[task 2021-04-01T22:54:07.445Z] 22:54:07     INFO - GECKO(2250) | emit@resource://devtools/shared/event-emitter.js:324:18
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | _windowReady@resource://devtools/server/actors/targets/browsing-context.js:1480:10
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | DebuggerProgressListener.prototype.onWindowCreated<@resource://devtools/server/actors/targets/browsing-context.js:1818:23
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | EventListener.handleEvent*watch@resource://devtools/server/actors/targets/browsing-context.js:1717:13
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | _watchDocshells@resource://devtools/server/actors/targets/browsing-context.js:685:28
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | _attach/<@resource://devtools/server/actors/targets/browsing-context.js:667:42
[task 2021-04-01T22:54:07.446Z] 22:54:07     INFO - GECKO(2250) | DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:54:21
[task 2021-04-01T22:54:07.447Z] 22:54:07     INFO - GECKO(2250) | _attach@resource://devtools/server/actors/targets/browsing-context.js:667:19
[task 2021-04-01T22:54:07.447Z] 22:54:07     INFO - GECKO(2250) | attach@resource://devtools/server/actors/targets/browsing-context.js:1083:10
[task 2021-04-01T22:54:07.447Z] 22:54:07     INFO - GECKO(2250) | addWatcherDataEntry@resource://devtools/server/actors/targets/target-actor-mixin.js:54:16
[task 2021-04-01T22:54:07.447Z] 22:54:07     INFO - GECKO(2250) | _createTargetActor@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:239:19
[task 2021-04-01T22:54:07.447Z] 22:54:07     INFO - GECKO(2250) | instantiate@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:174:14
[task 2021-04-01T22:54:07.447Z] 22:54:07     INFO - GECKO(2250) | handleEvent@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.jsm:483:12
[task 2021-04-01T22:54:08.779Z] 22:54:08     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:09.218Z] 22:54:09     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:09.218Z] 22:54:09     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:09.329Z] 22:54:09     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:09.330Z] 22:54:09     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:12.046Z] 22:54:12     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:15.064Z] 22:54:15     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_FLEXBOX"
[task 2021-04-01T22:54:15.169Z] 22:54:15     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_FLEXBOX"
[task 2021-04-01T22:54:15.829Z] 22:54:15     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:15.829Z] 22:54:15     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:16.262Z] 22:54:16     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:16.263Z] 22:54:16     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:21.854Z] 22:54:21     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:21.974Z] 22:54:21     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:23.354Z] 22:54:23     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:23.359Z] 22:54:23     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:23.365Z] 22:54:23     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:23.548Z] 22:54:23     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:26.046Z] 22:54:26     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:26.612Z] 22:54:26     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:26.612Z] 22:54:26     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:26.855Z] 22:54:26     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:26.855Z] 22:54:26     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:28.053Z] 22:54:28     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:28.597Z] 22:54:28     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:28.662Z] 22:54:28     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:29.080Z] 22:54:29     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:29.080Z] 22:54:29     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:30.812Z] 22:54:30     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:31.400Z] 22:54:31     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:31.446Z] 22:54:31     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:31.663Z] 22:54:31     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-04-01T22:54:31.663Z] 22:54:31     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-04-01T22:54:32.446Z] 22:54:32     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:32.902Z] 22:54:32     INFO - GECKO(2250) | console.error: "Error while calling actor 'pagestyle's method 'getApplied'" "can't access property \"rawNode\", node is null"
[task 2021-04-01T22:54:32.902Z] 22:54:32     INFO - GECKO(2250) | console.error: (new TypeError("can't access property \"rawNode\", node is null", "resource://devtools/server/actors/page-style.js", 570))
[task 2021-04-01T22:54:33.075Z] 22:54:33     INFO - GECKO(2250) | console.error: (new Error("Protocol error (TypeError): can't access property \"rawNode\", node is null from: server0.conn1.child59/pagestyle25 (resource://devtools/server/actors/page-style.js:570:5)", "resource://devtools/shared/protocol/Front.js", 358))
[task 2021-04-01T22:54:33.075Z] 22:54:33     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:33.075Z] 22:54:33     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-04-01T22:54:36.063Z] 22:54:36     INFO - GECKO(2250) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2021-04-01T22:54:38.752Z] 22:54:38     INFO - GECKO(2250) | console.error: "Tried to send a 'getApplied' method reply on an already destroyed actor 'pagestyle'"
[task 2021-04-01T22:54:38.752Z] 22:54:38     INFO - GECKO(2250) | console.error: "Tried to send a 'getApplied' method reply on an already destroyed actor 'pagestyle'"
[task 2021-04-01T22:54:38.883Z] 22:54:38     INFO - TEST-INFO | started process screencapture
[task 2021-04-01T22:54:38.989Z] 22:54:38     INFO - TEST-INFO | screencapture: exit 0
[task 2021-04-01T22:54:38.990Z] 22:54:38     INFO - <snipped 63 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2021-04-01T22:54:38.990Z] 22:54:38     INFO - Buffered messages logged at 22:54:09
[task 2021-04-01T22:54:38.990Z] 22:54:38     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the about:blank page after navigation - 
[task 2021-04-01T22:54:38.991Z] 22:54:38     INFO - Navigate to about:robots
[task 2021-04-01T22:54:38.991Z] 22:54:38     INFO - Load document "about:robots"
[task 2021-04-01T22:54:38.991Z] 22:54:38     INFO - Waiting for page to be loaded…
[task 2021-04-01T22:54:38.991Z] 22:54:38     INFO - Buffered messages logged at 22:54:11
[task 2021-04-01T22:54:38.991Z] 22:54:38     INFO - → page loaded
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Waiting for inspector to be reloaded…
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Waiting for markup view to load after navigation.
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Buffered messages logged at 22:54:12
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Waiting for new root.
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Waiting for inspector to update after new-root event.
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Waiting for inspector updates after page reload
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - → panel reloaded
[task 2021-04-01T22:54:38.992Z] 22:54:38     INFO - Waiting for target switch…
[task 2021-04-01T22:54:38.993Z] 22:54:38     INFO - Buffered messages logged at 22:54:13
[task 2021-04-01T22:54:38.993Z] 22:54:38     INFO - → switched-target emitted
[task 2021-04-01T22:54:38.993Z] 22:54:38     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the about:robots page after navigation - 
[task 2021-04-01T22:54:38.993Z] 22:54:38     INFO - Test with server side target switching DISABLED
[task 2021-04-01T22:54:38.993Z] 22:54:38     INFO - Adding a new tab with URL: http://example.com/document-builder.sjs?html=<div id=com>com
[task 2021-04-01T22:54:38.993Z] 22:54:38     INFO - Buffered messages logged at 22:54:15
[task 2021-04-01T22:54:38.994Z] 22:54:38     INFO - Tab added and finished loading
[task 2021-04-01T22:54:38.994Z] 22:54:38     INFO - Opening the toolbox
[task 2021-04-01T22:54:38.994Z] 22:54:38     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: "http://example.com/document-builder.sjs?html=%3Cdiv%20id=com%3Ecom" line: 0}]
[task 2021-04-01T22:54:38.994Z] 22:54:38     INFO - Buffered messages logged at 22:54:23
[task 2021-04-01T22:54:38.995Z] 22:54:38     INFO - Toolbox opened and focused
[task 2021-04-01T22:54:38.995Z] 22:54:38     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the COM page - 
[task 2021-04-01T22:54:38.995Z] 22:54:38     INFO - Navigate to the NET page
[task 2021-04-01T22:54:38.996Z] 22:54:38     INFO - Load document "http://example.net/document-builder.sjs?html=<div id=net>net"
[task 2021-04-01T22:54:38.997Z] 22:54:38     INFO - Waiting for page to be loaded…
[task 2021-04-01T22:54:38.997Z] 22:54:38     INFO - Buffered messages logged at 22:54:25
[task 2021-04-01T22:54:38.997Z] 22:54:38     INFO - → page loaded
[task 2021-04-01T22:54:38.997Z] 22:54:38     INFO - Waiting for inspector to be reloaded…
[task 2021-04-01T22:54:38.998Z] 22:54:38     INFO - Waiting for markup view to load after navigation.
[task 2021-04-01T22:54:38.999Z] 22:54:38     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: "http://example.net/document-builder.sjs?html=%3Cdiv%20id=net%3Enet" line: 0}]
[task 2021-04-01T22:54:38.999Z] 22:54:38     INFO - Buffered messages logged at 22:54:26
[task 2021-04-01T22:54:38.999Z] 22:54:38     INFO - Waiting for new root.
[task 2021-04-01T22:54:39.000Z] 22:54:38     INFO - Waiting for inspector to update after new-root event.
[task 2021-04-01T22:54:39.000Z] 22:54:39     INFO - Waiting for inspector updates after page reload
[task 2021-04-01T22:54:39.000Z] 22:54:39     INFO - → panel reloaded
[task 2021-04-01T22:54:39.002Z] 22:54:39     INFO - Waiting for target switch…
[task 2021-04-01T22:54:39.003Z] 22:54:39     INFO - → switched-target emitted
[task 2021-04-01T22:54:39.003Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the NET page - 
[task 2021-04-01T22:54:39.003Z] 22:54:39     INFO - Reload the NET page
[task 2021-04-01T22:54:39.003Z] 22:54:39     INFO - Load document "http://example.net/document-builder.sjs?html=<div id=net>net"
[task 2021-04-01T22:54:39.003Z] 22:54:39     INFO - Waiting for page to be loaded…
[task 2021-04-01T22:54:39.004Z] 22:54:39     INFO - Buffered messages logged at 22:54:27
[task 2021-04-01T22:54:39.006Z] 22:54:39     INFO - → page loaded
[task 2021-04-01T22:54:39.007Z] 22:54:39     INFO - Waiting for inspector to be reloaded…
[task 2021-04-01T22:54:39.007Z] 22:54:39     INFO - Waiting for markup view to load after navigation.
[task 2021-04-01T22:54:39.012Z] 22:54:39     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: "http://example.net/document-builder.sjs?html=%3Cdiv%20id=net%3Enet" line: 0}]
[task 2021-04-01T22:54:39.012Z] 22:54:39     INFO - Buffered messages logged at 22:54:28
[task 2021-04-01T22:54:39.012Z] 22:54:39     INFO - Waiting for new root.
[task 2021-04-01T22:54:39.012Z] 22:54:39     INFO - Waiting for inspector to update after new-root event.
[task 2021-04-01T22:54:39.012Z] 22:54:39     INFO - Waiting for inspector updates after page reload
[task 2021-04-01T22:54:39.013Z] 22:54:39     INFO - → panel reloaded
[task 2021-04-01T22:54:39.013Z] 22:54:39     INFO - Waiting for target 'navigate' event…
[task 2021-04-01T22:54:39.013Z] 22:54:39     INFO - → 'navigate' emitted
[task 2021-04-01T22:54:39.013Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the NET page after reload - 
[task 2021-04-01T22:54:39.013Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | The new node is different - 
[task 2021-04-01T22:54:39.014Z] 22:54:39     INFO - Navigate back to the COM page
[task 2021-04-01T22:54:39.014Z] 22:54:39     INFO - Load document "http://example.com/document-builder.sjs?html=<div id=com>com"
[task 2021-04-01T22:54:39.014Z] 22:54:39     INFO - Waiting for page to be loaded…
[task 2021-04-01T22:54:39.014Z] 22:54:39     INFO - Buffered messages logged at 22:54:29
[task 2021-04-01T22:54:39.014Z] 22:54:39     INFO - → page loaded
[task 2021-04-01T22:54:39.015Z] 22:54:39     INFO - Waiting for inspector to be reloaded…
[task 2021-04-01T22:54:39.015Z] 22:54:39     INFO - Waiting for markup view to load after navigation.
[task 2021-04-01T22:54:39.015Z] 22:54:39     INFO - Buffered messages logged at 22:54:30
[task 2021-04-01T22:54:39.015Z] 22:54:39     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: "http://example.com/document-builder.sjs?html=%3Cdiv%20id=com%3Ecom" line: 0}]
[task 2021-04-01T22:54:39.015Z] 22:54:39     INFO - Waiting for new root.
[task 2021-04-01T22:54:39.016Z] 22:54:39     INFO - Waiting for inspector to update after new-root event.
[task 2021-04-01T22:54:39.016Z] 22:54:39     INFO - Waiting for inspector updates after page reload
[task 2021-04-01T22:54:39.016Z] 22:54:39     INFO - Buffered messages logged at 22:54:31
[task 2021-04-01T22:54:39.016Z] 22:54:39     INFO - → panel reloaded
[task 2021-04-01T22:54:39.016Z] 22:54:39     INFO - Waiting for target switch…
[task 2021-04-01T22:54:39.017Z] 22:54:39     INFO - → switched-target emitted
[task 2021-04-01T22:54:39.017Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the COM page after navigation - 
[task 2021-04-01T22:54:39.017Z] 22:54:39     INFO - Navigate to about:blank
[task 2021-04-01T22:54:39.017Z] 22:54:39     INFO - Load document "about:blank"
[task 2021-04-01T22:54:39.017Z] 22:54:39     INFO - Waiting for page to be loaded…
[task 2021-04-01T22:54:39.018Z] 22:54:39     INFO - Buffered messages logged at 22:54:32
[task 2021-04-01T22:54:39.018Z] 22:54:39     INFO - → page loaded
[task 2021-04-01T22:54:39.018Z] 22:54:39     INFO - Waiting for inspector to be reloaded…
[task 2021-04-01T22:54:39.018Z] 22:54:39     INFO - Waiting for markup view to load after navigation.
[task 2021-04-01T22:54:39.018Z] 22:54:39     INFO - Waiting for new root.
[task 2021-04-01T22:54:39.019Z] 22:54:39     INFO - Waiting for inspector to update after new-root event.
[task 2021-04-01T22:54:39.019Z] 22:54:39     INFO - Waiting for inspector updates after page reload
[task 2021-04-01T22:54:39.019Z] 22:54:39     INFO - → panel reloaded
[task 2021-04-01T22:54:39.022Z] 22:54:39     INFO - Waiting for target 'navigate' event…
[task 2021-04-01T22:54:39.022Z] 22:54:39     INFO - → 'navigate' emitted
[task 2021-04-01T22:54:39.023Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the about:blank page after navigation - 
[task 2021-04-01T22:54:39.023Z] 22:54:39     INFO - Navigate to about:robots
[task 2021-04-01T22:54:39.023Z] 22:54:39     INFO - Load document "about:robots"
[task 2021-04-01T22:54:39.023Z] 22:54:39     INFO - Waiting for page to be loaded…
[task 2021-04-01T22:54:39.023Z] 22:54:39     INFO - Buffered messages logged at 22:54:34
[task 2021-04-01T22:54:39.023Z] 22:54:39     INFO - → page loaded
[task 2021-04-01T22:54:39.024Z] 22:54:39     INFO - Waiting for inspector to be reloaded…
[task 2021-04-01T22:54:39.024Z] 22:54:39     INFO - Waiting for markup view to load after navigation.
[task 2021-04-01T22:54:39.024Z] 22:54:39     INFO - Buffered messages logged at 22:54:35
[task 2021-04-01T22:54:39.024Z] 22:54:39     INFO - Waiting for new root.
[task 2021-04-01T22:54:39.024Z] 22:54:39     INFO - Waiting for inspector to update after new-root event.
[task 2021-04-01T22:54:39.024Z] 22:54:39     INFO - Waiting for inspector updates after page reload
[task 2021-04-01T22:54:39.025Z] 22:54:39     INFO - Buffered messages logged at 22:54:36
[task 2021-04-01T22:54:39.025Z] 22:54:39     INFO - → panel reloaded
[task 2021-04-01T22:54:39.025Z] 22:54:39     INFO - Waiting for target switch…
[task 2021-04-01T22:54:39.025Z] 22:54:39     INFO - → switched-target emitted
[task 2021-04-01T22:54:39.026Z] 22:54:39     INFO - Buffered messages logged at 22:54:37
[task 2021-04-01T22:54:39.026Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | Found node for the about:robots page after navigation - 
[task 2021-04-01T22:54:39.026Z] 22:54:39     INFO - Leaving test bound 
[task 2021-04-01T22:54:39.026Z] 22:54:39     INFO - Removing tab.
[task 2021-04-01T22:54:39.026Z] 22:54:39     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-04-01T22:54:39.026Z] 22:54:39     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-04-01T22:54:39.027Z] 22:54:39     INFO - Tab removed and finished closing
[task 2021-04-01T22:54:39.027Z] 22:54:39     INFO - Removing tab.
[task 2021-04-01T22:54:39.027Z] 22:54:39     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-04-01T22:54:39.027Z] 22:54:39     INFO - Buffered messages logged at 22:54:38
[task 2021-04-01T22:54:39.031Z] 22:54:39     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-04-01T22:54:39.031Z] 22:54:39     INFO - Tab removed and finished closing
[task 2021-04-01T22:54:39.031Z] 22:54:39     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_fission_navigation.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2021-04-01T22:54:39.031Z] 22:54:39     INFO - Buffered messages finished
[task 2021-04-01T22:54:39.031Z] 22:54:39     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_fission_navigation.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - 
[task 2021-04-01T22:54:39.031Z] 22:54:39     INFO - GECKO(2250) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-04-01T22:54:39.032Z] 22:54:39     INFO - GECKO(2250) | MEMORY STAT | vsize 7762MB | residentFast 338MB | heapAllocated 123MB
[task 2021-04-01T22:54:39.032Z] 22:54:39     INFO - TEST-OK | devtools/client/framework/test/browser_toolbox_fission_navigation.js | took 53020ms
[task 2021-04-01T22:54:39.032Z] 22:54:39     INFO - checking window state
...
...
...```

Set release status flags based on info from the regressing bug 1644397

status-firefox87: --- → unaffected
status-firefox88: --- → unaffected
status-firefox89: --- → affected
status-firefox-esr78: --- → unaffected

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=349550497&repo=autoland&lineNumber=21319

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.