Open Bug 1814093 Opened 1 year ago Updated 3 months ago

Intermittent devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | single tracking bug

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned, NeedInfo)

Details

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

Attachments

(1 file, 1 obsolete file)

Filed by: chorotan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=404048131&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/BOHEc6_DSwSKPaZ_Qdkcng/runs/0/artifacts/public/logs/live_backing.log


task 2023-01-31T12:18:05.363Z] 12:18:05     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | Frame paused at line 6, but expected line 6 - 
[task 2023-01-31T12:18:05.364Z] 12:18:05     INFO - Resume and pause on the debugger statement
[task 2023-01-31T12:18:05.365Z] 12:18:05     INFO - Buffered messages finished
[task 2023-01-31T12:18:05.365Z] 12:18:05     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | Test timed out - 
[task 2023-01-31T12:18:05.366Z] 12:18:05     INFO - GECKO(1616) | JavaScript error: resource://devtools/server/actors/targets/window-global.js, line 461: TypeError: can't access property "domWindow", docShell is null
[task 2023-01-31T12:18:05.367Z] 12:18:05     INFO - GECKO(1616) | console.error: "Error in enumIndexedProperties" (new Error("Connection closed, pending request to server0.conn0.windowGlobal1282047737858/obj74, type enumProperties failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:300:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19\nenumProperties@resource://devtools/client/fronts/object.js:150:18\nenumIndexedProperties@resource://devtools/client/shared/components/object-inspector/utils/client.js:12:40\nloadItemProperties@resource://devtools/client/shared/components/object-inspector/utils/load-properties.js:62:19\nloadObjectProperties@resource://devtools/client/debugger/src/client/firefox/commands.js:49:49\ngenerateInlinePreview/</previewBindings<@resource://devtools/client/debugger/src/actions/pause/inlinePreview.js:87:37\ngenerateInlinePreview/<@resource://devtools/client/debugger/src/actions/pause/inlinePreview.js:80:53\nasync*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nfetchScopes/</<@resource://devtools/client/debugger/src/actions/pause/fetchScopes.js:35:15\nsetTimeout handler*executeSoon@resource://devtools/client/debugger/src/utils/DevToolsUtils.js:23:13\npromiseMiddleware/</</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:109\npromiseMiddleware/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:55\nAsync*promiseMiddleware/</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:41\ncontext/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:35:12\nthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nfetchScopes/<@resource://devtools/client/debugger/src/actions/pause/fetchScopes.js:27:28\nthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\npaused/<@resource://devtools/client/debugger/src/actions/pause/paused.js:77:11\nasync*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\nonThreadStateAvailable@resource://devtools/client/debugger/src/client/firefox.js:184:21\nasync*_notifyWatchers@resource://devtools/shared/commands/resource/resource-command.js:872:24\nlater@resource://devtools/shared/throttle.js:33:19\nnotify@resource://gre/modules/Timer.sys.mjs:49:17\n", "resource://devtools/shared/protocol/Front.js", 106))
[task 2023-01-31T12:18:05.370Z] 12:18:05     INFO - GECKO(1616) | console.error: "Error in enumNonIndexedProperties" (new Error("Connection closed, pending request to server0.conn0.windowGlobal1282047737858/obj74, type enumProperties failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:300:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19\nenumProperties@resource://devtools/client/fronts/object.js:150:18\nenumNonIndexedProperties@resource://devtools/client/shared/components/object-inspector/utils/client.js:25:40\nloadItemProperties@resource://devtools/client/shared/components/object-inspector/utils/load-properties.js:66:19\nloadObjectProperties@resource://devtools/client/debugger/src/client/firefox/commands.js:49:49\ngenerateInlinePreview/</previewBindings<@resource://devtools/client/debugger/src/actions/pause/inlinePreview.js:87:37\ngenerateInlinePreview/<@resource://devtools/client/debugger/src/actions/pause/inlinePreview.js:80:53\nasync*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nfetchScopes/</<@resource://devtools/client/debugger/src/actions/pause/fetchScopes.js:35:15\nsetTimeout handler*executeSoon@resource://devtools/client/debugger/src/utils/DevToolsUtils.js:23:13\npromiseMiddleware/</</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:109\npromiseMiddleware/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:55\nAsync*promiseMiddleware/</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:41\ncontext/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:35:12\nthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nfetchScopes/<@resource://devtools/client/debugger/src/actions/pause/fetchScopes.js:27:28\nthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\npaused/<@resource://devtools/client/debugger/src/actions/pause/paused.js:77:11\nasync*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\nonThreadStateAvailable@resource://devtools/client/debugger/src/client/firefox.js:184:21\nasync*_notifyWatchers@resource://devtools/shared/commands/resource/resource-command.js:872:24\nlater@resource://devtools/shared/throttle.js:33:19\nnotify@resource://gre/modules/Timer.sys.mjs:49:17\n", "resource://devtools/shared/protocol/Front.js", 106))
[task 2023-01-31T12:18:05.375Z] 12:18:05     INFO - GECKO(1616) | console.error: "Error in enumSymbols" (new Error("Connection closed, pending request to server0.conn0.windowGlobal1282047737858/obj74, type enumSymbols failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:300:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19\nenumSymbols@resource://devtools/client/fronts/object.js:179:18\nenumSymbols@resource://devtools/client/shared/components/object-inspector/utils/client.js:49:40\nloadItemProperties@resource://devtools/client/shared/components/object-inspector/utils/load-properties.js:82:19\nloadObjectProperties@resource://devtools/client/debugger/src/client/firefox/commands.js:49:49\ngenerateInlinePreview/</previewBindings<@resource://devtools/client/debugger/src/actions/pause/inlinePreview.js:87:37\ngenerateInlinePreview/<@resource://devtools/client/debugger/src/actions/pause/inlinePreview.js:80:53\nasync*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nfetchScopes/</<@resource://devtools/client/debugger/src/actions/pause/fetchScopes.js:35:15\nsetTimeout handler*executeSoon@resource://devtools/client/debugger/src/utils/DevToolsUtils.js:23:13\npromiseMiddleware/</</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:109\npromiseMiddleware/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:55\nAsync*promiseMiddleware/</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:57:41\ncontext/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:35:12\nthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nfetchScopes/<@resource://devtools/client/debugger/src/actions/pause/fetchScopes.js:27:28\nthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\npaused/<@resource://devtools/client/debugger/src/actions/pause/paused.js:77:11\nasync*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\nonThreadStateAvailable@resource://devtools/client/debugger/src/client/firefox.js:184:21\nasync*_notifyWatchers@resource://devtools/shared/commands/resource/resource-command.js:872:24\nlater@resource://devtools/shared/throttle.js:33:19\nnotify@resource://gre/modules/Timer.sys.mjs:49:17\n", "resource://devtools/shared/protocol/Front.js", 106))
[task 2023-01-31T12:18:05.681Z] 12:18:05     INFO - Resuming from 6
[task 2023-01-31T12:18:05.681Z] 12:18:05     INFO - Waiting for the debugger to resume
[task 2023-01-31T12:18:05.682Z] 12:18:05     INFO - Waiting for state change: 
[task 2023-01-31T12:18:05.682Z] 12:18:05     INFO - Removing tab.
[task 2023-01-31T12:18:05.683Z] 12:18:05     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2023-01-31T12:18:05.701Z] 12:18:05     INFO - Got event: 'TabClose' on [object XULElement].
[task 2023-01-31T12:18:05.722Z] 12:18:05     INFO - Tab removed and finished closing
[task 2023-01-31T12:18:05.728Z] 12:18:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | Uncaught exception received from previously timed out test - at resource://devtools/client/debugger/src/client/firefox/commands.js:71 - TypeError: can't access property "actor", currentThreadFront() is null
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - Stack trace:
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - lookupTarget@resource://devtools/client/debugger/src/client/firefox/commands.js:71:17
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - lookupThreadFront@resource://devtools/client/debugger/src/client/firefox/commands.js:80:30
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - resume@resource://devtools/client/debugger/src/client/firefox/commands.js:102:10
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - command/<@resource://devtools/client/debugger/src/actions/pause/commands.js:94:39
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2023-01-31T12:18:05.730Z] 12:18:05     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:681:28
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - resume/<@resource://devtools/client/debugger/src/actions/pause/commands.js:176:12
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - bindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - resume@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/shared-head.js:822:21
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - @chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js:85:9
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - Async*handleTask@chrome://mochikit/content/browser-test.js:1037:26
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1109:18
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1251:14
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1026:14
[task 2023-01-31T12:18:05.731Z] 12:18:05     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1036:13

First occurrence so far: range of retriggers and backfills

Whiteboard: [retriggered]
Summary: Intermittent devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | single tracking bug → Frequent devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | single tracking bug

Update

There have been 70 failures within the last 7 days:

  • 3 failures on Linux 18.04 x64 WebRender tsan opt
  • 15 failures on OS X 10.15 WebRender opt
  • 10 failures on OS X 10.15 WebRender Shippable opt
  • 33 failures on Windows 11 x64 22H2 WebRender opt
  • 9 failures on Windows 11 x64 22H2 WebRender Shippable opt

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

[task 2023-04-16T09:15:02.367Z] 09:15:02     INFO - 
[task 2023-04-16T09:15:02.368Z] 09:15:02     INFO - Console message: [JavaScript Error: "Error: Can't find profile directory." {file: "resource://gre/modules/XULStore.sys.mjs" line: 60}]
[task 2023-04-16T09:15:02.368Z] 09:15:02     INFO - load@resource://gre/modules/XULStore.sys.mjs:60:15
[task 2023-04-16T09:15:02.368Z] 09:15:02     INFO - XULStore@resource://gre/modules/XULStore.sys.mjs:17:10
[task 2023-04-16T09:15:02.368Z] 09:15:02     INFO - enter@resource://devtools/server/actors/utils/event-loop.js:82:18
[task 2023-04-16T09:15:02.368Z] 09:15:02     INFO - _pauseAndRespond@resource://devtools/server/actors/thread.js:958:29
[task 2023-04-16T09:15:02.369Z] 09:15:02     INFO - onDebuggerStatement@resource://devtools/server/actors/thread.js:1907:17
[task 2023-04-16T09:15:02.369Z] 09:15:02     INFO - @https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-watchpoints.html:11:5
[task 2023-04-16T09:15:02.369Z] 09:15:02     INFO - 
[task 2023-04-16T09:15:02.369Z] 09:15:02     INFO - Finished waiting for state change: source doc-watchpoints.html exists
[task 2023-04-16T09:15:02.369Z] 09:15:02     INFO - Finished waiting on sources: doc-watchpoints.html
[task 2023-04-16T09:15:02.370Z] 09:15:02     INFO - Buffered messages logged at 09:14:18
[task 2023-04-16T09:15:02.370Z] 09:15:02     INFO - Waiting for state change: selected source
[task 2023-04-16T09:15:02.371Z] 09:15:02     INFO - Buffered messages finished
[task 2023-04-16T09:15:02.371Z] 09:15:02     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | Test timed out - 
[task 2023-04-16T09:15:02.392Z] 09:15:02     INFO - Removing tab.
[task 2023-04-16T09:15:02.393Z] 09:15:02     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2023-04-16T09:15:02.398Z] 09:15:02     INFO - Got event: 'TabClose' on [object XULElement].
[task 2023-04-16T09:15:02.407Z] 09:15:02     INFO - Tab removed and finished closing
[task 2023-04-16T09:15:02.415Z] 09:15:02     INFO - GECKO(6908) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2023-04-16T09:15:02.415Z] 09:15:02     INFO - GECKO(6908) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2023-04-16T09:15:02.416Z] 09:15:02     INFO - GECKO(6908) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2023-04-16T09:15:02.420Z] 09:15:02     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2023-04-16T09:15:02.420Z] 09:15:02     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2023-04-16T09:15:02.421Z] 09:15:02     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2023-04-16T09:15:02.422Z] 09:15:02     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2023-04-16T09:15:02.653Z] 09:15:02     INFO - GECKO(6908) | MEMORY STAT | vsize 8817MB | residentFast 1020MB | heapAllocated 262MB
[task 2023-04-16T09:15:02.653Z] 09:15:02     INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | took 45558ms

Hubert, can you check this out too?
Thank you.

Flags: needinfo?(hmanilla)
Whiteboard: [retriggered] → [retriggered][stockwell needswork:owner]
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered]
Assignee: nobody → csabou
Status: NEW → ASSIGNED
Pushed by csabou@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/45634c9c4747
Disable browser_dbg-watchpoints.js because of frequent failures. r=intermittent-reviewers,jmaher DONTBUILD
Assignee: csabou → nobody
Status: ASSIGNED → NEW
Keywords: leave-open
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disabled]
Summary: Frequent devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | single tracking bug → Intermittent devtools/client/debugger/test/mochitest/browser_dbg-watchpoints.js | single tracking bug
Attachment #9387036 - Attachment is obsolete: true
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: