Closed Bug 1573809 Opened 5 years ago Closed 5 years ago

trace weak references in sweeping and compacting

Categories

(Core :: JavaScript: GC, task, P3)

Product:
Core
Component:
JavaScript: GC
Type:
task

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla71
Tracking Flags:
Tracking Status
firefox71 --- fixed

People

(Reporter: allstars.chh, Assigned: allstars.chh)

References

Details

Attachments

(7 files)

Bug 1573809 - Part 1 : rename to TraceManuallyBarrieredWeakEdge.
47 bytes, text/x-phabricator-request
Details | Review
Bug 1573809 - Part 2 : trace jit realm.
47 bytes, text/x-phabricator-request
Details | Review
Bug 1573809 - Part 3 : traceWeak in realm.
47 bytes, text/x-phabricator-request
Details | Review
Bug 1573809 - Part 4 : traceWeak in object realm.
47 bytes, text/x-phabricator-request
Details | Review
Bug 1573809 - Part 5 : traceWeak JitRuntime JitcodeGlobalTable.
47 bytes, text/x-phabricator-request
Details | Review
Bug 1573809 - Part 6 : GCHashmap/GCHashTable traceWeak.
47 bytes, text/x-phabricator-request
Details | Review
Bug 1573809 - Part 7 : realm->tracekWeakVarNames.
47 bytes, text/x-phabricator-request
Details | Review

For compacting, we pass MovingTracer to do the tracing, and for sweeping, we pass SweepingTracer.
This bug is to fix those functions which are used both in compacting and sweeping.

Status: NEW → ASSIGNED
Attachment #9094225 - Attachment description: Bug 1573809 - Part 6: GCHashmap rename needsSweep to traceWeak → Bug 1573809 - Part 6 WIP: GCHashmap rename needsSweep to traceWeak

Hi Jonco, could you check these patches are what you have in mind? Thanks

Flags: needinfo?(jcoppeard)

(In reply to Yoshi Cheng-Hao Huang [:allstars.chh][:allstarschh] from comment #7)
Yes, this is what I had in mind.

Flags: needinfo?(jcoppeard)
Attachment #9094220 - Attachment description: Bug 1573809 - Part 1 : trace zone weakmap → Bug 1573809 - Part 1 : trace zone weakmap.
Attachment #9094221 - Attachment description: Bug 1573809 - Part 2: trace jit realm → Bug 1573809 - Part 2: trace jit realm.
Attachment #9094222 - Attachment description: Bug 1573809 : Part 3: traceWeak in realm → Bug 1573809 : Part 3: traceWeak in realm$.
Attachment #9094223 - Attachment description: Bug 1573809 - Part 4: trace object realm → Bug 1573809 - Part 4: traceWeak in object realm.
Attachment #9094224 - Attachment description: Bug 1573809 - Part 5: trace JitRuntime JitcodeGlobalTable → Bug 1573809 - Part 5: traceWeak JitRuntime JitcodeGlobalTable.
Attachment #9094225 - Attachment description: Bug 1573809 - Part 6 WIP: GCHashmap rename needsSweep to traceWeak → Bug 1573809 - Part 6 :(WIP) GCHashmap/GCHashTable traceWeak.
Attachment #9094225 - Attachment description: Bug 1573809 - Part 6 :(WIP) GCHashmap/GCHashTable traceWeak. → Bug 1573809 - Part 6 : GCHashmap/GCHashTable traceWeak.
Attachment #9094222 - Attachment description: Bug 1573809 : Part 3: traceWeak in realm$. → Bug 1573809 : Part 3: traceWeak in realm.
Attachment #9094221 - Attachment description: Bug 1573809 - Part 2: trace jit realm. → Bug 1573809 - Part 2 : trace jit realm.
Attachment #9094222 - Attachment description: Bug 1573809 : Part 3: traceWeak in realm. → Bug 1573809 - Part 3 : traceWeak in realm.
Attachment #9094223 - Attachment description: Bug 1573809 - Part 4: traceWeak in object realm. → Bug 1573809 - Part 4 : traceWeak in object realm.
Attachment #9094224 - Attachment description: Bug 1573809 - Part 5: traceWeak JitRuntime JitcodeGlobalTable. → Bug 1573809 - Part 5 : traceWeak JitRuntime JitcodeGlobalTable.

Backed out changeset 19ac681aac4b (Bug 1573809) for multiple failures

Push with failures: https://treeherder.mozilla.org/#/jobs?repo=autoland&fromchange=ff84a91c864141f95c2c6e6e6dd5054581840837&tochange=f105027fd15ff308eedfb96f06c25ca225895cd0&selectedJob=270041083

Backout link: https://hg.mozilla.org/integration/autoland/rev/f105027fd15ff308eedfb96f06c25ca225895cd0

Failures logs:
Damp: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=270041083&repo=autoland&lineNumber=893

[task 2019-10-07T11:14:26.755Z] 11:14:26 INFO - PID 1430 | inspector/custom.js took 9254ms.
[task 2019-10-07T11:14:26.755Z] 11:14:26 INFO - PID 1430 | Loading test 'debugger/custom.js'
[task 2019-10-07T11:14:26.755Z] 11:14:26 INFO - PID 1430 | Executing test 'debugger/custom.js'
[task 2019-10-07T11:14:28.344Z] 11:14:28 INFO - PID 1430 | Waiting for state change: has source http://127.0.0.1:49983/tests/devtools/addon/content/pages/custom/debugger/static/js/App.js
[task 2019-10-07T11:14:28.524Z] 11:14:28 INFO - PID 1430 | Finished waiting for state change: has source http://127.0.0.1:49983/tests/devtools/addon/content/pages/custom/debugger/static/js/App.js
[task 2019-10-07T11:14:28.524Z] 11:14:28 INFO - PID 1430 | Selecting source: App.js
[task 2019-10-07T11:14:28.599Z] 11:14:28 INFO - PID 1430 | Waiting for state change: selected source
[task 2019-10-07T11:14:28.935Z] 11:14:28 INFO - PID 1430 | Finished waiting for state change: selected source
[task 2019-10-07T11:14:28.935Z] 11:14:28 INFO - PID 1430 | Waiting until: text is visible
[task 2019-10-07T11:14:29.022Z] 11:14:29 INFO - PID 1430 | Finished Waiting until: text is visible
[task 2019-10-07T11:14:29.022Z] 11:14:29 INFO - PID 1430 | Waiting until: has file metadata
[task 2019-10-07T11:14:29.101Z] 11:14:29 INFO - PID 1430 | Finished Waiting until: has file metadata
[task 2019-10-07T11:14:29.101Z] 11:14:29 INFO - PID 1430 | 'custom.jsdebugger.open.settle.DAMP' took 0.16813099998398684ms.
[task 2019-10-07T11:14:29.101Z] 11:14:29 INFO - PID 1430 | Garbage collect
[task 2019-10-07T11:14:29.810Z] 11:14:29 INFO - PID 1430 | Reload page on 'custom.jsdebugger'
[task 2019-10-07T11:14:29.859Z] 11:14:29 INFO - PID 1430 | Waiting for state change: count sources
[task 2019-10-07T11:14:30.801Z] 11:14:30 INFO - PID 1430 | Finished waiting for state change: count sources
[task 2019-10-07T11:14:30.836Z] 11:14:30 INFO - PID 1430 | Waiting until: text is visible
[task 2019-10-07T11:14:30.911Z] 11:14:30 INFO - PID 1430 | Finished Waiting until: text is visible
[task 2019-10-07T11:14:30.912Z] 11:14:30 INFO - PID 1430 | Waiting until: has file metadata
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | Finished Waiting until: has file metadata
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | Wait for pending paints on 'custom.jsdebugger'
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | 'custom.jsdebugger.reload.settle.DAMP' took 0.21677900000941008ms.
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | Waiting for debugger panel
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | Creating context
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | Pausing debugger
[task 2019-10-07T11:14:31.151Z] 11:14:31 INFO - PID 1430 | add breakpoint
[task 2019-10-07T11:14:31.152Z] 11:14:31 INFO - PID 1430 | Selecting source: App.js
[task 2019-10-07T11:14:31.152Z] 11:14:31 INFO - PID 1430 | Waiting for state change: selected source
[task 2019-10-07T11:14:31.152Z] 11:14:31 INFO - PID 1430 | Finished waiting for state change: selected source
[task 2019-10-07T11:14:31.351Z] 11:14:31 INFO - PID 1430 | Run function in content process: window.hitBreakpoint()
[task 2019-10-07T11:14:31.638Z] 11:14:31 INFO - PID 1430 | remove all breakpoints
[task 2019-10-07T11:14:31.692Z] 11:14:31 INFO - PID 1430 | Garbage collect
[task 2019-10-07T11:14:32.451Z] 11:14:32 INFO - PID 1430 | add breakpoint
[task 2019-10-07T11:14:32.451Z] 11:14:32 INFO - PID 1430 | Selecting source: step-in-test.js
[task 2019-10-07T11:14:32.489Z] 11:14:32 INFO - PID 1430 | Waiting for state change: selected source
[task 2019-10-07T11:14:33.502Z] 11:14:33 INFO - PID 1430 | Finished waiting for state change: selected source
[task 2019-10-07T11:14:33.650Z] 11:14:33 INFO - PID 1430 | Run function in content process: window.hitBreakpoint()
[task 2019-10-07T11:14:35.289Z] 11:14:35 INFO - PID 1430 | remove all breakpoints
[task 2019-10-07T11:14:35.425Z] 11:14:35 INFO - PID 1430 | Garbage collect
[task 2019-10-07T11:14:36.325Z] 11:14:36 INFO - PID 1430 | add breakpoint
[task 2019-10-07T11:14:36.325Z] 11:14:36 INFO - PID 1430 | Selecting source: step-over-test.js
[task 2019-10-07T11:14:36.401Z] 11:14:36 INFO - PID 1430 | Waiting for state change: selected source
[task 2019-10-07T11:14:36.552Z] 11:14:36 INFO - PID 1430 | Finished waiting for state change: selected source
[task 2019-10-07T11:14:36.622Z] 11:14:36 INFO - PID 1430 | Run function in content process: window.hitBreakpoint()
[task 2019-10-07T11:14:37.485Z] 11:14:37 INFO - PID 1430 | remove all breakpoints
[task 2019-10-07T11:14:37.556Z] 11:14:37 INFO - PID 1430 | Garbage collect
[task 2019-10-07T11:14:38.321Z] 11:14:38 INFO - PID 1430 | add breakpoint
[task 2019-10-07T11:14:38.322Z] 11:14:38 INFO - PID 1430 | Selecting source: step-out-test.js
[task 2019-10-07T11:14:38.402Z] 11:14:38 INFO - PID 1430 | Waiting for state change: selected source
[task 2019-10-07T11:14:38.668Z] 11:14:38 INFO - PID 1430 | Finished waiting for state change: selected source
[task 2019-10-07T11:14:38.681Z] 11:14:38 INFO - PID 1430 | Run function in content process: window.hitBreakpoint()
[task 2019-10-07T11:19:26.753Z] 11:19:26 INFO - PID 1430 | TEST-UNEXPECTED-FAIL | damp | debugger/custom.js: Test timed out
[task 2019-10-07T11:19:26.830Z] 11:19:26 INFO - Terminating psutil.Process(pid=1430, name='firefox', started='11:12:54')
[task 2019-10-07T11:19:26.857Z] 11:19:26 INFO - PID 1430 | [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2019-10-07T11:19:26.857Z] 11:19:26 INFO - PID 1430 | Exiting due to channel error.
[task 2019-10-07T11:19:26.857Z] 11:19:26 INFO - PID 1430 | [Child 1452, Chrome_ChildThread] WARNING: pipe error: Broken pipe: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 728
[task 2019-10-07T11:19:26.857Z] 11:19:26 INFO - PID 1430 | Exiting due to channel error.
[task 2019-10-07T11:19:26.857Z] 11:19:26 INFO - PID 1430 | Exiting due to channel error.
[task 2019-10-07T11:19:26.857Z] 11:19:26 INFO - PID 1430 | Exiting due to channel error.
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - TEST-UNEXPECTED-ERROR | damp | unexpected error
[task 2019-10-07T11:19:27.042Z] 11:19:27 ERROR - Traceback (most recent call last):
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - File "/Users/cltbld/tasks/task_1570446678/build/tests/talos/talos/run_tests.py", line 281, in run_tests
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - talos_results.add(mytest.runTest(browser_config, test))
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - File "/Users/cltbld/tasks/task_1570446678/build/tests/talos/talos/ttest.py", line 64, in runTest
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - return self._runTest(browser_config, test_config, setup)
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - File "/Users/cltbld/tasks/task_1570446678/build/tests/talos/talos/ttest.py", line 216, in _runTest
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - debugger_args=browser_config['debugger_args']
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - File "/Users/cltbld/tasks/task_1570446678/build/tests/talos/talos/talos_process.py", line 156, in run_browser
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - raise TalosError("unexpected error")
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - TalosError: unexpected error
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - TEST-INFO took 392960ms
[task 2019-10-07T11:19:27.042Z] 11:19:27 INFO - SUITE-END | took 392s
[task 2019-10-07T11:19:27.384Z] 11:19:27 ERROR - Return code: 2
[task 2019-10-07T11:19:27.384Z] 11:19:27 WARNING - setting return code to 2
[task 2019-10-07T11:19:27.384Z] 11:19:27 ERROR - # TBPL FAILURE #
[task 2019-10-07T11:19:27.384Z] 11:19:27 INFO - Running post-action listener: _package_coverage_data
[task 2019-10-07T11:19:27.384Z] 11:19:27 INFO - Running post-action listener: _resource_record_post_action
[task 2019-10-07T11:19:27.384Z] 11:19:27 INFO - Running post-action listener: process_java_coverage_data
[task 2019-10-07T11:19:27.384Z] 11:19:27 INFO - [mozharness: 2019-10-07 11:19:27.384709Z] Finished run-tests step (success)

DevTools: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=270034506&repo=autoland&lineNumber=6334

[task 2019-10-07T10:30:52.550Z] 10:30:52 INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-debugger-buttons.js
[task 2019-10-07T10:30:52.596Z] 10:30:52 INFO - GECKO(1154) | ++DOCSHELL 0x7faba8d61000 == 2 [pid = 1315] [id = {423fb381-6bbc-442d-bfd8-3844c0692cde}]
[task 2019-10-07T10:30:52.597Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 3 (0x7faba8d933e0) [pid = 1315] [serial = 77] [outer = (nil)]
[task 2019-10-07T10:30:52.598Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 4 (0x7faba8d4ec00) [pid = 1315] [serial = 78] [outer = 0x7faba8d933e0]
[task 2019-10-07T10:30:52.746Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 5 (0x7faba8d5a000) [pid = 1315] [serial = 79] [outer = 0x7faba8d933e0]
[task 2019-10-07T10:30:52.891Z] 10:30:52 INFO - GECKO(1154) | ++DOCSHELL 0x7f6467417800 == 7 [pid = 1154] [id = {8e44154f-eb3a-4b8a-8cc1-cdcb6ef2f8f2}]
[task 2019-10-07T10:30:52.892Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 19 (0x7f646744c5c0) [pid = 1154] [serial = 208] [outer = (nil)]
[task 2019-10-07T10:30:52.894Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 20 (0x7f646decfc00) [pid = 1154] [serial = 209] [outer = 0x7f646744c5c0]
[task 2019-10-07T10:30:52.936Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 21 (0x7f646de1a000) [pid = 1154] [serial = 210] [outer = 0x7f646744c5c0]
[task 2019-10-07T10:30:52.971Z] 10:30:52 INFO - GECKO(1154) | ++DOMWINDOW == 22 (0x7f646df3e800) [pid = 1154] [serial = 211] [outer = 0x7f646744c5c0]
[task 2019-10-07T10:30:53.055Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/dom/base/nsContentUtils.cpp, line 3684
[task 2019-10-07T10:30:53.212Z] 10:30:53 INFO - GECKO(1154) | ++DOCSHELL 0x7f6467402800 == 8 [pid = 1154] [id = {a7eb3d1d-f935-4179-82dc-2a0c76d1a158}]
[task 2019-10-07T10:30:53.213Z] 10:30:53 INFO - GECKO(1154) | ++DOMWINDOW == 23 (0x7f646744c980) [pid = 1154] [serial = 212] [outer = (nil)]
[task 2019-10-07T10:30:53.214Z] 10:30:53 INFO - GECKO(1154) | ++DOMWINDOW == 24 (0x7f646df47000) [pid = 1154] [serial = 213] [outer = 0x7f646744c980]
[task 2019-10-07T10:30:53.631Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 42
[task 2019-10-07T10:30:53.632Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: '!principal', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 132
[task 2019-10-07T10:30:53.632Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/toolkit/components/antitracking/StorageAccess.cpp, line 33
[task 2019-10-07T10:30:53.632Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 42
[task 2019-10-07T10:30:53.633Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: '!principal', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 132
[task 2019-10-07T10:30:53.633Z] 10:30:53 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/toolkit/components/antitracking/StorageAccess.cpp, line 33
[task 2019-10-07T10:30:54.070Z] 10:30:54 INFO - GECKO(1154) | --DOCSHELL 0x7faba8d6e800 == 1 [pid = 1315] [id = {8b89569b-b007-436d-998a-d48e190bbd57}] [url = about:blank]
[task 2019-10-07T10:30:54.146Z] 10:30:54 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file /builds/worker/workspace/build/src/extensions/permissions/nsPermission.cpp, line 42
[task 2019-10-07T10:30:54.154Z] 10:30:54 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: '!principal', file /builds/worker/workspace/build/src/netwerk/cookie/CookieSettings.cpp, line 132
[task 2019-10-07T10:30:54.154Z] 10:30:54 INFO - GECKO(1154) | [Parent 1154, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/toolkit/components/antitracking/StorageAccess.cpp, line 33
[task 2019-10-07T10:30:54.473Z] 10:30:54 INFO - GECKO(1154) | [ACTION] SET_ORIENTATION - {"type":"SET_ORIENTATION","orientation":"horizontal"}
[task 2019-10-07T10:30:54.577Z] 10:30:54 INFO - GECKO(1154) | [ACTION] UPDATE_EVENT_LISTENERS - {"type":"UPDATE_EVENT_LISTENERS","active":[]}
[task 2019-10-07T10:30:54.678Z] 10:30:54 INFO - GECKO(1154) | [ACTION] RECEIVE_EVENT_LISTENER_TYPES - {"type":"RECEIVE_EVENT_LISTENER_TYPES","categories":[{"name":"Animation","events":[{"id":"animationframe.request","name":"Request Animation Frame"},{"id":"animationframe.cancel","name":"Cancel Animation Frame"},{"id":"animationframe.fire","name":"Animation Frame fired"}]},{"name":"Clipboard","events":[{"id":"event.clipboard.copy","name":"copy"},{"id":"event.clipboard.cut","name":"cut"},{"id":"event.clipboard.paste","name":"paste"},{"id":"event.clipboard.beforecopy","name":"beforecopy"},{"id":"event.clipboard.beforecut","name":"beforecut"},{"id":"event.clipboard.beforepaste","name":"beforepaste"}]},{"name":"Control","events":[{"id":"event.control.resize","name":"resize"},{"id":"event.control.scroll","name":"scroll"},{"id":"event.control.zoom","name":"zoom"},{"id":"event.control.focus","name":"focus"},{"id":"event.control.blur","name":"blur"},{"id":"event.control.select","name":"select"},{"id":"event.control.change","name":"change"},{"id":"event.control.submit","name":"submit"},{"id":"event.control.reset","name":"reset"}]},{"name":"DOM Mutation","events":[{"id":"event.dom-mutation.DOMActivate","name":"DOMActivate"},{"id":"event.dom-mutation.DOMFocusIn","name":"DOMFocusIn"},{"id":"event.dom-mutation.DOMFocusOut","name":"DOMFocusOut"},{"id":"event.dom-mutation.DOMAttrModified","name":"DOMAttrModified"},{"id":"event.dom-mutation.DOMCharacterDataModified","name":"DOMCharacterDataModified"},{"id":"event.dom-mutation.DOMNodeInserted","name":"DOMNodeInserted"},{"id":"event.dom-mutation.DOMNodeInsertedIntoDocument","name":"DOMNodeInsertedIntoDocument"},{"id":"event.dom-mutation.DOMNodeRemoved","name":"DOMNodeRemoved"},{"id":"event.dom-mutation.DOMNodeRemovedIntoDocument","name":"DOMNodeRemovedIntoDocument"},{"id":"event.dom-mutation.DOMSubtreeModified","name":"DOMSubtreeModified"},{"id":"event.dom-mutation.DOMContentLoaded","name":"DOMContentLoaded"}]},{"name":"Device","events":[{"id":"event.device.deviceorientation","name":"deviceorientation"},{"id":"event.device.devicemotion","name":"devicemotion"}]},{"name":"Drag and Drop","events":[{"id":"event.drag-and-drop.drag","name":"drag"},{"id":"event.drag-and-drop.dragstart","name":"dragstart"},{"id":"event.drag-and-drop.dragend","name":"dragend"},{"id":"event.drag-and-drop.dragenter","name":"dragenter"},{"id":"event.drag-and-drop.dragover","name":"dragover"},{"id":"event.drag-and-drop.dragleave","name":"dragleave"},{"id":"event.drag-and-drop.drop","name":"drop"}]},{"name":"Keyboard","events":[{"id":"event.keyboard.keydown","name":"keydown"},{"id":"event.keyboard.keyup","name":"keyup"},{"id":"event.keyboard.keypress","name":"keypress"},{"id":"event.keyboard.input","name":"input"}]},{"name":"Load","events":[{"id":"event.load.load","name":"load"},{"id":"event.load.abort","name":"abort"},{"id":"event.load.error","name":"error"},{"id":"event.load.hashchange","name":"hashchange"},{"id":"event.load.popstate","name":"popstate"}]},{"name":"Media","events":[{"id":"event.media.play","name":"play"},{"id":"event.media.pause","name":"pause"},{"id":"event.media.playing","name":"playing"},{"id":"event.media.canplay","name":"canplay"},{"id":"event.media.canplaythrough","name":"canplaythrough"},{"id":"event.media.seeking","name":"seeking"},{"id":"event.media.seeked","name":"seeked"},{"id":"event.media.timeupdate","name":"timeupdate"},{"id":"event.media.ended","name":"ended"},{"id":"event.media.ratechange","name":"ratechange"},{"id":"event.media.durationchange","name":"durationchange"},{"id":"event.media.volumechange","name":"volumechange"},{"id":"event.media.loadstart","name":"loadstart"},{"id":"event.media.progress","name":"progress"},{"id":"event.media.suspend","name":"suspend"},{"id":"event.media.abort","name":"abort"},{"id":"event.media.error","name":"error"},{"id":"event.media.emptied","name":"emptied"},{"id":"event.media.stalled","name":"stalled"},{"id":"event.media.loadedmetadata","name":"loadedmetadata"},{"id":"event.media.loadeddata","name":"loadeddata"},{"id":"event.media.waiting","name":"waiting"}]},{"name":"Mouse","events":[{"id":"event.mouse.auxclick","name":"auxclick"},{"id":"event.mouse.click","name":"click"},{"id":"event.mouse.dblclick","name":"dblclick"},{"id":"event.mouse.mousedown","name":"mousedown"},{"id":"event.mouse.mouseup","name":"mouseup"},{"id":"event.mouse.mouseover","name":"mouseover"},{"id":"event.mouse.mousemove","name":"mousemove"},{"id":"event.mouse.mouseout","name":"mouseout"},{"id":"event.mouse.mouseenter","name":"mouseenter"},{"id":"event.mouse.mouseleave","name":"mouseleave"},{"id":"event.mouse.mousewheel","name":"mousewheel"},{"id":"event.mouse.wheel","name":"wheel"},{"id":"event.mouse.contextmenu","name":"contextmenu"}]},{"name":"Pointer","events":[{"id":"event.pointer.pointerover","name":"pointerover"},{"id":"event.pointer.pointerout","name":"pointerout"},{"id":"event.pointer.pointerenter","name":"pointerenter"},{"id":"event.pointer.pointerleave","name":"pointerleave"},{"id":"event.pointer.pointerdown","name":"pointerdown"},{"id":"event.pointer.pointerup","name":"pointerup"},{"id":"event.pointer.pointermove","name":"pointermove"},{"id":"event.pointer.pointercancel","name":"pointercancel"},{"id":"event.pointer.gotpointercapture","name":"gotpointercapture"},{"id":"event.pointer.lostpointercapture","name":"lostpointercapture"}]},{"name":"Timer","events":[{"id":"timer.timeout.set","name":"setTimeout"},{"id":"timer.timeout.clear","name":"clearTimeout"},{"id":"timer.timeout.fire","name":"setTimeout fired"},{"id":"timer.interval.set","name":"setInterval"},{"id":"timer.interval.clear","name":"clearInterval"},{"id":"timer.interval.fire","name":"setInterval fired"}]},{"name":"Touch","events":[{"id":"event.touch.touchstart","name":"touchstart"},{"id":"event.touch.touchmove","name":"touchmove"},{"id":"event.touch.touchend","name":"touchend"},{"id":"event.touch.touchcancel","name":"touchcancel"}]},{"name":"Worker","events":[{"id":"event.worker.message","name":"message"},{"id":"event.worker.messageerror","name":"messageerror"}]},{"name":"XHR","events":[{"id":"event.xhr.readystatechange","name":"readystatechange"},{"id":"event.xhr.load","name":"load"},{"id":"event.xhr.loadstart","name":"loadstart"},{"id":"event.xhr.loadend","name":"loadend"},{"id":"event.xhr.abort","name":"abort"},{"id":"event.xhr.error","name":"error"},{"id":"event.xhr.progress","name":"progress"},{"id":"event.xhr.timeout","name":"timeout"}]}]}
[task 2019-10-07T10:30:54.686Z] 10:30:54 INFO - GECKO(1154) | [ACTION] CONNECT - {"type":"CONNECT","mainThread":{"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html","actor":"server0.conn30.child1/thread20","type":"mainThread","name":"Main Thread"},"canRewind":false,"isWebExtension":false}
[task 2019-10-07T10:30:54.969Z] 10:30:54 INFO - GECKO(1154) | [ACTION] ADD_SOURCES - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[{"id":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html","relativeUrl":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html","isPrettyPrinted":false,"extensionName":null,"introductionUrl":null,"introductionType":"scriptElement","isBlackBoxed":false,"isWasm":false,"isExtension":false}]}
[task 2019-10-07T10:30:54.996Z] 10:30:54 INFO - GECKO(1154) | [ACTION] INSERT_SOURCE_ACTORS - {"type":"INSERT_SOURCE_ACTORS","items":[{"id":"server0.conn30.child1/source22","actor":"server0.conn30.child1/source22","thread":"server0.conn30.child1/thread20","source":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html","isBlackBoxed":false,"sourceMapURL":null,"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html","introductionUrl":null,"introductionType":"scriptElement"}]}
[task 2019-10-07T10:30:55.024Z] 10:30:55 INFO - GECKO(1154) | [ACTION] SET_EXPANDED_STATE - {"type":"SET_EXPANDED_STATE","expanded":{}}
[task 2019-10-07T10:30:55.044Z] 10:30:55 INFO - GECKO(1154) | [ACTION] SET_EXPANDED_STATE - {"type":"SET_EXPANDED_STATE","expanded":{}}
[task 2019-10-07T10:30:55.125Z] 10:30:55 INFO - GECKO(1154) | [ACTION] ADD_SOURCES - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[]}
...
[task 2019-10-07T10:32:23.313Z] 10:32:23 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-debugger-buttons.js | gutter is visible -
[task 2019-10-07T10:32:23.314Z] 10:32:23 INFO - step into
[task 2019-10-07T10:32:23.314Z] 10:32:23 INFO - Waiting for state change: paused
[task 2019-10-07T10:32:23.315Z] 10:32:23 INFO - Buffered messages logged at 10:30:59
[task 2019-10-07T10:32:23.316Z] 10:32:23 INFO - Console message: [JavaScript Error: "Error: Unknown source actor: server0.conn30.child1/source44" {file: "resource://devtools/client/debugger/src/client/firefox/commands.js" line: 439}]
[task 2019-10-07T10:32:23.316Z] 10:32:23 INFO - Buffered messages logged at 10:31:36
[task 2019-10-07T10:32:23.316Z] 10:32:23 INFO - Console message: [JavaScript Error: "getScreenshot(http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-scripts.html) failed: TypeError: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/Screenshots.jsm" line: 59}]
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:59:10
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - asyncmaybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:112:37
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - _fetchScreenshot@resource://activity-stream/lib/TopSitesFeed.jsm:527:23
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - _fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:515:16
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:408:16
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - asyncrefresh@resource://activity-stream/lib/TopSitesFeed.jsm:431:30
[task 2019-10-07T10:32:23.317Z] 10:32:23 INFO - onAction@resource://activity-stream/lib/TopSitesFeed.jsm:771:14
[task 2019-10-07T10:32:23.319Z] 10:32:23 INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:63:17
[task 2019-10-07T10:32:23.319Z] 10:32:23 INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:39:54
[task 2019-10-07T10:32:23.319Z] 10:32:23 INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:27:24
[task 2019-10-07T10:32:23.320Z] 10:32:23 INFO - notify@resource://gre/modules/Timer.jsm:62:17
[task 2019-10-07T10:32:23.320Z] 10:32:23 INFO -
[task 2019-10-07T10:32:23.321Z] 10:32:23 INFO - Buffered messages finished
[task 2019-10-07T10:32:23.322Z] 10:32:23 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-debugger-buttons.js | Test timed out -
[task 2019-10-07T10:32:23.322Z] 10:32:23 INFO - GECKO(1154) | JavaScript error: resource://devtools/server/actors/highlighters.js, line 721: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIXPCComponents_Utils.isDeadWrapper]
[task 2019-10-07T10:32:23.987Z] 10:32:23 INFO - Removing tab.
[task 2019-10-07T10:32:23.988Z] 10:32:23 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-10-07T10:32:24.016Z] 10:32:24 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-10-07T10:32:24.040Z] 10:32:24 INFO - Tab removed and finished closing
[task 2019-10-07T10:32:24.084Z] 10:32:24 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-debugger-statements.html" line: 0}]
[task 2019-10-07T10:32:24.086Z] 10:32:24 INFO - Console message: [JavaScript Error: "NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIXPCComponents_Utils.isDeadWrapper]" {file: "resource://devtools/server/actors/highlighters.js" line: 721}]
[task 2019-10-07T10:32:24.087Z] 10:32:24 INFO - GECKO(1154) | --DOCSHELL 0x7f6467402800 == 6 [pid = 1154] [id = {a7eb3d1d-f935-4179-82dc-2a0c76d1a158}] [url = chrome://devtools/content/debugger/index.html]
[task 2019-10-07T10:32:24.088Z] 10:32:24 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-debugger-buttons.js | The main process DebuggerServer has no pending connection when the test ends -
[task 2019-10-07T10:32:24.104Z] 10:32:24 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-07T10:32:24.108Z] 10:32:24 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-debugger-buttons.js | A promise chain failed to handle a rejection: Unknown source actor: server0.conn30.child1/source44 - stack: getSourceForActor@resource://devtools/client/debugger/src/client/firefox/commands.js:439:11
[task 2019-10-07T10:32:24.108Z] 10:32:24 INFO - createFrame@resource://devtools/client/debugger/src/client/firefox/create.js:36:40
[task 2019-10-07T10:32:24.108Z] 10:32:24 INFO - createPause@resource://devtools/client/debugger/src/client/firefox/create.js:62:29
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - paused@resource://devtools/client/debugger/src/client/firefox/events.js:71:43
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - Asyncemit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - onPacket@resource://devtools/shared/protocol/Front.js:205:13
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - onPacket@resource://devtools/shared/client/debugger-client.js:597:13
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-10-07T10:32:24.109Z] 10:32:24 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-10-07T10:32:24.110Z] 10:32:24 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-10-07T10:32:24.110Z] 10:32:24 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-10-07T10:32:24.110Z] 10:32:24 INFO - send@resource://devtools/server/debugger-server-connection.js:89:20
[task 2019-10-07T10:32:24.110Z] 10:32:24 INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
[task 2019-10-07T10:32:24.110Z] 10:32:24 INFO - MessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:14
[task 2019-10-07T10:32:24.111Z] 10:32:24 INFO - ready@resource://devtools/shared/transport/child-transport.js:57:10
[task 2019-10-07T10:32:24.111Z] 10:32:24 INFO - connectToFrame/</onActorCreated<@resource://devtools/server/connectors/frame-connector.js:183:22
[task 2019-10-07T10:32:24.111Z] 10:32:24 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-10-07T10:32:24.111Z] 10:32:24 INFO - MessageListener.receiveMessagetrackMessageManager@resource://devtools/server/connectors/frame-connector.js:55:12
[task 2019-10-07T10:32:24.111Z] 10:32:24 INFO - connectToFrame/<@resource://devtools/server/connectors/frame-connector.js:283:5
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - connectToFrame@resource://devtools/server/connectors/frame-connector.js:41:10
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - connect@resource://devtools/server/actors/targets/frame-proxy.js:65:35
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - asyncBrowserTabList.prototype._getActorForBrowser@resource://devtools/server/actors/webbrowser.js:368:16
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - BrowserTabList.prototype.getTab@resource://devtools/server/actors/webbrowser.js:410:21
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - onGetTab@resource://devtools/server/actors/root.js:368:35
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - onPacket@resource://devtools/server/debugger-server-connection.js:378:58
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-10-07T10:32:24.112Z] 10:32:24 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-10-07T10:32:24.113Z] 10:32:24 INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-10-07T10:32:24.113Z] 10:32:24 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-10-07T10:32:24.113Z] 10:32:24 INFO - send@resource://devtools/shared/protocol/Front.js:151:30
[task 2019-10-07T10:32:24.113Z] 10:32:24 INFO - request@resource://devtools/shared/protocol/Front.js:169:10
[task 2019-10-07T10:32:24.113Z] 10:32:24 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-10-07T10:32:24.113Z] 10:32:24 INFO - getTab@resource://devtools/shared/fronts/root.js:363:30
...

Flags: needinfo?(allstars.chh)

Another DevTools failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=270035147&repo=autoland&lineNumber=6691

[task 2019-10-07T10:40:44.411Z] 10:40:44 INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js
[task 2019-10-07T10:40:46.435Z] 10:40:46 INFO - GECKO(2454) | [ACTION] SET_ORIENTATION - {"type":"SET_ORIENTATION","orientation":"horizontal"}
...
[task 2019-10-07T10:40:55.015Z] 10:40:55 INFO - GECKO(2454) | [ACTION] SET_EXPANDED_SCOPE - {"type":"SET_EXPANDED_SCOPE","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":19},"thread":"server0.conn70.child1/thread20","path":"server0.conn70.child1/environment114-1","expanded":true}
[task 2019-10-07T10:40:55.043Z] 10:40:55 INFO - GECKO(2454) | [ACTION] NODE_PROPERTIES_LOADED - {}
[task 2019-10-07T10:40:55.059Z] 10:40:55 INFO - TEST-INFO | started process screentopng
[task 2019-10-07T10:40:55.436Z] 10:40:55 INFO - TEST-INFO | screentopng: exit 0
[task 2019-10-07T10:40:55.436Z] 10:40:55 INFO - <snipped 37 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2019-10-07T10:40:55.437Z] 10:40:55 INFO - Buffered messages logged at 10:40:49
[task 2019-10-07T10:40:55.438Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | debug line is visible -
[task 2019-10-07T10:40:55.440Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | expression is highlighted as paused -
[task 2019-10-07T10:40:55.442Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | gutter is visible -
[task 2019-10-07T10:40:55.443Z] 10:40:55 INFO - Resuming from 2
[task 2019-10-07T10:40:55.444Z] 10:40:55 INFO - Waiting for state change: active
[task 2019-10-07T10:40:55.445Z] 10:40:55 INFO - Finished waiting for state change: active
[task 2019-10-07T10:40:55.446Z] 10:40:55 INFO - 3. Test pausing on a caught Error
[task 2019-10-07T10:40:55.447Z] 10:40:55 INFO - Invoking in tab: caughtException()
[task 2019-10-07T10:40:55.449Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.450Z] 10:40:55 INFO - Buffered messages logged at 10:40:50
[task 2019-10-07T10:40:55.451Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.453Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.454Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.455Z] 10:40:55 INFO - Finished waiting for state change: selected source
[task 2019-10-07T10:40:55.456Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | top frame's source is selected -
[task 2019-10-07T10:40:55.458Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Line is highlighted as paused -
[task 2019-10-07T10:40:55.459Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | There is only one line -
[task 2019-10-07T10:40:55.459Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | debug line is visible -
[task 2019-10-07T10:40:55.460Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | expression is highlighted as paused -
[task 2019-10-07T10:40:55.460Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | gutter is visible -
[task 2019-10-07T10:40:55.460Z] 10:40:55 INFO - 3.b Test pausing in the catch statement
[task 2019-10-07T10:40:55.461Z] 10:40:55 INFO - Resuming from 7
[task 2019-10-07T10:40:55.462Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.463Z] 10:40:55 INFO - Buffered messages logged at 10:40:51
[task 2019-10-07T10:40:55.464Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.465Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.465Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.466Z] 10:40:55 INFO - Finished waiting for state change: selected source
...
[task 2019-10-07T10:40:55.489Z] 10:40:55 INFO - Resuming from 16
[task 2019-10-07T10:40:55.490Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.490Z] 10:40:55 INFO - Buffered messages logged at 10:40:53
[task 2019-10-07T10:40:55.491Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.492Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.493Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.494Z] 10:40:55 INFO - Finished waiting for state change: selected source
[task 2019-10-07T10:40:55.495Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | client is paused -
[task 2019-10-07T10:40:55.496Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Got at least one frame -
[task 2019-10-07T10:40:55.497Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame has correct source -
[task 2019-10-07T10:40:55.497Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame paused at 22, but expected 22 -
[task 2019-10-07T10:40:55.498Z] 10:40:55 INFO - Resuming from 22
[task 2019-10-07T10:40:55.499Z] 10:40:55 INFO - 6. Only pause once on an exception when pausing in a finally block
[task 2019-10-07T10:40:55.500Z] 10:40:55 INFO - Invoking in tab: deepErrorFinally()
[task 2019-10-07T10:40:55.501Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.501Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.502Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.503Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.504Z] 10:40:55 INFO - Finished waiting for state change: selected source
[task 2019-10-07T10:40:55.504Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | client is paused -
[task 2019-10-07T10:40:55.505Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Got at least one frame -
[task 2019-10-07T10:40:55.506Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame has correct source -
[task 2019-10-07T10:40:55.506Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame paused at 34, but expected 34 -
[task 2019-10-07T10:40:55.506Z] 10:40:55 INFO - Resuming from 34
[task 2019-10-07T10:40:55.507Z] 10:40:55 INFO - Buffered messages logged at 10:40:54
[task 2019-10-07T10:40:55.507Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.508Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.509Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.510Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.511Z] 10:40:55 INFO - Finished waiting for state change: selected source
[task 2019-10-07T10:40:55.512Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | client is paused -
[task 2019-10-07T10:40:55.512Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Got at least one frame -
[task 2019-10-07T10:40:55.513Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame has correct source -
[task 2019-10-07T10:40:55.513Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame paused at 31, but expected 31 -
[task 2019-10-07T10:40:55.515Z] 10:40:55 INFO - Resuming from 31
[task 2019-10-07T10:40:55.515Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.516Z] 10:40:55 INFO - Buffered messages logged at 10:40:55
[task 2019-10-07T10:40:55.516Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.516Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.517Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.517Z] 10:40:55 INFO - Finished waiting for state change: selected source
[task 2019-10-07T10:40:55.519Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | client is paused -
[task 2019-10-07T10:40:55.519Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Got at least one frame -
[task 2019-10-07T10:40:55.520Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame has correct source -
[task 2019-10-07T10:40:55.521Z] 10:40:55 INFO - Buffered messages finished
[task 2019-10-07T10:40:55.521Z] 10:40:55 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame paused at 31, but expected 40 -
[task 2019-10-07T10:40:55.522Z] 10:40:55 INFO - Stack trace:
[task 2019-10-07T10:40:55.522Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:test_ok:1575
[task 2019-10-07T10:40:55.522Z] 10:40:55 INFO - chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/helpers.js:assertPausedAtSourceAndLine:441
[task 2019-10-07T10:40:55.522Z] 10:40:55 INFO - chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js:null:83
[task 2019-10-07T10:40:55.523Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1345
[task 2019-10-07T10:40:55.523Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1380
[task 2019-10-07T10:40:55.523Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1208
[task 2019-10-07T10:40:55.523Z] 10:40:55 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-10-07T10:40:55.524Z] 10:40:55 INFO - Resuming from 31
[task 2019-10-07T10:40:55.524Z] 10:40:55 INFO - GECKO(2454) | [ACTION] COMMAND [start] - {"type":"COMMAND","command":"resume","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":19},"thread":"server0.conn70.child1/thread20","seqId":"37","status":"start"}
[task 2019-10-07T10:40:55.526Z] 10:40:55 INFO - GECKO(2454) | [ACTION] ADD_INLINE_PREVIEW - {"type":"ADD_INLINE_PREVIEW","thread":"server0.conn70.child1/thread20","frame":{"id":"server0.conn70.child1/frame98","thread":"server0.conn70.child1/thread20","displayName":"b","location":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":31,"column":6},"generatedLocation":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":31,"column":6},"this":{"type":"object","actor":"server0.conn70.child1/obj126","class":"Window","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":810,"preview":{"kind":"ObjectWithURL","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-exceptions.html"}},"source":null,"originalDisplayName":"b"},"previews":{}}
[task 2019-10-07T10:40:55.526Z] 10:40:55 INFO - GECKO(2454) | [ACTION] RESUME - {"type":"RESUME","thread":"server0.conn70.child1/thread20","wasStepping":false}
[task 2019-10-07T10:40:55.527Z] 10:40:55 INFO - GECKO(2454) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":false,"pauseCounter":20},"inputs":[],"results":[]}
[task 2019-10-07T10:40:55.527Z] 10:40:55 INFO - GECKO(2454) | [ACTION] COMMAND [done] - {"type":"COMMAND","command":"resume","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":19},"thread":"server0.conn70.child1/thread20","seqId":"37","status":"done"}
[task 2019-10-07T10:40:55.529Z] 10:40:55 INFO - 7. Only pause once on an exception when it is rethrown from a catch
[task 2019-10-07T10:40:55.529Z] 10:40:55 INFO - Invoking in tab: deepErrorCatch()
[task 2019-10-07T10:40:55.529Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:40:55.530Z] 10:40:55 INFO - GECKO(2454) | JavaScript warning: resource://testing-common/content-task.js line 62 > eval, line 7: DebuggeeWouldRun: debuggee http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js:43' would run [task 2019-10-07T10:40:55.530Z] 10:40:55 INFO - Console message: [JavaScript Warning: "DebuggeeWouldRun: debuggeehttp://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js:43' would run" {file: "resource://testing-common/content-task.js line 62 > eval" line: 7}]
[task 2019-10-07T10:40:55.532Z] 10:40:55 INFO - GECKO(2454) | [ACTION] PAUSED - {"type":"PAUSED","thread":"server0.conn70.child1/thread20","why":{"type":"debuggerStatement"},"frames":[{"id":"server0.conn70.child1/frame100","location":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":40,"column":2},"displayName":"deepErrorFinally"}],"selectedFrameId":"server0.conn70.child1/frame100","pauseInfo":{"why":{"type":"debuggerStatement"}},"scopes":[],"loadedObjects":[]}
[task 2019-10-07T10:40:55.532Z] 10:40:55 INFO - GECKO(2454) | [ACTION] MAP_FRAMES - {}
[task 2019-10-07T10:40:55.533Z] 10:40:55 INFO - GECKO(2454) | [ACTION] SET_SELECTED_LOCATION - {"type":"SET_SELECTED_LOCATION","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":21},"source":{"id":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","relativeUrl":"/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","isPrettyPrinted":false,"extensionName":null,"introductionUrl":null,"introductionType":"scriptElement","isBlackBoxed":false,"isWasm":false,"isExtension":false},"location":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":40,"column":2}}
[task 2019-10-07T10:40:55.533Z] 10:40:55 INFO - GECKO(2454) | [ACTION] ADD_SCOPES [start] - {}
[task 2019-10-07T10:40:55.535Z] 10:40:55 INFO - GECKO(2454) | [ACTION] MAP_SCOPES [start] - {}
[task 2019-10-07T10:40:55.538Z] 10:40:55 INFO - GECKO(2454) | [ACTION] MAP_SCOPES [done] - {}
[task 2019-10-07T10:40:55.554Z] 10:40:55 INFO - GECKO(2454) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":21},"inputs":[],"results":[]}
[task 2019-10-07T10:40:55.557Z] 10:40:55 INFO - GECKO(2454) | [ACTION] IN_SCOPE_LINES - {}
[task 2019-10-07T10:40:55.579Z] 10:40:55 INFO - GECKO(2454) | [ACTION] SET_VIEWPORT - {"type":"SET_VIEWPORT","viewport":{"start":{"line":12,"column":0},"end":{"line":63,"column":191}}}
[task 2019-10-07T10:40:55.587Z] 10:40:55 INFO - GECKO(2454) | [ACTION] ADD_SCOPES [done] - {}
[task 2019-10-07T10:40:55.611Z] 10:40:55 INFO - GECKO(2454) | [ACTION] NODE_EXPAND - {}
[task 2019-10-07T10:40:55.612Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.615Z] 10:40:55 INFO - GECKO(2454) | [ACTION] SET_EXPANDED_SCOPE - {"type":"SET_EXPANDED_SCOPE","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":21},"thread":"server0.conn70.child1/thread20","path":"server0.conn70.child1/environment132-1","expanded":true}
[task 2019-10-07T10:40:55.639Z] 10:40:55 INFO - Finished waiting for state change: paused
[task 2019-10-07T10:40:55.641Z] 10:40:55 INFO - GECKO(2454) | [ACTION] NODE_PROPERTIES_LOADED - {}
[task 2019-10-07T10:40:55.663Z] 10:40:55 INFO - Waiting for state change: selected source
[task 2019-10-07T10:40:55.665Z] 10:40:55 INFO - Finished waiting for state change: selected source
[task 2019-10-07T10:40:55.665Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | client is paused -
[task 2019-10-07T10:40:55.667Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Got at least one frame -
[task 2019-10-07T10:40:55.667Z] 10:40:55 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame has correct source -
[task 2019-10-07T10:40:55.668Z] 10:40:55 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-07T10:40:55.671Z] 10:40:55 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Frame paused at 40, but expected 53 -
[task 2019-10-07T10:40:55.671Z] 10:40:55 INFO - Stack trace:
[task 2019-10-07T10:40:55.672Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:test_ok:1575
[task 2019-10-07T10:40:55.672Z] 10:40:55 INFO - chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/helpers.js:assertPausedAtSourceAndLine:441
[task 2019-10-07T10:40:55.672Z] 10:40:55 INFO - chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js:null:89
[task 2019-10-07T10:40:55.672Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1345
[task 2019-10-07T10:40:55.673Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1380
[task 2019-10-07T10:40:55.673Z] 10:40:55 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1208
[task 2019-10-07T10:40:55.673Z] 10:40:55 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-10-07T10:40:55.673Z] 10:40:55 INFO - Resuming from 40
[task 2019-10-07T10:40:55.673Z] 10:40:55 INFO - GECKO(2454) | [ACTION] COMMAND [start] - {"type":"COMMAND","command":"resume","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":21},"thread":"server0.conn70.child1/thread20","seqId":"40","status":"start"}
[task 2019-10-07T10:40:55.754Z] 10:40:55 INFO - GECKO(2454) | [ACTION] ADD_INLINE_PREVIEW - {"type":"ADD_INLINE_PREVIEW","thread":"server0.conn70.child1/thread20","frame":{"id":"server0.conn70.child1/frame100","thread":"server0.conn70.child1/thread20","displayName":"deepErrorFinally","location":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":40,"column":2},"generatedLocation":{"sourceId":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":40,"column":2},"this":{"type":"object","actor":"server0.conn70.child1/obj131","class":"Window","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":810,"preview":{"kind":"ObjectWithURL","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-exceptions.html"}},"source":null,"originalDisplayName":"deepErrorFinally"},"previews":{"25":[{"line":25,"column":17,"name":"b","value":{"type":"object","actor":"server0.conn70.child1/obj138","class":"Function","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":3,"name":"b","displayName":"b","parameterNames":[],"location":{"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":27}}}],"28":[{"line":28,"column":6,"name":"c","value":{"type":"object","actor":"server0.conn70.child1/obj139","class":"Function","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":3,"name":"c","displayName":"c","parameterNames":[],"location":{"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":34}}}],"36":[{"line":36,"column":4,"name":"a","value":{"type":"object","actor":"server0.conn70.child1/obj140","class":"Function","extensible":true,"frozen":false,"sealed":false,"ownPropertyLength":3,"name":"a","displayName":"a","parameterNames":[],"location":{"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/exceptions.js","line":26}}}]}}
[task 2019-10-07T10:40:55.778Z] 10:40:55 INFO - GECKO(2454) | [ACTION] RESUME - {"type":"RESUME","thread":"server0.conn70.child1/thread20","wasStepping":false}
[task 2019-10-07T10:40:55.794Z] 10:40:55 INFO - GECKO(2454) | [ACTION] EVALUATE_EXPRESSIONS - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":false,"pauseCounter":22},"inputs":[],"results":[]}
[task 2019-10-07T10:40:55.835Z] 10:40:55 INFO - GECKO(2454) | [ACTION] COMMAND [done] - {"type":"COMMAND","command":"resume","cx":{"navigateCounter":0,"thread":"server0.conn70.child1/thread20","isPaused":true,"pauseCounter":21},"thread":"server0.conn70.child1/thread20","seqId":"40","status":"done"}
[task 2019-10-07T10:40:55.842Z] 10:40:55 INFO - Waiting for state change: paused
[task 2019-10-07T10:42:14.434Z] 10:42:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-07T10:42:14.435Z] 10:42:14 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | Test timed out -
[task 2019-10-07T10:42:14.865Z] 10:42:14 INFO - Removing tab.
[task 2019-10-07T10:42:14.865Z] 10:42:14 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-10-07T10:42:14.902Z] 10:42:14 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-10-07T10:42:14.938Z] 10:42:14 INFO - Tab removed and finished closing
[task 2019-10-07T10:42:14.974Z] 10:42:14 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | The main process DebuggerServer has no pending connection when the test ends -
[task 2019-10-07T10:42:14.990Z] 10:42:14 INFO - GECKO(2454) | MEMORY STAT | vsize 20976815MB | residentFast 2775MB
[task 2019-10-07T10:42:14.991Z] 10:42:14 INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-pause-exceptions.js | took 90581ms

Backout by btara@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/ffe0ac689fc7 Backed out changeset a69d0ed7e27c for causing multiple failures CLOSED TREE
Attachment #9094220 - Attachment description: Bug 1573809 - Part 1 : trace zone weakmap. → Bug 1573809 - Part 1 : rename to TraceManuallyBarrieredWeakEdge.
Pushed by allstars.chh@gmail.com: https://hg.mozilla.org/integration/autoland/rev/5d38281c5391 Part 1 : rename to TraceManuallyBarrieredWeakEdge. r=jonco https://hg.mozilla.org/integration/autoland/rev/fdaa13c5e9a8 Part 2 : trace jit realm. r=jonco https://hg.mozilla.org/integration/autoland/rev/6a6886d5971a Part 3 : traceWeak in realm. r=jonco https://hg.mozilla.org/integration/autoland/rev/de287ed32236 Part 4 : traceWeak in object realm. r=jonco https://hg.mozilla.org/integration/autoland/rev/419ae7632252 Part 5 : traceWeak JitRuntime JitcodeGlobalTable. r=jonco https://hg.mozilla.org/integration/autoland/rev/b6584463c8a7 Part 6 : GCHashmap/GCHashTable traceWeak. r=jonco https://hg.mozilla.org/integration/autoland/rev/cf7481c207a8 Part 7 : realm->tracekWeakVarNames. r=jonco

I still need more time to debug the failure in devtools/debugger, after I discussed with Jonco, I'll revert the part 1 change (trace WeakMap), and fix it in another bug.
full try run https://treeherder.mozilla.org/#/jobs?repo=try&revision=1655be601b4b66a98f9b031c554d90b33b436b15

the mochitest devtools webreplay e10s seems problematic, but a try on the central branch also has the same failure, so I guess it's a known issue already.
https://treeherder.mozilla.org/#/jobs?repo=try&revision=55f2941f8c9347ac0c19dd940ed4fce1d7038da3

Flags: needinfo?(allstars.chh)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: