Open Bug 1905427 Opened 2 days ago

Intermittent damp | jstracer/jstracer.js: Test timed out

Categories

(Testing :: Talos, defect, P5)

Product:
Testing
Component:
Talos
Type:
defect

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2024-06-28T17:49:26.608Z] 17:49:26     INFO -  PID 1397 | Garbage collect
[task 2024-06-28T17:49:26.925Z] 17:49:26     INFO -  PID 1397 | toolbox/panels-in-background.js took 3132ms.
[task 2024-06-28T17:49:26.925Z] 17:49:26     INFO -  PID 1397 | Loading test 'toolbox/screenshot.js'
[task 2024-06-28T17:49:26.925Z] 17:49:26     INFO -  PID 1397 | Executing test 'toolbox/screenshot.js'
[task 2024-06-28T17:49:26.933Z] 17:49:26     INFO -  PID 1397 | Wait for a pageshow event for browsing context 44
[task 2024-06-28T17:49:27.409Z] 17:49:27     INFO -  PID 1397 | Received pageshow event for 44
[task 2024-06-28T17:49:27.660Z] 17:49:27     INFO -  PID 1397 | Open toolbox on 'webconsole'
[task 2024-06-28T17:49:27.660Z] 17:49:27     INFO -  PID 1397 | Open toolbox - Call showToolboxForTab
[task 2024-06-28T17:49:29.223Z] 17:49:29     INFO -  PID 1397 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2024-06-28T17:49:29.228Z] 17:49:29     INFO -  PID 1397 | Garbage collect
[task 2024-06-28T17:49:29.508Z] 17:49:29     INFO -  PID 1397 | toolbox/screenshot.js took 2583ms.
[task 2024-06-28T17:49:29.508Z] 17:49:29     INFO -  PID 1397 | Loading test 'toolbox/browser-toolbox.js'
[task 2024-06-28T17:49:29.508Z] 17:49:29     INFO -  PID 1397 | Executing test 'toolbox/browser-toolbox.js'
[task 2024-06-28T17:49:29.518Z] 17:49:29     INFO -  PID 1397 | Wait for a pageshow event for browsing context 47
[task 2024-06-28T17:49:29.542Z] 17:49:29     INFO -  PID 1397 | Received pageshow event for 47
[task 2024-06-28T17:49:29.716Z] 17:49:29     INFO -  PID 1397 | DevTools Server for Browser Toolbox listening on port: 64943
[task 2024-06-28T17:49:29.719Z] 17:49:29     INFO -  PID 1397 | Starting Browser Toolbox /opt/worker/tasks/task_171959579237711/build/application/Firefox Nightly.app/Contents/MacOS/firefox -no-remote -foreground -profile /var/folders/40/r3s4v7650l5_ctg0b7p2x2_r000014/T/tmp8ssggh8f/profile/chrome_debugger_profile -chrome chrome://devtools/content/framework/browser-toolbox/window.html
[task 2024-06-28T17:49:31.189Z] 17:49:31     INFO -  PID 1397 | 1452> Wait for debugger to initialize
[task 2024-06-28T17:49:31.774Z] 17:49:31     INFO -  PID 1397 | 1452> Wait for tab source in the content process
[task 2024-06-28T17:49:31.775Z] 17:49:31     INFO -  PID 1397 | 1452> Select this source
[task 2024-06-28T17:49:32.766Z] 17:49:32     INFO -  PID 1397 | 1452> Wait for test page console message to appear
[task 2024-06-28T17:49:33.224Z] 17:49:33     INFO -  PID 1397 | Garbage collect
[task 2024-06-28T17:49:33.534Z] 17:49:33     INFO -  PID 1397 | toolbox/browser-toolbox.js took 4026ms.
[task 2024-06-28T17:49:33.534Z] 17:49:33     INFO -  PID 1397 | Loading test 'server/protocol.js'
[task 2024-06-28T17:49:33.535Z] 17:49:33     INFO -  PID 1397 | Executing test 'server/protocol.js'
[task 2024-06-28T17:49:33.543Z] 17:49:33     INFO -  PID 1397 | Wait for a pageshow event for browsing context 48
[task 2024-06-28T17:49:33.570Z] 17:49:33     INFO -  PID 1397 | Received pageshow event for 48
[task 2024-06-28T17:49:33.742Z] 17:49:33     INFO -  PID 1397 | Open toolbox on 'options'
[task 2024-06-28T17:49:33.742Z] 17:49:33     INFO -  PID 1397 | Open toolbox - Call showToolboxForTab
[task 2024-06-28T17:49:34.579Z] 17:49:34     INFO -  PID 1397 | Garbage collect
[task 2024-06-28T17:49:34.744Z] 17:49:34     INFO -  PID 1397 | server/protocol.js took 1210ms.
[task 2024-06-28T17:49:34.744Z] 17:49:34     INFO -  PID 1397 | Loading test 'source-map/source-map-loader.js'
[task 2024-06-28T17:49:34.744Z] 17:49:34     INFO -  PID 1397 | Executing test 'source-map/source-map-loader.js'
[task 2024-06-28T17:49:34.752Z] 17:49:34     INFO -  PID 1397 | Wait for a pageshow event for browsing context 51
[task 2024-06-28T17:49:34.762Z] 17:49:34     INFO -  PID 1397 | Received pageshow event for 51
[task 2024-06-28T17:49:35.136Z] 17:49:35     INFO -  PID 1397 | Garbage collect
[task 2024-06-28T17:49:35.298Z] 17:49:35     INFO -  PID 1397 | source-map/source-map-loader.js took 554ms.
[task 2024-06-28T17:49:35.298Z] 17:49:35     INFO -  PID 1397 | Loading test 'source-map/source-map-library.js'
[task 2024-06-28T17:49:35.305Z] 17:49:35     INFO -  PID 1397 | Executing test 'source-map/source-map-library.js'
[task 2024-06-28T17:49:35.312Z] 17:49:35     INFO -  PID 1397 | Wait for a pageshow event for browsing context 52
[task 2024-06-28T17:49:35.328Z] 17:49:35     INFO -  PID 1397 | Received pageshow event for 52
[task 2024-06-28T17:49:36.983Z] 17:49:36     INFO -  PID 1397 | Garbage collect
[task 2024-06-28T17:49:37.161Z] 17:49:37     INFO -  PID 1397 | source-map/source-map-library.js took 1863ms.
[task 2024-06-28T17:49:37.161Z] 17:49:37     INFO -  PID 1397 | Loading test 'jstracer/jstracer.js'
[task 2024-06-28T17:49:37.161Z] 17:49:37     INFO -  PID 1397 | Executing test 'jstracer/jstracer.js'
[task 2024-06-28T17:49:37.170Z] 17:49:37     INFO -  PID 1397 | Wait for a pageshow event for browsing context 53
[task 2024-06-28T17:49:37.232Z] 17:49:37     INFO -  PID 1397 | Received pageshow event for 53
[task 2024-06-28T17:49:37.345Z] 17:49:37     INFO -  PID 1397 | Open toolbox on 'options'
[task 2024-06-28T17:49:37.345Z] 17:49:37     INFO -  PID 1397 | Open toolbox - Call showToolboxForTab
[task 2024-06-28T17:49:39.056Z] 17:49:39     INFO -  PID 1397 | Stop tracing JavaScript
[task 2024-06-28T17:54:37.279Z] 17:54:37     INFO -  PID 1397 | TEST-UNEXPECTED-FAIL | damp | jstracer/jstracer.js: Test timed out
[task 2024-06-28T17:54:37.286Z] 17:54:37     INFO -  PID 1397 | [DampLoad helper] Unregister DampLoad actors
[task 2024-06-28T18:54:37.541Z] 18:54:37     INFO - Automation Error: mozharness timed out after 3600 seconds running ['/opt/worker/tasks/task_171959579237711/build/venv/bin/python', '/opt/worker/tasks/task_171959579237711/build/tests/talos/talos/run_tests.py', '--executablePath', '/opt/worker/tasks/task_171959579237711/build/application/Firefox Nightly.app/Contents/MacOS/firefox', '--suite', 'damp-other', '--title', 'macmini-r8-232', '--symbolsPath', 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/e3W9g-g4TVaDN6X58wmI2A/artifacts/public/build/target.crashreporter-symbols.zip', '--project', 'autoland', '--screenshot-on-failure', '--setpref=talos.damp.suite=other', '--setpref=gfx.webrender.software=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--log-tbpl-level=info', '--log-errorsummary=/opt/worker/tasks/task_171959579237711/build/blobber_upload_dir/damp-other_errorsummary.log']
[task 2024-06-28T18:54:37.544Z] 18:54:37     INFO - Return code: -9
[task 2024-06-28T18:54:37.544Z] 18:54:37  WARNING - setting return code to -9
[task 2024-06-28T18:54:37.544Z] 18:54:37  WARNING - setting return code to 2
[task 2024-06-28T18:54:37.544Z] 18:54:37     INFO - Running post-action listener: _package_coverage_data
[task 2024-06-28T18:54:37.544Z] 18:54:37     INFO - Running post-action listener: _resource_record_post_action
[task 2024-06-28T18:54:37.544Z] 18:54:37     INFO - Running post-action listener: process_java_coverage_data
[task 2024-06-28T18:54:37.544Z] 18:54:37     INFO - [mozharness: 2024-06-28 18:54:37.544650Z] Finished run-tests step (success)
[task 2024-06-28T18:54:37.544Z] 18:54:37     INFO - Running post-run listener: _resource_record_post_run
[task 2024-06-28T18:54:38.898Z] 18:54:38     INFO - Total resource usage - Wall time: 4396s; CPU: 3%; Read bytes: 305442816; Write bytes: 1799684096; Read time: 7962; Write time: 19524
[task 2024-06-28T18:54:38.898Z] 18:54:38     INFO - TinderboxPrint: CPU usage<br/>3.0%
[task 2024-06-28T18:54:38.898Z] 18:54:38     INFO - TinderboxPrint: I/O read bytes / time<br/>305,442,816 / 7,962
[task 2024-06-28T18:54:38.898Z] 18:54:38     INFO - TinderboxPrint: I/O write bytes / time<br/>1,799,684,096 / 19,524
[task 2024-06-28T18:54:38.898Z] 18:54:38     INFO - TinderboxPrint: CPU idle<br/>51,324.9 (97.3%)
[task 2024-06-28T18:54:38.899Z] 18:54:38     INFO - TinderboxPrint: CPU system<br/>647.8 (1.2%)
[task 2024-06-28T18:54:38.899Z] 18:54:38     INFO - TinderboxPrint: CPU user<br/>767.5 (1.5%)
[task 2024-06-28T18:54:38.899Z] 18:54:38     INFO - TinderboxPrint: Swap in / out<br/>647,540,736 / 0
[task 2024-06-28T18:54:38.906Z] 18:54:38     INFO - install - Wall time: 39s; CPU: 17%; Read bytes: 612242944; Write bytes: 522784768; Read time: 43921; Write time: 852
[task 2024-06-28T18:54:39.110Z] 18:54:39     INFO - run-tests - Wall time: 4357s; CPU: 3%; Read bytes: 191229952; Write bytes: 1276895232; Read time: 7114; Write time: 18672
[task 2024-06-28T18:54:45.179Z] 18:54:45  WARNING - returning nonzero exit status 2
[taskcluster 2024-06-28T18:54:45.442Z]    Exit Code: 2
[taskcluster 2024-06-28T18:54:45.442Z]    User Time: 9m20.288406s
[taskcluster 2024-06-28T18:54:45.442Z]  Kernel Time: 1m26.348014s
[taskcluster 2024-06-28T18:54:45.442Z]    Wall Time: 1h15m20.867242s
[taskcluster 2024-06-28T18:54:45.442Z]       Result: FAILED
[taskcluster 2024-06-28T18:54:45.443Z] === Task Finished ===
[taskcluster 2024-06-28T18:54:45.443Z] Task Duration: 1h15m20.870764s
[taskcluster 2024-06-28T18:54:46.663Z] Uploading artifact public/logs/localconfig.json from file /opt/worker/tasks/task_171959579237711/logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-06-28T12:29:34.015Z
[taskcluster 2024-06-28T18:54:46.961Z] Uploading artifact public/test_info/damp-other_errorsummary.log from file /opt/worker/tasks/task_171959579237711/build/blobber_upload_dir/damp-other_errorsummary.log with content encoding "gzip", mime type "text/plain" and expiry 2025-06-28T12:29:34.015Z
[taskcluster 2024-06-28T18:54:47.219Z] Uploading artifact public/test_info/profile_resource-usage.json from file /opt/worker/tasks/task_171959579237711/build/blobber_upload_dir/profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-06-28T12:29:34.015Z
[taskcluster 2024-06-28T18:54:47.608Z] Uploading artifact public/test_info/resource-usage.json from file /opt/worker/tasks/task_171959579237711/build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-06-28T12:29:34.015Z
[taskcluster 2024-06-28T18:54:48.565Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-06-28T12:29:34.015Z
[taskcluster:error] exit status 2
You need to log in before you can comment on or make changes to this bug.