Closed Bug 1832135 Opened 2 years ago Closed 1 year ago

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

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [stockwell disabled][stockwell unknown])

Attachments

(2 files, 1 obsolete file)

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


[task 2023-05-09T16:04:39.392Z] 16:04:39     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | We stopped recording traces, an the function call isn't logged in the console - 
[task 2023-05-09T16:04:39.398Z] 16:04:39     INFO - Navigating to "https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-sourcemaps2.html"
[task 2023-05-09T16:04:39.399Z] 16:04:39     INFO - Waiting for page to be loaded…
[task 2023-05-09T16:04:39.400Z] 16:04:39     INFO - Buffered messages logged at 16:03:22
[task 2023-05-09T16:04:39.401Z] 16:04:39     INFO - → page loaded
[task 2023-05-09T16:04:39.402Z] 16:04:39     INFO - Wait for the toolbox to reload
[task 2023-05-09T16:04:39.404Z] 16:04:39     INFO - Waiting for toolbox commands to be reloaded…
[task 2023-05-09T16:04:39.405Z] 16:04:39     INFO - Waiting for target switch…
[task 2023-05-09T16:04:39.406Z] 16:04:39     INFO - → switched-target emitted
[task 2023-05-09T16:04:39.407Z] 16:04:39     INFO - Waiting for 'dom-complete' resource…
[task 2023-05-09T16:04:39.409Z] 16:04:39     INFO - Console message: [JavaScript Warning: "Source map error: Error: NetworkError when attempting to fetch resource.
[task 2023-05-09T16:04:39.409Z] 16:04:39     INFO - Resource URL: https://example.com/browser/devtools/client/debugger/test/mochitest/examples/sourcemaps2/main.min.js
[task 2023-05-09T16:04:39.409Z] 16:04:39     INFO - Source Map URL: main.js.map"]
[task 2023-05-09T16:04:39.411Z] 16:04:39     INFO - → 'dom-complete' resource emitted
[task 2023-05-09T16:04:39.412Z] 16:04:39     INFO - Waiting for jsdebugger to be reloaded…
[task 2023-05-09T16:04:39.413Z] 16:04:39     INFO - Waiting for jsdebugger updates after page reload
[task 2023-05-09T16:04:39.414Z] 16:04:39     INFO - → panel reloaded
[task 2023-05-09T16:04:39.415Z] 16:04:39     INFO - Wait for Responsive UI to reload
[task 2023-05-09T16:04:39.416Z] 16:04:39     INFO - Waiting on sources: main.js, main.min.js
[task 2023-05-09T16:04:39.418Z] 16:04:39     INFO - Waiting for state change: source main.js exists
[task 2023-05-09T16:04:39.418Z] 16:04:39     INFO - Buffered messages finished
[task 2023-05-09T16:04:39.420Z] 16:04:39     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | Test timed out - 
[task 2023-05-09T16:04:41.359Z] 16:04:41     INFO - Removing tab.
[task 2023-05-09T16:04:41.360Z] 16:04:41     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2023-05-09T16:04:41.403Z] 16:04:41     INFO - Got event: 'TabClose' on [object XULElement].
[task 2023-05-09T16:04:41.441Z] 16:04:41     INFO - GECKO(1600) | [Parent 1600, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2023-05-09T16:04:41.442Z] 16:04:41     INFO - GECKO(1600) | [Parent 1600, Main Thread] WARNING: '!inner', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorProtocol.cpp:163
[task 2023-05-09T16:04:41.452Z] 16:04:41     INFO - Tab removed and finished closing
[task 2023-05-09T16:04:41.454Z] 16:04:41     INFO - GECKO(1600) | [Child 9913: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f1670632800 == 1 [pid = 9913] [id = 0] [url = https://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scripts.html]
[task 2023-05-09T16:04:41.491Z] 16:04:41     INFO - GECKO(1600) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2023-05-09T16:04:41.495Z] 16:04:41     INFO - GECKO(1600) | [Parent 1600: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f4fcd13b800 == 7 [pid = 1600] [id = 219] [url = chrome://devtools/content/debugger/index.html]
[task 2023-05-09T16:04:41.497Z] 16:04:41     INFO - GECKO(1600) | [Parent 1600: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f4fcd167c00 == 6 [pid = 1600] [id = 220] [url = chrome://devtools/content/webconsole/index.html]
[task 2023-05-09T16:04:41.519Z] 16:04:41     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]

a11y_checks failure seems to be triggered by this click and since the <body> is not expected to be focusable/interactive, we would need to setEnv to change the mustBeAccessible rule to false for this click alone.

Example of similar exception from a11y_checks: https://searchfox.org/mozilla-central/rev/b580e3f77470b2337bc8ae032b58a85c11e66aba/browser/components/extensions/test/browser/browser_ext_browserAction_popup_preload.js#73-79,85

Depends on: 1870710
Depends on: 1871192

Update:
There have been 48 failures within the last 7 days:
• 45 failures on Linux 18.04 x64 WebRender tsan opt
• 3 failures on OS X 10.15 WebRender opt
All the failures on linux have this failure line: TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | A promise chain failed to handle a rejection: Unsupported tracer log method null - stack: transformTracerStateResource@resource://devtools/client/webconsole/utils/messages.js:431:13
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=443233703&repo=mozilla-central&lineNumber=9260

[task 2024-01-13T23:05:48.474Z] 23:05:48     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | Even after the click, the code called before the click is still not logged - 
[task 2024-01-13T23:05:48.474Z] 23:05:48     INFO - Invoking in tab: foo()
[task 2024-01-13T23:05:48.475Z] 23:05:48     INFO - Buffered messages logged at 23:05:48
[task 2024-01-13T23:05:48.475Z] 23:05:48     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | foo was traced as expected - 
[task 2024-01-13T23:05:48.475Z] 23:05:48     INFO - Buffered messages finished
[task 2024-01-13T23:05:48.480Z] 23:05:48     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | A promise chain failed to handle a rejection: Unsupported tracer log method null - stack: transformTracerStateResource@resource://devtools/client/webconsole/utils/messages.js:431:13
[task 2024-01-13T23:05:48.480Z] 23:05:48     INFO - transformResource@resource://devtools/client/webconsole/utils/messages.js:130:14
[task 2024-01-13T23:05:48.480Z] 23:05:48     INFO - prepareMessage@resource://devtools/client/webconsole/utils/messages.js:89:16
[task 2024-01-13T23:05:48.481Z] 23:05:48     INFO - messagesAdd/messages<@resource://devtools/client/webconsole/actions/messages.js:40:19
[task 2024-01-13T23:05:48.481Z] 23:05:48     INFO - messagesAdd@resource://devtools/client/webconsole/actions/messages.js:39:28
[task 2024-01-13T23:05:48.481Z] 23:05:48     INFO - setTimeoutIfNeeded/this.throttledDispatchPromise</<@resource://devtools/client/webconsole/webconsole-wrapper.js:394:19
[task 2024-01-13T23:05:48.481Z] 23:05:48     INFO - setTimeout handler*setTimeoutIfNeeded/this.throttledDispatchPromise<@resource://devtools/client/webconsole/webconsole-wrapper.js:381:17
[task 2024-01-13T23:05:48.481Z] 23:05:48     INFO - setTimeoutIfNeeded@resource://devtools/client/webconsole/webconsole-wrapper.js:380:37
[task 2024-01-13T23:05:48.481Z] 23:05:48     INFO - batchedMessagesAdd@resource://devtools/client/webconsole/webconsole-wrapper.js:340:12
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - dispatchMessagesAdd@resource://devtools/client/webconsole/webconsole-wrapper.js:179:10
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - _onResourceAvailable@resource://devtools/client/webconsole/webconsole-ui.js:513:18
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - _notifyWatchers@resource://devtools/shared/commands/resource/resource-command.js:871:24
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - throttledFunction@resource://devtools/shared/throttle.js:45:21
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - _onResourceAvailable@resource://devtools/shared/commands/resource/resource-command.js:720:12
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - _emit@resource://devtools/shared/event-emitter.js:242:32
[task 2024-01-13T23:05:48.482Z] 23:05:48     INFO - emit@resource://devtools/shared/event-emitter.js:186:18
[task 2024-01-13T23:05:48.483Z] 23:05:48     INFO - emit@resource://devtools/shared/event-emitter.js:330:18
[task 2024-01-13T23:05:48.483Z] 23:05:48     INFO - onPacket@resource://devtools/shared/protocol/Front.js:348:13
[task 2024-01-13T23:05:48.483Z] 23:05:48     INFO - onPacket@resource://devtools/client/devtools-client.js:471:13
[task 2024-01-13T23:05:48.483Z] 23:05:48     INFO - send/<@resource://devtools/shared/transport/local-transport.js:67:25
[task 2024-01-13T23:05:48.483Z] 23:05:48     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2024-01-13T23:05:48.483Z] 23:05:48     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:64:21
[task 2024-01-13T23:05:48.484Z] 23:05:48     INFO - send@resource://devtools/shared/transport/local-transport.js:55:21
[task 2024-01-13T23:05:48.484Z] 23:05:48     INFO - send@resource://devtools/server/devtools-server-connection.js:96:20
[task 2024-01-13T23:05:48.484Z] 23:05:48     INFO - _onPacketReceived@resource://devtools/shared/transport/js-window-actor-transport.js:53:18
[task 2024-01-13T23:05:48.484Z] 23:05:48     INFO - emit@resource://gre/modules/EventEmitter.sys.mjs:148:20
[task 2024-01-13T23:05:48.484Z] 23:05:48     INFO - receiveMessage@resource://devtools/server/connectors/js-window-actor/DevToolsFrameParent.sys.mjs:238:21
[task 2024-01-13T23:05:48.485Z] 23:05:48     INFO - JSActor query*sendPacket@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.sys.mjs:378:10
[task 2024-01-13T23:05:48.485Z] 23:05:48     INFO - send@resource://devtools/shared/transport/js-window-actor-transport.js:58:25
[task 2024-01-13T23:05:48.485Z] 23:05:48     INFO - send@resource://devtools/server/devtools-server-connection.js:96:20
[task 2024-01-13T23:05:48.485Z] 23:05:48     INFO - _sendEvent@resource://devtools/shared/protocol/Actor.js:73:15
[task 2024-01-13T23:05:48.485Z] 23:05:48     INFO - Actor/<@resource://devtools/shared/protocol/Actor.js:47:16
[task 2024-01-13T23:05:48.485Z] 23:05:48     INFO - _emit@resource://devtools/shared/event-emitter.js:242:32
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - emit@resource://devtools/shared/event-emitter.js:186:18
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - emit@resource://devtools/shared/event-emitter.js:330:18
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - notifyResources@resource://devtools/server/actors/targets/base-target-actor.js:90:10
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - onTracingToggled@resource://devtools/server/actors/resources/jstracer-state.js:62:10
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - notifyToggle@resource://devtools/server/tracer/tracer.jsm:310:39
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - JavaScriptTracer@resource://devtools/server/tracer/tracer.jsm:171:10
[task 2024-01-13T23:05:48.486Z] 23:05:48     INFO - startTracing@resource://devtools/server/tracer/tracer.jsm:556:20
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - #startTracing@resource://devtools/server/actors/tracer.js:135:17
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - startTracing@resource://devtools/server/actors/tracer.js:104:23
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - handler@resource://devtools/shared/protocol/Actor.js:198:37
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - onPacket@resource://devtools/server/devtools-server-connection.js:379:58
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - _onPacketReceived@resource://devtools/shared/transport/js-window-actor-transport.js:53:18
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - emit@resource://gre/modules/EventEmitter.sys.mjs:148:20
[task 2024-01-13T23:05:48.487Z] 23:05:48     INFO - receiveMessage@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.sys.mjs:460:21
[task 2024-01-13T23:05:48.488Z] 23:05:48     INFO - JSActor query*sendPacket@resource://devtools/server/connectors/js-window-actor/DevToolsFrameParent.sys.mjs:226:10
[task 2024-01-13T23:05:48.488Z] 23:05:48     INFO - send@resource://devtools/shared/transport/js-window-actor-transport.js:58:25
[task 2024-01-13T23:05:48.488Z] 23:05:48     INFO - onPacket@resource://devtools/server/devtools-server-connection.js:355:21
[task 2024-01-13T23:05:48.488Z] 23:05:48     INFO - send/<@resource://devtools/shared/transport/local-transport.js:67:25
[task 2024-01-13T23:05:48.488Z] 23:05:48     INFO - Rejection date: Sat Jan 13 2024 23:05:46 GMT+0000 (Coordinated Universal Time) - false == true - {"filename":"resource://testing-common/PromiseTestUtils.sys.mjs","name":"assertNoUncaughtRejections","sourceId":586,"lineNumber":266,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"handleTask","sourceId":551,"lineNumber":1166,"columnNumber":22,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"_runTaskBasedTest","sourceId":551,"lineNumber":1210,"columnNumber":18,"sourceLine":"","asyncCause":null,"asyncCaller":{"filename":"chrome://mochikit/content/browser-test.js","name":"Tester_execTest","sourceId":551,"lineNumber":1352,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/browser-test.js","name":"nextTest/<","sourceId":551,"lineNumber":1127,"columnNumber":14,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":{"filename":"chrome://mochikit/content/tests/SimpleTest/SimpleTest.js","name":"SimpleTest.waitForFocus/<","sourceId":577,"lineNumber":1058,"columnNumber":13,"sourceLine":"","asyncCause":null,"asyncCaller":null,"caller":null,"formattedStack":"SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"nextTest/<@chrome://mochikit/content/browser-test.js:1127:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"async*Tester_execTest@chrome://mochikit/content/browser-test.js:1352:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1127:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"async*_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1210:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1352:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1127:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"caller":null,"formattedStack":"handleTask@chrome://mochikit/content/browser-test.js:1166:22\nasync*_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1210:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1352:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1127:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}},"formattedStack":"assertNoUncaughtRejections@resource://testing-common/PromiseTestUtils.sys.mjs:266:14\nhandleTask@chrome://mochikit/content/browser-test.js:1166:22\nasync*_runTaskBasedTest@chrome://mochikit/content/browser-test.js:1210:18\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1352:14\nnextTest/<@chrome://mochikit/content/browser-test.js:1127:14\nSimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13\n","nativeSavedFrame":{}}
[task 2024-01-13T23:05:48.488Z] 23:05:48     INFO - Stack trace:
[task 2024-01-13T23:05:48.489Z] 23:05:48     INFO - resource://testing-common/PromiseTestUtils.sys.mjs:assertNoUncaughtRejections:266
[task 2024-01-13T23:05:48.494Z] 23:05:48     INFO - chrome://mochikit/content/browser-test.js:handleTask:1166
[task 2024-01-13T23:05:48.494Z] 23:05:48     INFO - chrome://mochikit/content/browser-test.js:_runTaskBasedTest:1210
[task 2024-01-13T23:05:48.495Z] 23:05:48     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1352
[task 2024-01-13T23:05:48.495Z] 23:05:48     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1127
[task 2024-01-13T23:05:48.495Z] 23:05:48     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1058
[task 2024-01-13T23:05:48.495Z] 23:05:48     INFO - Leaving test bound testTracingOnNextInteraction
[task 2024-01-13T23:05:48.496Z] 23:05:48     INFO - GECKO(1327) | JavaScript error: resource://devtools/shared/protocol/Pool.js, line 215: TypeError: can't access property "removeActorPool", this.conn is null
[task 2024-01-13T23:05:49.428Z] 23:05:49     INFO - Removing tab.
[task 2024-01-13T23:05:49.429Z] 23:05:49     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:49.500Z] 23:05:49     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:49.596Z] 23:05:49     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:49.953Z] 23:05:49     INFO - GECKO(1327) | JavaScript error: resource://devtools/shared/protocol/Pool.js, line 215: TypeError: can't access property "removeActorPool", this.conn is null
[task 2024-01-13T23:05:51.110Z] 23:05:51     INFO - Removing tab.
[task 2024-01-13T23:05:51.111Z] 23:05:51     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:51.155Z] 23:05:51     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:51.235Z] 23:05:51     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - Console message: [JavaScript Error: "TypeError: can't access property "removeActorPool", this.conn is null" {file: "resource://devtools/shared/protocol/Pool.js" line: 215}]
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - destroy@resource://devtools/shared/protocol/Pool.js:215:5
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - onTransportClosed/<@resource://devtools/server/devtools-server-connection.js:496:34
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - onTransportClosed@resource://devtools/server/devtools-server-connection.js:496:19
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - close@resource://devtools/shared/transport/js-window-actor-transport.js:46:18
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - close@resource://devtools/server/devtools-server-connection.js:91:23
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - _destroyTargetActor@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.sys.mjs:312:31
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - receiveMessage@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.sys.mjs:437:21
[task 2024-01-13T23:05:51.481Z] 23:05:51     INFO - 
[task 2024-01-13T23:05:52.645Z] 23:05:52     INFO - Removing tab.
[task 2024-01-13T23:05:52.646Z] 23:05:52     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:52.682Z] 23:05:52     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:52.732Z] 23:05:52     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - Console message: [JavaScript Error: "TypeError: can't access property "removeActorPool", this.conn is null" {file: "resource://devtools/shared/protocol/Pool.js" line: 215}]
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - destroy@resource://devtools/shared/protocol/Pool.js:215:5
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - onTransportClosed/<@resource://devtools/server/devtools-server-connection.js:496:34
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - onTransportClosed@resource://devtools/server/devtools-server-connection.js:496:19
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - close@resource://devtools/shared/transport/js-window-actor-transport.js:46:18
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - close@resource://devtools/server/devtools-server-connection.js:91:23
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - _destroyTargetActor@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.sys.mjs:312:31
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - receiveMessage@resource://devtools/server/connectors/js-window-actor/DevToolsFrameChild.sys.mjs:437:21
[task 2024-01-13T23:05:52.850Z] 23:05:52     INFO - 
[task 2024-01-13T23:05:54.002Z] 23:05:54     INFO - Removing tab.
[task 2024-01-13T23:05:54.003Z] 23:05:54     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:54.042Z] 23:05:54     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:54.113Z] 23:05:54     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:55.567Z] 23:05:55     INFO - Removing tab.
[task 2024-01-13T23:05:55.568Z] 23:05:55     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:55.596Z] 23:05:55     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:55.653Z] 23:05:55     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:55.716Z] 23:05:55     INFO - GECKO(1327) | JavaScript error: resource://devtools/client/webconsole/utils/messages.js, line 431: Error: Unsupported tracer log method null
[task 2024-01-13T23:05:55.722Z] 23:05:55     INFO - GECKO(1327) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2024-01-13T23:05:55.730Z] 23:05:55     INFO - Removing tab.
[task 2024-01-13T23:05:55.731Z] 23:05:55     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:55.767Z] 23:05:55     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:55.787Z] 23:05:55     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:55.851Z] 23:05:55     INFO - Console message: [JavaScript Error: "Error: Unsupported tracer log method null" {file: "resource://devtools/client/webconsole/utils/messages.js" line: 431}]
[task 2024-01-13T23:05:55.861Z] 23:05:55     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2024-01-13T23:05:55.867Z] 23:05:55     INFO - Removing tab.
[task 2024-01-13T23:05:55.868Z] 23:05:55     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:55.906Z] 23:05:55     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:55.933Z] 23:05:55     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:56.017Z] 23:05:56     INFO - Removing tab.
[task 2024-01-13T23:05:56.018Z] 23:05:56     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:56.055Z] 23:05:56     INFO - Got event: 'TabClose' on [object XULElement].
[task 2024-01-13T23:05:56.103Z] 23:05:56     INFO - Tab removed and finished closing
[task 2024-01-13T23:05:56.284Z] 23:05:56     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | The main process DevToolsServer has no pending connection when the test ends - 
Whiteboard: [stockwell needswork:owner]

Hi Hubert! Can you please take a look at this?
Thank you!

Flags: needinfo?(hmanilla)

Hi Alex,
This seems related to the tracer. Cool you please have a look, if you have a chance?
Thank you!

Flags: needinfo?(hmanilla)

Hi Hubert,
Can you please add a needinfo to this bug, based on the comment above?
Thank you!

Flags: needinfo?(hmanilla)

Oh sorry i forgot to add the need info.

Flags: needinfo?(hmanilla) → needinfo?(poirot.alex)
Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/5b2fe8557a9e Disable browser_dbg-javascript-tracer.js on tsan for frequent failures. r=intermittent-reviewers,devtools-reviewers,ochameau,jmaher DONTBUILD
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]

Update

There have been 33 failures within the last 7 days, all of them on Linux 18.04 x64 WebRender debug.

Recent log: https://treeherder.mozilla.org/logviewer?job_id=446751759&repo=autoland&lineNumber=11284

[task 2024-02-09T23:03:18.506Z] 23:03:18     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2024-02-09T23:03:18.544Z] 23:03:18     INFO - finish() was called, cleaning up and clearing debugger preferences...
[task 2024-02-09T23:03:18.546Z] 23:03:18     INFO - Buffered messages finished
[task 2024-02-09T23:03:18.550Z] 23:03:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.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 2024-02-09T23:03:18.551Z] 23:03:18     INFO - GECKO(1330) | MEMORY STAT | vsize 4503MB | residentFast 942MB | heapAllocated 507MB
[task 2024-02-09T23:03:18.564Z] 23:03:18     INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-javascript-tracer.js | took 126251ms
Whiteboard: [stockwell disabled] → [stockwell disabled][stockwell needswork:owner]

Doing all the tests in one-go would go over the timeout in debug builds.

Flags: needinfo?(poirot.alex)
Pushed by apoirot@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/0c80d4a3d6b4 [devtools] Split JavaScript tracer test in many tests. r=devtools-reviewers,nchevobbe
Attachment #9386331 - Attachment is obsolete: true
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: