1697542 - Intermittent TV-fis devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js | the markup view is still rendered fine - | Uncaught exception - at chrome://*/devtools/client/shared/test/shared-head.js:706 - Error: Failed waitFor()

Status: RESOLVED INCOMPLETE

(DevTools :: Framework, defect, P5)

Description

[Treeherder Bug Filer]

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

---

...
[task 2021-03-10T16:21:42.736Z] 16:21:42     INFO - Entering test bound 
[task 2021-03-10T16:21:42.736Z] 16:21:42     INFO - Adding a new tab with URL: data:text/html,<meta charset=utf8>
[task 2021-03-10T16:21:42.736Z] 16:21:42     INFO - Tab added and finished loading
[task 2021-03-10T16:21:42.736Z] 16:21:42     INFO - Opening the toolbox
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Buffered messages logged at 16:21:37
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Console message: Invalid chrome URI (need path): chrome://devtools/
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Buffered messages logged at 16:21:38
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Toolbox opened and focused
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Console message: Invalid chrome URI (need path): chrome://devtools/
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Buffered messages logged at 16:21:40
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Console message: Invalid chrome URI (need path): chrome://devtools/
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Navigate to a different origin
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Load document "http://example.com/browser/devtools/client/framework/test/doc_backward_forward_navigation.html"
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Waiting for page to be loaded…
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Buffered messages logged at 16:21:41
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - → page loaded
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Waiting for inspector to be reloaded…
[task 2021-03-10T16:21:42.737Z] 16:21:42     INFO - Waiting for markup view to load after navigation.
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Waiting for new root.
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Waiting for inspector to update after new-root event.
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Waiting for inspector updates after page reload
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - → panel reloaded
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Waiting for target switch…
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - → switched-target emitted
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Then navigate back, and forth immediatly
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Load document "http://example.org/browser/devtools/client/framework/test/doc_backward_forward_navigation.html?no-mutation"
[task 2021-03-10T16:21:42.738Z] 16:21:42     INFO - Waiting for page to be loaded…
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - Buffered messages logged at 16:21:42
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - Console message: [JavaScript Warning: "Loading failed for the <script> with source “http://example.com/browser/devtools/client/framework/test/code_inline_bundle.js?id=1615393301902”." {file: "http://example.com/browser/devtools/client/framework/test/doc_backward_forward_navigation.html" line: 1}]
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - → page loaded
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - Waiting for inspector to be reloaded…
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - Waiting for markup view to load after navigation.
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - Waiting for new root.
[task 2021-03-10T16:21:42.739Z] 16:21:42     INFO - Waiting for inspector to update after new-root event.
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - Waiting for inspector updates after page reload
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - → panel reloaded
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - Waiting for target 'navigate' event…
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - → 'navigate' emitted
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - Check that the toolbox toolbar is still visible
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - TEST-PASS | devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js | Toolbar is still visible - 
[task 2021-03-10T16:21:42.740Z] 16:21:42     INFO - Check that the markup view is rendered correctly and elements can be selected
[task 2021-03-10T16:21:42.741Z] 16:21:42     INFO - Buffered messages finished
[task 2021-03-10T16:21:42.741Z] 16:21:42     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js | the markup view is still rendered fine - 
[task 2021-03-10T16:21:42.741Z] 16:21:42     INFO - Stack trace:
[task 2021-03-10T16:21:42.741Z] 16:21:42     INFO - chrome://mochikit/content/browser-test.js:test_ok:1331
[task 2021-03-10T16:21:42.741Z] 16:21:42     INFO - chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js:checkToolboxState:74
[task 2021-03-10T16:21:42.741Z] 16:21:42     INFO - chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js:null:61
[task 2021-03-10T16:21:42.742Z] 16:21:42     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1089
[task 2021-03-10T16:21:42.742Z] 16:21:42     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1129
[task 2021-03-10T16:21:42.742Z] 16:21:42     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:943
[task 2021-03-10T16:21:42.742Z] 16:21:42     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:1037
[task 2021-03-10T16:21:42.742Z] 16:21:42     INFO - Selecting the node for 'ul.logs'
[task 2021-03-10T16:21:42.743Z] 16:21:42     INFO - GECKO(7544) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-03-10T16:21:42.743Z] 16:21:42     INFO - GECKO(7544) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2021-03-10T16:21:42.743Z] 16:21:42     INFO - Check that the debugger has some sources
[task 2021-03-10T16:21:42.743Z] 16:21:42     INFO - Wait for doc_backward_forward_navigation.html to be displayed in the debugger source panel
[task 2021-03-10T16:21:42.832Z] 16:21:42     INFO - GECKO(7544) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2021-03-10T16:21:42.843Z] 16:21:42     INFO - GECKO(7544) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2021-03-10T16:21:47.536Z] 16:21:47     INFO - GECKO(7544) | [Child 8560: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (000001BAC1649C00) [pid = 8560] [serial = 2] [outer = 0000000000000000] [url = about:blank]
[task 2021-03-10T16:21:48.776Z] 16:21:48     INFO - GECKO(7544) | [Child 1816: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 000001ACEB43F400 == 0 [pid = 1816] [id = 1] [url = data:text/html,<meta charset=utf8>]
[task 2021-03-10T16:21:48.835Z] 16:21:48     INFO - GECKO(7544) | [Child 1816: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (000001ACF1AD0AC0) [pid = 1816] [serial = 5] [outer = 0000000000000000] [url = data:text/html,<meta charset=utf8>]
[task 2021-03-10T16:21:49.036Z] 16:21:49     INFO - GECKO(7544) | [Parent 4752: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 26 (000001AD1567B800) [pid = 4752] [serial = 28] [outer = 0000000000000000] [url = about:blank]
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:706 - Error: Failed waitFor(): 
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - Failed condition: () =>
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO -     dbgPanel.panelWin.document.querySelector(selectors.sourceTreeRootNode)
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - 
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - Stack trace:
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - waitFor@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:706:11
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - async*checkToolboxState@chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js:82:26
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - async*@chrome://mochitests/content/browser/devtools/client/framework/test/browser_toolbox_backward_forward_navigation.js:61:9
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1089:34
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1129:11
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:943:14
[task 2021-03-10T16:21:50.030Z] 16:21:50     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23
[task 2021-03-10T16:21:50.031Z] 16:21:50     INFO - Leaving test bound 
[task 2021-03-10T16:21:51.351Z] 16:21:51     INFO - Removing tab.
[task 2021-03-10T16:21:51.351Z] 16:21:51     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2021-03-10T16:21:51.369Z] 16:21:51     INFO - Got event: 'TabClose' on [object XULElement].
[task 2021-03-10T16:21:51.379Z] 16:21:51     INFO - GECKO(7544) | [Child 6284, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80040111 (NS_ERROR_NOT_AVAILABLE): file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1349
[task 2021-03-10T16:21:51.379Z] 16:21:51     INFO - GECKO(7544) | [Parent 4752, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1327
[task 2021-03-10T16:21:51.389Z] 16:21:51     INFO - GECKO(7544) | [Parent 4752, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:181
[task 2021-03-10T16:21:51.393Z] 16:21:51     INFO - Tab removed and finished closing
[task 2021-03-10T16:21:51.455Z] 16:21:51     INFO - GECKO(7544) | [Parent 4752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 000001AD15986000 == 12 [pid = 4752] [id = 13] [url = chrome://devtools/content/debugger/index.html]
[task 2021-03-10T16:21:51.455Z] 16:21:51     INFO - GECKO(7544) | [Parent 4752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 000001AD1C134C00 == 11 [pid = 4752] [id = 14] [url = chrome://devtools/content/inspector/index.xhtml]
[task 2021-03-10T16:21:51.455Z] 16:21:51     INFO - GECKO(7544) | [Parent 4752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 000001AD0C65E800 == 10 [pid = 4752] [id = 15] [url = chrome://devtools/content/inspector/markup/markup.xhtml]
[task 2021-03-10T16:21:52.772Z] 16:21:52     INFO - GECKO(7544) | [Parent 4752: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 000001AD13116400 == 9 [pid = 4752] [id = 12] [url = about:devtools-toolbox]
[task 2021-03-10T16:21:52.892Z] 16:21:52     INFO - GECKO(7544) | [Child 1816: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (000001ACF1AB0400) [pid = 1816] [serial = 6] [outer = 0000000000000000] [url = about:blank]
[task 2021-03-10T16:21:52.892Z] 16:21:52     INFO - GECKO(7544) | [Child 1816: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (000001ACF1F6E400) [pid = 1816] [serial = 7] [outer = 0000000000000000] [url = data:text/html,<meta charset=utf8>]
[task 2021-03-10T16:21:52.932Z] 16:21:52     INFO - Removing tab.
...```

Comment 1

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

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

Comment 2

[Intermittent Failures Robot]

7 failures in 4622 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • opt: 1
  • debug: 1
• windows10-64-qr: 2
  • opt: 1
  • debug: 1
• macosx1014-64-qr: 2
  • opt: 1
  • debug: 1
• linux1804-64: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1697542&startday=2021-03-08&endday=2021-03-14&tree=all

Comment 3

[Nicolas Chevobbe [:nchevobbe]]

This bug mentions TV-fis jobs, but the test should be disabled on fission: https://searchfox.org/mozilla-central/rev/1a47a74bd5ba89f2474aa27c40bd478e853f3276/devtools/client/framework/test/browser.ini#95-96

Comment 4

[Intermittent Failures Robot]

10 failures in 4257 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-central: 1

Platform and build breakdown:

• macosx1014-64-qr: 2
  • debug: 1
  • opt: 1
• linux1804-64-qr: 2
  • debug: 1
  • opt: 1
• windows10-64-qr: 2
  • opt: 1
  • debug: 1
• windows7-32: 1
  • debug: 1
• linux1804-64: 2
  • debug: 2
• linux1804-64-asan: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1697542&startday=2021-03-15&endday=2021-03-21&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-asan: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1697542&startday=2021-03-22&endday=2021-03-28&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1697542&startday=2021-03-29&endday=2021-04-04&tree=all

Comment 7

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

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

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows10-64: 1
  • asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1697542&startday=2021-04-26&endday=2021-05-02&tree=all