Open Bug 1548866 Opened 7 months ago Updated 3 months ago

Intermittent devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | Uncaught exception - waitFor - timed out after 500 tries.

Categories

(DevTools :: Console, defect, P5)

defect

Tracking

(Not tracked)

REOPENED

People

(Reporter: intermittent-bug-filer, Assigned: nchevobbe)

Details

(Keywords: intermittent-failure, leave-open, regression, Whiteboard: [stockwell disabled])

Attachments

(3 files)

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=244492260&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/MzoSaiu1T96iJyImM2qmxQ/runs/0/artifacts/public/logs/live_backing.log


INFO - TEST-START | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js
14:43:55 INFO - GECKO(4884) | ++DOCSHELL 00C67800 == 2 [pid = 3852] [id = {b2425aa0-3929-4571-a11d-f4b9e02880f9}]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 5 (00CCBCA0) [pid = 3852] [serial = 316] [outer = 00000000]
14:43:55 INFO - GECKO(4884) | [Child 3852, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
14:43:55 INFO - GECKO(4884) | [Child 3852, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 6 (086A3400) [pid = 3852] [serial = 317] [outer = 00CCBCA0]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 7 (086A5800) [pid = 3852] [serial = 318] [outer = 00CCBCA0]
14:43:55 INFO - GECKO(4884) | ++DOCSHELL 0F3D2C00 == 13 [pid = 1296] [id = {cd08b3ce-3763-4ad2-9c37-e7b54d34af06}]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 52 (009BD160) [pid = 1296] [serial = 1576] [outer = 00000000]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 53 (12983C00) [pid = 1296] [serial = 1577] [outer = 009BD160]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 54 (140F4C00) [pid = 1296] [serial = 1578] [outer = 009BD160]
14:43:55 INFO - GECKO(4884) | --DOMWINDOW == 1 (018C73A0) [pid = 1080] [serial = 23] [outer = 00000000] [url = https://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:55 INFO - GECKO(4884) | ++DOCSHELL 0B8EBC00 == 14 [pid = 1296] [id = {a2a5794f-8fc5-404e-b884-17560ad0e6fa}]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 55 (009BD700) [pid = 1296] [serial = 1579] [outer = 00000000]
14:43:55 INFO - GECKO(4884) | ++DOMWINDOW == 56 (0E8CC800) [pid = 1296] [serial = 1580] [outer = 009BD700]
14:43:56 INFO - GECKO(4884) | [Parent 1296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 43
14:43:56 INFO - GECKO(4884) | [Parent 1296, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 55
14:43:57 INFO - GECKO(4884) | ++DOCSHELL 08E19000 == 6 [pid = 5176] [id = {726edeeb-ae38-4ba2-8136-d9daf32ce25d}]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 14 (01876940) [pid = 5176] [serial = 455] [outer = 00000000]
14:43:57 INFO - GECKO(4884) | [Child 5176, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 15 (08E23000) [pid = 5176] [serial = 456] [outer = 01876940]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 16 (08EDA000) [pid = 5176] [serial = 457] [outer = 01876940]
14:43:57 INFO - GECKO(4884) | --DOMWINDOW == 3 (08B3C4C0) [pid = 1580] [serial = 344] [outer = 00000000] [url = about:blank]
14:43:57 INFO - GECKO(4884) | ++DOCSHELL 19C09000 == 15 [pid = 1296] [id = {95224b85-db08-4385-bc4d-87c9ee2947b8}]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 57 (3A560280) [pid = 1296] [serial = 1581] [outer = 00000000]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 58 (19D5CC00) [pid = 1296] [serial = 1582] [outer = 3A560280]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 59 (22020000) [pid = 1296] [serial = 1583] [outer = 3A560280]
14:43:57 INFO - GECKO(4884) | ++DOCSHELL 0FFE3000 == 16 [pid = 1296] [id = {cc062570-e91e-4731-911c-665756666d30}]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 60 (3A5603A0) [pid = 1296] [serial = 1584] [outer = 00000000]
14:43:57 INFO - GECKO(4884) | ++DOMWINDOW == 61 (1F240800) [pid = 1296] [serial = 1585] [outer = 3A5603A0]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 0 (0AC59400) [pid = 1080] [serial = 25] [outer = 00000000] [url = https://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 0C814800 == 5 [pid = 5176] [id = {e32e46a2-9bba-41b0-8003-f62f7eddb907}] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-insecure-frame.html]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 08ED7800 == 4 [pid = 5176] [id = {37095e9c-9126-4bb0-9f25-c72d11eaa4fb}] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 08ED2C00 == 3 [pid = 5176] [id = {7d6e147e-7af7-46bf-8f4e-b73225925468}] [url = http://example.org/browser/devtools/client/webconsole/test/mochitest/test-inspect-cross-domain-objects-frame.html]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 08E16400 == 2 [pid = 5176] [id = {1aa8de87-935d-4a63-8cc9-8706b3aa8508}] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-inspect-cross-domain-objects-top.html]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 08E20C00 == 1 [pid = 5176] [id = {76e5be97-5815-4954-a693-bb8a7a99cdd5}] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-insecure-passwords-web-console-warning.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 15 (01876B80) [pid = 5176] [serial = 441] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-insecure-passwords-web-console-warning.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 14 (018763A0) [pid = 5176] [serial = 448] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-inspect-cross-domain-objects-top.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 13 (08E23000) [pid = 5176] [serial = 456] [outer = 00000000] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 12 (08E18C00) [pid = 5176] [serial = 449] [outer = 00000000] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 11 (0C81A800) [pid = 5176] [serial = 447] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 10 (08ED6000) [pid = 5176] [serial = 445] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-insecure-frame.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 9 (08ED1C00) [pid = 5176] [serial = 450] [outer = 00000000] [url = data:text/html;charset=utf8,<p>hello]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 8 (0C814000) [pid = 5176] [serial = 443] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-insecure-passwords-web-console-warning.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 7 (08ED9000) [pid = 5176] [serial = 453] [outer = 00000000] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 6 (0C81F000) [pid = 5176] [serial = 454] [outer = 00000000] [url = http://example.org/browser/devtools/client/webconsole/test/mochitest/test-inspect-cross-domain-objects-frame.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 5 (08ED8800) [pid = 5176] [serial = 451] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-inspect-cross-domain-objects-top.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 4 (01876EE0) [pid = 5176] [serial = 452] [outer = 00000000] [url = http://example.org/browser/devtools/client/webconsole/test/mochitest/test-inspect-cross-domain-objects-frame.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 3 (08E624C0) [pid = 5176] [serial = 444] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-insecure-frame.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 2 (08E62700) [pid = 5176] [serial = 446] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 2 (08BBE400) [pid = 1580] [serial = 345] [outer = 00000000] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 01665800 == 0 [pid = 1580] [id = {3a7a2184-7cfe-4220-b13b-1f16a802c1fb}] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOCSHELL 00C5F800 == 1 [pid = 3852] [id = {76737be9-68b0-4d08-835c-6c592d8949d6}] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 6 (00CCB820) [pid = 3852] [serial = 311] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 5 (00CCB700) [pid = 3852] [serial = 314] [outer = 00000000] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 4 (0A586400) [pid = 3852] [serial = 313] [outer = 00000000] [url = http://example.com/browser/devtools/client/webconsole/test/mochitest/test-iframe-insecure-form-action.html]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 3 (00C66400) [pid = 3852] [serial = 315] [outer = 00000000] [url = about:blank]
14:43:58 INFO - GECKO(4884) | --DOMWINDOW == 2 (086A3400) [pid = 3852] [serial = 317] [outer = 00000000] [url = about:blank]
14:44:01 INFO - GECKO(4884) | --DOCSHELL 0E2EEC00 == 15 [pid = 1296] [id = {b405309b-d095-471a-9d4f-d566498ff229}] [url = about:blank]
14:44:01 INFO - GECKO(4884) | --DOCSHELL 0B8EF000 == 14 [pid = 1296] [id = {fd7adb96-e86a-4e77-868b-177d3f79a91f}] [url = about:blank]
14:44:01 INFO - GECKO(4884) | --DOCSHELL 0B8F0400 == 13 [pid = 1296] [id = {eff0e005-0b04-456d-ace7-666b8dcda33e}] [url = chrome://devtools/content/webconsole/index.html]
14:44:01 INFO - GECKO(4884) | --DOCSHELL 0B8F6000 == 12 [pid = 1296] [id = {1e0f0c07-8c94-4186-9639-7ca18ffaf475}] [url = chrome://devtools/content/webconsole/index.html]
14:44:01 INFO - GECKO(4884) | --DOCSHELL 0B8E9400 == 11 [pid = 1296] [id = {66b025ea-bc9d-4a04-a99b-bf540d6419f0}] [url = chrome://devtools/content/webconsole/index.html]
14:44:01 INFO - GECKO(4884) | --DOCSHELL 0B8EAC00 == 10 [pid = 1296] [id = {33eca2d9-2f77-4cb1-b9d0-003af1bb5689}] [url = about:blank]
14:44:03 INFO - GECKO(4884) | [Parent 1296, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 43
14:44:03 INFO - GECKO(4884) | [Parent 1296, Main Thread] WARNING: NS_ENSURE_TRUE(principal) failed: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 55
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 60 (129EDDC0) [pid = 1296] [serial = 1555] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 59 (129EDB80) [pid = 1296] [serial = 1552] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 58 (129ED820) [pid = 1296] [serial = 1565] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 57 (192E84C0) [pid = 1296] [serial = 1557] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 56 (009BD280) [pid = 1296] [serial = 1546] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 55 (1F205B80) [pid = 1296] [serial = 1562] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 54 (009BDB80) [pid = 1296] [serial = 1549] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 53 (192E8A60) [pid = 1296] [serial = 1560] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 52 (009BD3A0) [pid = 1296] [serial = 1540] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 51 (009BD5E0) [pid = 1296] [serial = 1543] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 50 (12983000) [pid = 1296] [serial = 1558] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 49 (0B8EA000) [pid = 1296] [serial = 1568] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 48 (0F3D7C00) [pid = 1296] [serial = 1556] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 47 (19222C00) [pid = 1296] [serial = 1563] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 46 (0F3D0800) [pid = 1296] [serial = 1554] [outer = 00000000] [url = about:devtools-toolbox]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 45 (0E21AC00) [pid = 1296] [serial = 1553] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 44 (0E2F0400) [pid = 1296] [serial = 1569] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 43 (0E2F6400) [pid = 1296] [serial = 1548] [outer = 00000000] [url = about:devtools-toolbox]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 42 (0E2F6000) [pid = 1296] [serial = 1547] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 41 (1AB90800) [pid = 1296] [serial = 1566] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 40 (0FFE2400) [pid = 1296] [serial = 1545] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 39 (1922F800) [pid = 1296] [serial = 1567] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 38 (15E71400) [pid = 1296] [serial = 1559] [outer = 00000000] [url = about:devtools-toolbox]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 37 (0FFD6C00) [pid = 1296] [serial = 1541] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 36 (0E8C8C00) [pid = 1296] [serial = 1551] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 35 (19D67C00) [pid = 1296] [serial = 1564] [outer = 00000000] [url = about:devtools-toolbox]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 34 (0F336000) [pid = 1296] [serial = 1550] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 33 (15E7B400) [pid = 1296] [serial = 1561] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 32 (0FFE1800) [pid = 1296] [serial = 1542] [outer = 00000000] [url = about:devtools-toolbox]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 31 (0E21C000) [pid = 1296] [serial = 1544] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 30 (0FFE1C00) [pid = 1296] [serial = 1572] [outer = 00000000] [url = about:devtools-toolbox]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 29 (0F3CBC00) [pid = 1296] [serial = 1574] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 28 (0F32E800) [pid = 1296] [serial = 1571] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 27 (009BDCA0) [pid = 1296] [serial = 1570] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 26 (13465400) [pid = 1296] [serial = 1575] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 25 (129ED940) [pid = 1296] [serial = 1573] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 24 (12983C00) [pid = 1296] [serial = 1577] [outer = 00000000] [url = about:blank]
14:44:08 INFO - GECKO(4884) | --DOMWINDOW == 23 (19D5CC00) [pid = 1296] [serial = 1582] [outer = 00000000] [url = about:blank]
14:44:10 INFO - GECKO(4884) | --DOMWINDOW == 1 (08B3C280) [pid = 1580] [serial = 346] [outer = 00000000] [url = about:blank]
14:44:10 INFO - TEST-INFO | started process screenshot
14:44:10 INFO - TEST-INFO | screenshot: exit 0
14:44:10 INFO - Buffered messages logged at 14:43:55
14:44:10 INFO - Entering test bound
14:44:10 INFO - Adding a new tab with URL: data:text/html;charset=utf-8,<p>Test keyboard accessibility</p>
14:44:10 INFO - <script>
14:44:10 INFO - for (let i = 1; i <= 100; i++) {
14:44:10 INFO - console.log("console message " + i);
14:44:10 INFO - }
14:44:10 INFO - </script>
14:44:10 INFO -
14:44:10 INFO - Tab added and finished loading
14:44:10 INFO - Opening the toolbox
14:44:10 INFO - Buffered messages logged at 14:43:56
14:44:10 INFO - Toolbox opened and focused
14:44:10 INFO - Web Console opened
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | scroll position changed after page up -
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | scroll position now at bottom -
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | scroll position now at top -
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | scroll position now at bottom -
14:44:10 INFO - try ctrl-l to clear output
14:44:10 INFO - Synthesizing key shortcut: Ctrl+Shift+L
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | console was cleared and input is focused -
14:44:10 INFO - try ctrl-f to focus filter
14:44:10 INFO - Synthesizing key shortcut: CmdOrCtrl+F
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | input is not focused -
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | filter input is focused -
14:44:10 INFO - try ctrl-f when filter is already focused
14:44:10 INFO - Synthesizing key shortcut: CmdOrCtrl+F
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | input is not focused -
14:44:10 INFO - TEST-PASS | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | filter input is focused -
14:44:10 INFO - Buffered messages logged at 14:43:57
14:44:10 INFO - Adding a new tab with URL: data:text/html;charset=utf-8,<p>Test keyboard accessibility</p>
14:44:10 INFO - <script>
14:44:10 INFO - for (let i = 1; i <= 100; i++) {
14:44:10 INFO - console.log("console message " + i);
14:44:10 INFO - }
14:44:10 INFO - </script>
14:44:10 INFO -
14:44:10 INFO - Tab added and finished loading
14:44:10 INFO - Opening the toolbox
14:44:10 INFO - Buffered messages logged at 14:44:03
14:44:10 INFO - Toolbox opened and focused
14:44:10 INFO - Web Console opened
14:44:10 INFO - Buffered messages finished
14:44:10 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | Uncaught exception - waitFor - timed out after 500 tries.
14:44:10 INFO - Leaving test bound
14:44:10 INFO - GECKO(4884) | ++DOMWINDOW == 24 (0B8EE400) [pid = 1296] [serial = 1586] [outer = 3A560280]
14:44:10 INFO - GECKO(4884) | console.warn: "Error while detaching the thread front: 'detach' request packet to 'server1.conn243.child1/context18' can't be sent as the connection is closed."
14:44:13 INFO - GECKO(4884) | --DOCSHELL 0FFE3000 == 9 [pid = 1296] [id = {cc062570-e91e-4731-911c-665756666d30}] [url = chrome://devtools/content/webconsole/index.html]
14:44:13 INFO - GECKO(4884) | --DOCSHELL 19C09000 == 8 [pid = 1296] [id = {95224b85-db08-4385-bc4d-87c9ee2947b8}] [url = about:blank]
14:44:13 INFO - Removing tab.
14:44:13 INFO - Waiting for event: 'TabClose' on [object XULElement].
14:44:13 INFO - Got event: 'TabClose' on [object XULElement].
14:44:13 INFO - Tab removed and finished closing
14:44:13 INFO - GECKO(4884) | [Child 3852, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
14:44:13 INFO - GECKO(4884) | ++DOMWINDOW == 25 (0E212400) [pid = 1296] [serial = 1587] [outer = 009BD160]
14:44:13 INFO - GECKO(4884) | console.warn: "Error while detaching the thread front: 'detach' request packet to 'server1.conn242.child1/context18' can't be sent as the connection is closed."
14:44:14 INFO - GECKO(4884) | --DOMWINDOW == 0 (01669400) [pid = 1580] [serial = 347] [outer = 00000000] [url = about:blank]
14:44:15 INFO - GECKO(4884) | --DOCSHELL 0B8EBC00 == 7 [pid = 1296] [id = {a2a5794f-8fc5-404e-b884-17560ad0e6fa}] [url = chrome://devtools/content/webconsole/index.html]
14:44:15 INFO - GECKO(4884) | --DOCSHELL 0F3D2C00 == 6 [pid = 1296] [id = {cd08b3ce-3763-4ad2-9c37-e7b54d34af06}] [url = about:blank]
14:44:15 INFO - Removing tab.
14:44:15 INFO - Waiting for event: 'TabClose' on [object XULElement].
14:44:15 INFO - GECKO(4884) | ++DOCSHELL 01661000 == 1 [pid = 1580] [id = {5584eaa0-03d3-44be-89a9-ee14b7903404}]
14:44:15 INFO - GECKO(4884) | ++DOMWINDOW == 1 (08B3C280) [pid = 1580] [serial = 348] [outer = 00000000]
14:44:15 INFO - GECKO(4884) | [Child 1580, Main Thread] WARNING: No active window: file z:/build/build/src/js/xpconnect/src/XPCJSContext.cpp, line 662
14:44:15 INFO - GECKO(4884) | ++DOMWINDOW == 2 (01669400) [pid = 1580] [serial = 349] [outer = 08B3C280]
14:44:15 INFO - Got event: 'TabClose' on [object XULElement].
14:44:15 INFO - GECKO(4884) | ++DOMWINDOW == 3 (08BC2000) [pid = 1580] [serial = 350] [outer = 08B3C280]
14:44:15 INFO - Tab removed and finished closing
14:44:15 INFO - GECKO(4884) | --DOMWINDOW == 24 (3A560280) [pid = 1296] [serial = 1581] [outer = 00000000] [url = about:blank]
14:44:15 INFO - GECKO(4884) | --DOMWINDOW == 23 (3A5603A0) [pid = 1296] [serial = 1584] [outer = 00000000] [url = chrome://devtools/content/webconsole/index.html]
14:44:15 INFO - GECKO(4884) | MEMORY STAT | vsize 1641MB | vsizeMaxContiguous 94MB | residentFast 620MB | heapAllocated 158MB
14:44:15 INFO - TEST-OK | devtools/client/webconsole/test/mochitest/browser_webconsole_keyboard_accessibility.js | took 20453ms

Assignee: nobody → nchevobbe
Status: NEW → ASSIGNED

The failure was happening on window7, which I guess is
pretty slow. Looking at the failure screenshot I was able
to see that the console output was empty.
The test is initially waiting for a 100 messages to be displayed
in the console output.
We are using the waitFor helper function for that. waitFor by default
call the function argument 500 times, at a 10ms interval, and then
fail.
Here, we bump the interval to 100ms, and set the max number of
tries to 500, in order to give extra time for the 100 messages
to be rendered in the output.

Pushed a TV job a re-ran it a few times without seeing the failure,
so the fix should be good.

Pushed by nchevobbe@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/ef2895162f2f
Fix intermittent failure on browser_webconsole_keyboard_accessibility.js. r=jdescottes.
Status: ASSIGNED → RESOLVED
Closed: 5 months ago
Resolution: --- → FIXED
Target Milestone: --- → Firefox 69
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Target Milestone: Firefox 69 → ---
Flags: needinfo?(nchevobbe)

(In reply to Alexandru Michis [:malexandru] from comment #13)

This issue has appeared again in a recent push: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=253885843&repo=autoland&lineNumber=29489

That's weird, the log makes it look like this is the previous version of the file, but navigating the tree from that push I can see that it has the current, "fixed", version.
Is there another way to show the content of the test file that is run, just to double check?

Flags: needinfo?(nchevobbe)

Nicolas this has reached the disabling queue and i made the patch.

Please take a look.

Flags: needinfo?(nchevobbe)
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]

I tried yesterday to add more logs so I can diagnose this, but I can't get the failure to show up (https://treeherder.mozilla.org/#/jobs?repo=try&revision=29d26a2900393d1e13b09e256e4e923ca8e78c9f&selectedJob=254418358)
I'll try again, but if I can't with TV, I guess this requires some other tests to be run before to have the failure

Flags: needinfo?(nchevobbe)

nchevobbe: Any update here?

We have 36 failures associated with this bug in the last 7 days, all on windows 7-32 - debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=259062830&repo=autoland&lineNumber=31149

Flags: needinfo?(nchevobbe)

The test was supposed to pass custom values to waitFor (for
the number of tries and the interval), but what we end up
using in the webconsole is the waitFor function from the
debugger helper, which is loaded via loadSubScript.
Since the debugger waitFor doesn't allow to pass custom
maxTries nor interval, we are not waiting long enough
for all the messages to be displayed on slow machines.
The quickfix here is to allow debugger waitFor to have
custom maxTries and interval.
A better fix would be to actually not load the waitFor
from the debugger, for example by avoiding loadSubScript
and using modules instead.

pushed a new fix

Flags: needinfo?(nchevobbe)
Pushed by nchevobbe@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/60ba89028ca8
Fix browser_webconsole_keyboard_accessibility.js intermittent. r=davidwalsh.
You need to log in before you can comment on or make changes to this bug.