Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=480243392&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/H4qxmO3HSoqReV25QekTCw/runs/0/artifacts/public/logs/live_backing.log
[task 2024-10-29T13:24:10.039Z] 13:24:10 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-continue-to-here-click.js | Debugger is paused -
[task 2024-10-29T13:24:10.040Z] 13:24:10 INFO - Cmd+click on a line and check the debugger continues to that line
[task 2024-10-29T13:24:10.040Z] 13:24:10 INFO - Waiting for the debugger to resume
[task 2024-10-29T13:24:10.041Z] 13:24:10 INFO - Waiting for state change:
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - Console message: [JavaScript Error: "TypeError: event.target.closest is not a function" {file: "resource:///modules/UrlbarInput.sys.mjs" line: 4051}]
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - _on_mousedown@resource:///modules/UrlbarInput.sys.mjs:4051:26
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - handleEvent@resource:///modules/UrlbarInput.sys.mjs:663:23
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - synthesizeMouseAtPoint@chrome://mochikit/content/tests/SimpleTest/EventUtils.js:804:13
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - synthesizeMouse@chrome://mochikit/content/tests/SimpleTest/EventUtils.js:553:10
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - synthesizeMouseAtCenter@chrome://mochikit/content/tests/SimpleTest/EventUtils.js:977:10
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - clickDOMElement@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/shared-head.js:1998:14
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - cmdClickGutter@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/shared-head.js:2060:18
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - async*cmdClickLine@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-continue-to-here-click.js:47:9
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - @chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-continue-to-here-click.js:25:9
[task 2024-10-29T13:24:10.043Z] 13:24:10 INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1145:26
[task 2024-10-29T13:24:10.044Z] 13:24:10 INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1217:18
[task 2024-10-29T13:24:10.044Z] 13:24:10 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1358:14
[task 2024-10-29T13:24:10.044Z] 13:24:10 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1134:14
[task 2024-10-29T13:24:10.044Z] 13:24:10 INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1058:13
[task 2024-10-29T13:24:10.044Z] 13:24:10 INFO -
[task 2024-10-29T13:24:10.044Z] 13:24:10 INFO - Buffered messages finished
[task 2024-10-29T13:24:10.045Z] 13:24:10 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-continue-to-here-click.js | Test timed out -
[task 2024-10-29T13:24:10.045Z] 13:24:10 INFO - GECKO(1058) | Completed ShutdownLeaks collections in process 1058
[task 2024-10-29T13:24:10.046Z] 13:24:10 INFO - TEST-START | Shutdown
[task 2024-10-29T13:24:10.046Z] 13:24:10 INFO - Browser Chrome Test Summary
[task 2024-10-29T13:24:10.047Z] 13:24:10 INFO - Passed: 829
[task 2024-10-29T13:24:10.047Z] 13:24:10 INFO - Failed: 1
[task 2024-10-29T13:24:10.048Z] 13:24:10 INFO - Todo: 0
[task 2024-10-29T13:24:10.048Z] 13:24:10 INFO - Mode: e10s
[task 2024-10-29T13:24:10.049Z] 13:24:10 INFO - *** End BrowserChrome Test Results ***
[task 2024-10-29T13:24:10.359Z] 13:24:10 INFO - GECKO(1058) | 1730208250358 Marionette TRACE Received observer notification quit-application
[task 2024-10-29T13:24:10.361Z] 13:24:10 INFO - GECKO(1058) | 1730208250359 Marionette INFO Stopped listening on port 2828
[task 2024-10-29T13:24:10.367Z] 13:24:10 INFO - GECKO(1058) | 1730208250367 Marionette DEBUG Marionette stopped listening
[task 2024-10-29T13:24:10.895Z] 13:24:10 INFO - GECKO(1058) | JavaScript error: , line 0: NotFoundError: No such JSProcessActor 'DevToolsProcess'
[task 2024-10-29T13:24:10.900Z] 13:24:10 INFO - GECKO(1058) | JavaScript error: , line 0: NotFoundError: No such JSProcessActor 'DevToolsProcess'
[task 2024-10-29T13:24:10.901Z] 13:24:10 INFO - GECKO(1058) | JavaScript error: , line 0: NotFoundError: No such JSProcessActor 'DevToolsProcess'
[task 2024-10-29T13:24:10.932Z] 13:24:10 INFO - GECKO(1058) | JavaScript error: , line 0: NotFoundError: No such JSProcessActor 'DevToolsProcess'
[task 2024-10-29T13:24:10.933Z] 13:24:10 INFO - GECKO(1058) | JavaScript error: , line 0: NotFoundError: No such JSProcessActor 'DevToolsProcess'
[task 2024-10-29T13:24:11.119Z] 13:24:11 INFO - GECKO(1058) | console.error: "Error in worker resource://devtools/client/shared/source-map-loader/worker.js" ": "
[task 2024-10-29T13:24:15.321Z] 13:24:15 INFO - TEST-INFO | Main app process: exit 0
[task 2024-10-29T13:24:15.321Z] 13:24:15 INFO - runtests.py | Application ran for: 0:14:25.081530
[task 2024-10-29T13:24:15.321Z] 13:24:15 INFO - zombiecheck | Reading PID log: /tmp/tmpqra8lalapidlog
<...>
[task 2024-10-29T13:24:15.415Z] 13:24:15 INFO - zombiecheck | Checking for orphan process with PID: 3581
[task 2024-10-29T13:24:15.415Z] 13:24:15 INFO - zombiecheck | Checking for orphan process with PID: 1534
[task 2024-10-29T13:24:15.415Z] 13:24:15 INFO - Stopping web server
[task 2024-10-29T13:24:15.415Z] 13:24:15 INFO - Server shut down.
[task 2024-10-29T13:24:15.416Z] 13:24:15 INFO - Web server killed.
[task 2024-10-29T13:24:15.416Z] 13:24:15 INFO - Stopping web socket server
[task 2024-10-29T13:24:15.416Z] 13:24:15 INFO - Stopping ssltunnel
[task 2024-10-29T13:24:15.416Z] 13:24:15 INFO - Stopping gst for v4l2loopback
[task 2024-10-29T13:24:15.417Z] 13:24:15 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2024-10-29T13:24:15.417Z] 13:24:15 INFO - runtests.py | Running tests: end.
[task 2024-10-29T13:24:15.419Z] 13:24:15 INFO - Buffered messages finished
[task 2024-10-29T13:24:15.420Z] 13:24:15 INFO - TEST-INFO | checking window state
[task 2024-10-29T13:24:15.420Z] 13:24:15 INFO - Browser Chrome Test Summary
[task 2024-10-29T13:24:15.420Z] 13:24:15 INFO - Passed: 829
[task 2024-10-29T13:24:15.421Z] 13:24:15 INFO - Failed: 1
[task 2024-10-29T13:24:15.421Z] 13:24:15 INFO - Todo: 0
[task 2024-10-29T13:24:15.421Z] 13:24:15 INFO - Mode: e10s
[task 2024-10-29T13:24:15.421Z] 13:24:15 INFO - *** End BrowserChrome Test Results ***
[task 2024-10-29T13:24:15.422Z] 13:24:15 INFO - Buffered messages finished
[task 2024-10-29T13:24:15.422Z] 13:24:15 INFO - SUITE-END | took 866s
[task 2024-10-29T13:24:15.440Z] 13:24:15 INFO - Return code: 1
[task 2024-10-29T13:24:15.440Z] 13:24:15 WARNING - Got 1 unexpected statuses
[task 2024-10-29T13:24:15.440Z] 13:24:15 INFO - TinderboxPrint: mochitest-mochitest-devtools-chrome<br/>49/<em class="testfail">1</em>/0
[task 2024-10-29T13:24:15.441Z] 13:24:15 WARNING - setting return code to 1
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - The mochitest suite: mochitest-devtools-chrome ran with return status: WARNING
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running post-action listener: _package_coverage_data
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running post-action listener: _resource_record_post_action
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running post-action listener: process_java_coverage_data
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - [mozharness: 2024-10-29 13:24:15.441515Z] Finished run-tests step (success)
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - [mozharness: 2024-10-29 13:24:15.441560Z] Running uninstall step.
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running main action method: uninstall
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Skipping uninstall for non-MSIX test
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running post-action listener: _resource_record_post_action
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - [mozharness: 2024-10-29 13:24:15.441760Z] Finished uninstall step (success)
[task 2024-10-29T13:24:15.441Z] 13:24:15 INFO - Running post-run listener: _resource_record_post_run
[task 2024-10-29T13:24:15.710Z] 13:24:15 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-devtools-chrome.11.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-4"], "subtests": [{"name": "cpu_percent", "value": 43.62401283043898}, {"name": "io_write_bytes", "value": 1514762240}, {"name": "io.read_bytes", "value": 57344}, {"name": "io_write_time", "value": 1416604}, {"name": "io_read_time", "value": 64}]}, {"name": "mochitest.mochitest-devtools-chrome.11.start-pulseaudio", "subtests": [{"name": "time", "value": 0.012393466000048647}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-devtools-chrome.11.install", "subtests": [{"name": "time", "value": 36.90495775099998}, {"name": "cpu_percent", "value": 25.258355978260866}]}, {"name": "mochitest.mochitest-devtools-chrome.11.stage-files", "subtests": [{"name": "time", "value": 0.0002464780000082101}, {"name": "cpu_percent", "value": 0}]}, {"name": "mochitest.mochitest-devtools-chrome.11.run-tests", "subtests": [{"name": "time", "value": 867.0833473040002}, {"name": "cpu_percent", "value": 44.40620170683874}]}, {"name": "mochitest.mochitest-devtools-chrome.11.uninstall", "subtests": [{"name": "time", "value": 0.0001343290000477282}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - Total resource usage - Wall time: 904s; CPU: Can't collect data; Read bytes: 57344; Write bytes: 1514762240; Read time: 64; Write time: 1416604
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - TinderboxPrint: I/O read bytes / time<br/>57,344 / 64
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - TinderboxPrint: I/O write bytes / time<br/>1,514,762,240 / 1,416,604
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - TinderboxPrint: CPU idle<br/>2,005.8 (55.7%)
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - TinderboxPrint: CPU system<br/>113.5 (3.1%)
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - TinderboxPrint: CPU user<br/>1,456.4 (40.4%)
[task 2024-10-29T13:24:15.712Z] 13:24:15 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-10-29T13:24:15.713Z] 13:24:15 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-10-29T13:24:15.716Z] 13:24:15 INFO - install - Wall time: 37s; CPU: 25%; Read bytes: 0; Write bytes: 164356096; Read time: 0; Write time: 125584
[task 2024-10-29T13:24:15.717Z] 13:24:15 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-10-29T13:24:15.758Z] 13:24:15 INFO - run-tests - Wall time: 867s; CPU: 44%; Read bytes: 57344; Write bytes: 1350406144; Read time: 64; Write time: 1291020
[task 2024-10-29T13:24:15.760Z] 13:24:15 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-10-29T13:24:17.132Z] 13:24:17 WARNING - returning nonzero exit status 1
[task 2024-10-29T13:24:17.187Z] cleanup
[task 2024-10-29T13:24:17.188Z] + cleanup
[task 2024-10-29T13:24:17.188Z] + local rv=1
[task 2024-10-29T13:24:17.188Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-10-29T13:24:17.188Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-10-29T13:24:17.189Z] + '[' ']'
[task 2024-10-29T13:24:17.189Z] + true
[task 2024-10-29T13:24:17.189Z] + cleanup_xvfb
[task 2024-10-29T13:24:17.189Z] ++ pidof Xvfb
[task 2024-10-29T13:24:17.194Z] + local xvfb_pid=52
[task 2024-10-29T13:24:17.194Z] + local vnc=false
[task 2024-10-29T13:24:17.194Z] + local interactive=false
[task 2024-10-29T13:24:17.194Z] + '[' -n 52 ']'
[task 2024-10-29T13:24:17.194Z] + [[ false == false ]]
[task 2024-10-29T13:24:17.194Z] + [[ false == false ]]
[task 2024-10-29T13:24:17.194Z] + kill 52
[task 2024-10-29T13:24:17.194Z] + screen -XS xvfb quit
[task 2024-10-29T13:24:17.201Z] + exit 1
[taskcluster 2024-10-29 13:24:17.615Z] === Task Finished ===
[taskcluster 2024-10-29 13:24:19.441Z] Unsuccessful task run with exit code: 1 completed in 975.518 seconds
Description
•