1592854 - Intermittent devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | The textarea of logpoint panel is focused - Got BODY, expected TEXTAREA

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-10-30T23:52:12.299Z] 23:52:12 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js
[task 2019-10-30T23:52:12.356Z] 23:52:12 INFO - GECKO(1740) | ++DOCSHELL 0x105e49000 == 2 [pid = 1743] [id = {86ac10f5-3254-0243-a3af-be5369305196}]
[task 2019-10-30T23:52:12.356Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 4 (0x11e7513e0) [pid = 1743] [serial = 454] [outer = 0x0]
[task 2019-10-30T23:52:12.357Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 5 (0x105f34000) [pid = 1743] [serial = 455] [outer = 0x11e7513e0]
[task 2019-10-30T23:52:12.485Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 6 (0x11694cc00) [pid = 1743] [serial = 456] [outer = 0x11e7513e0]
[task 2019-10-30T23:52:12.551Z] 23:52:12 INFO - GECKO(1740) | --DOCSHELL 0x158e53800 == 7 [pid = 1740] [id = {2bd7ebec-1822-0e43-885e-12f8eee9f662}] [url = chrome://devtools/content/debugger/index.html]
[task 2019-10-30T23:52:12.709Z] 23:52:12 INFO - GECKO(1740) | ++DOCSHELL 0x125975000 == 8 [pid = 1740] [id = {d639d203-1ba2-1040-a066-d05ec749f4eb}]
[task 2019-10-30T23:52:12.710Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 21 (0x111963020) [pid = 1740] [serial = 1320] [outer = 0x0]
[task 2019-10-30T23:52:12.710Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 22 (0x111012800) [pid = 1740] [serial = 1321] [outer = 0x111963020]
[task 2019-10-30T23:52:12.710Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 23 (0x11c5b2c00) [pid = 1740] [serial = 1322] [outer = 0x111963020]
[task 2019-10-30T23:52:12.755Z] 23:52:12 INFO - GECKO(1740) | ++DOMWINDOW == 24 (0x12436c400) [pid = 1740] [serial = 1323] [outer = 0x111963020]
[task 2019-10-30T23:52:12.851Z] 23:52:12 INFO - GECKO(1740) | [Parent 1740, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsContentUtils.cpp, line 3697

[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - Got event: 'source-in-debugger-opened' on [object Object].
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | source url found ("http://example.com/browser/devtools/client/webconsole/test/browser/test-location-debugger-link-logpoint-1.js") -
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | expected source url -
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - Buffered messages finished
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | The textarea of logpoint panel is focused - Got BODY, expected TEXTAREA
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - Stack trace:
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:test_is:1314
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:checkClickOnNode:531
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:testOpenInDebugger:455
[task 2019-10-30T23:52:22.458Z] 23:52:22 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js:null:65
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1069
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:932
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:537 - TypeError: inputEl.parentElement.parentElement is null
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - Stack trace:
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - checkClickOnNode@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:537:24
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - asynctestOpenInDebugger@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:455:9
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - async@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js:65:9
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1069:34
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1104:11
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:932:14
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:805:67
[task 2019-10-30T23:52:22.459Z] 23:52:22 INFO - Leaving test bound
[task 2019-10-30T23:52:22.460Z] 23:52:22 INFO - Entering test bound
[task 2019-10-30T23:52:22.460Z] 23:52:22 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-location-debugger-link-logpoint.html

Comment 1

[Andreea Pavel [:apavel]]

Nicolas can you assign someone to take a look at this?

Retriggers got me here so far: https://treeherder.mozilla.org/#/jobs?repo=autoland&selectedJob=273815476&searchStr=OS%2CX%2C10.14%2Cdebug%2CMochitests%2Ctest-macosx1014-64%2Fdebug-mochitest-devtools-chrome-e10s-7%2CM%28dt7%29&tochange=0fc431c94ad2f635a98b2a0a1682d1f2c4055034&fromchange=9fd190b08922cd13e69b28ed4525d713aae4258a

Comment 2

[Intermittent Failures Robot]

25 failures in 814 pushes (0.031 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 25

Platform breakdown:

• macosx1014-64: 24
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-10-30&endday=2019-10-30&tree=all

Comment 3

[Cosmin Sabou [:CosminS]]

Brian, could you please take a look? https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=os%2Cx%2C10.14%2Cdebug%2Cmochitests%2Ctest-macosx1014-64%2Fdebug-mochitest-devtools-chrome-e10s-7%2Cm%28dt7%29&tochange=0fc431c94ad2f635a98b2a0a1682d1f2c4055034&fromchange=9fd190b08922cd13e69b28ed4525d713aae4258a&selectedJob=273815484

Comment 4

[Intermittent Failures Robot]

70 failures in 703 pushes (0.1 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 62
• mozilla-central: 4
• mozilla-inbound: 1
• try: 3

Platform breakdown:

• macosx1014-64: 61
• macosx1014-64-shippable: 8
• macosx1014-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-10-31&endday=2019-10-31&tree=all

Comment 5

[Brian Hackett [Laid off!]]

I can reproduce this locally, there seems to be some strange interaction between the conditional panel and the breakpoints state in the debugger editor. This is testing that clicking on a logpoint message in the console opens up the debugger with the conditional panel open and showing the logpoint value. When the test fails, there is a SET_BREAKPOINT action that comes in after the last time the conditional panel was rendered, and the conditional panel disappears for some reason (looking at the reducer operations and rendering calls to the editor, it looks like it should be shown). If I pause the test when the conditional panel is correctly being shown, I see some weird behavior when clicking on the gutter --- breakpoints are not added/removed, and sometimes the panel goes away and breakpoints appear in places I didn't try to add them. I don't have much knowledge of how the debugger interacts with the code mirror editor display, but there seem to be problems here which bug 1558771 seems to have exposed. If I introduce a delay into the test when clicking on the console locations then the problem goes away, so a temporary workaround like this might be best for now to fix the test failures.

Comment 6

[Brian Hackett [Laid off!]]

Attachment: Bug 1592854 - Workaround rendering problems with conditional panel during test, r=loganfsmyth.

Comment 7

[Intermittent Failures Robot]

97 failures in 783 pushes (0.124 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 88
• mozilla-central: 6
• try: 3

Platform breakdown:

• macosx1014-64: 81
• macosx1014-64-shippable: 16

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-11-01&endday=2019-11-01&tree=all

Comment 8

[Intermittent Failures Robot]

27 failures in 224 pushes (0.121 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 17
• mozilla-central: 6
• try: 4

Platform breakdown:

• macosx1014-64: 20
• macosx1014-64-shippable: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-11-02&endday=2019-11-02&tree=all

Comment 9

[Andreea Pavel [:apavel]]

There are 242 total failures in the last 7 days on macosx debug.

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

[task 2019-11-03T17:40:59.219Z] 17:40:59 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js
[task 2019-11-03T17:40:59.261Z] 17:40:59 INFO - GECKO(1749) | ++DOCSHELL 0x104d4c800 == 2 [pid = 1753] [id = {f915e1ed-1004-3445-a53f-239405265c21}]
[task 2019-11-03T17:40:59.261Z] 17:40:59 INFO - GECKO(1749) | ++DOMWINDOW == 3 (0x11d753020) [pid = 1753] [serial = 519] [outer = 0x0]
[task 2019-11-03T17:40:59.261Z] 17:40:59 INFO - GECKO(1749) | ++DOMWINDOW == 4 (0x105638800) [pid = 1753] [serial = 520] [outer = 0x11d753020]
[task 2019-11-03T17:40:59.367Z] 17:40:59 INFO - GECKO(1749) | ++DOMWINDOW == 5 (0x10779fc00) [pid = 1753] [serial = 521] [outer = 0x11d753020]
[task 2019-11-03T17:40:59.507Z] 17:40:59 INFO - GECKO(1749) | --DOMWINDOW == 25 (0x11c8b2020) [pid = 1749] [serial = 1304] [outer = 0x0] [url = chrome://devtools/content/webconsole/index.html]

[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | source url found ("http://example.com/browser/devtools/client/webconsole/test/browser/test-location-debugger-link-logpoint-2.js") -
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | expected source url -
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - Buffered messages finished
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | The textarea of logpoint panel is focused - Got BODY, expected TEXTAREA
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - Stack trace:
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - chrome://mochikit/content/browser-test.js:test_is:1314
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:checkClickOnNode:531
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:testOpenInDebugger:455
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js:null:151
[task 2019-11-03T17:41:21.665Z] 17:41:21 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1069
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1104
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:932
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js | Uncaught exception - at chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:537 - TypeError: inputEl.parentElement.parentElement is null
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - Stack trace:
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - checkClickOnNode@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:537:24
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - asynctestOpenInDebugger@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/head.js:455:9
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - async@chrome://mochitests/content/browser/devtools/client/webconsole/test/browser/browser_webconsole_location_logpoint_debugger_link.js:151:9
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - AsyncTester_execTest/<@chrome://mochikit/content/browser-test.js:1069:34
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - asyncTester_execTest@chrome://mochikit/content/browser-test.js:1104:11
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:932:14
[task 2019-11-03T17:41:21.666Z] 17:41:21 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:805:67

Removing the disable recommended tag since there is a patch here waiting for review.

Comment 10

[Intermittent Failures Robot]

260 failures in 4314 pushes (0.06 failures/push) were associated with this bug in the last 7 days.

This is the #1 most frequent failure this week.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 226
• mozilla-inbound: 1
• mozilla-central: 18
• try: 15

Platform breakdown:

• macosx1014-64: 218
• macosx1014-64-shippable: 39
• macosx1014-64-devedition: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-10-28&endday=2019-11-03&tree=all

Comment 11

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/dc6f47b6ce58
Workaround rendering problems with conditional panel during test, r=loganfsmyth.

Comment 12

[Intermittent Failures Robot]

108 failures in 862 pushes (0.125 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 103
• mozilla-central: 2
• try: 3

Platform breakdown:

• macosx1014-64: 76
• macosx1014-64-shippable: 31
• android-hw-p2-8-0-android-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-11-04&endday=2019-11-04&tree=all

Comment 13

[Arthur Iakab [arthur_iakab]]

https://hg.mozilla.org/mozilla-central/rev/dc6f47b6ce58

Comment 14

[Andreea Pavel [:apavel]]

No occurrences since November 5th.

Comment 15

[Intermittent Failures Robot]

115 failures in 4850 pushes (0.024 failures/push) were associated with this bug in the last 7 days.

This is the #9 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 108
• mozilla-central: 4
• try: 3

Platform breakdown:

• macosx1014-64: 78
• macosx1014-64-shippable: 36
• android-hw-p2-8-0-android-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1592854&startday=2019-11-04&endday=2019-11-10&tree=all