Intermittent browser/modules/test/browser/browser_UsageTelemetry_interaction.js | single tracking bug
Categories
(Firefox :: Settings UI, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=495216370&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/e6OGtPLqRQum6FK9r3z5RQ/runs/0/artifacts/public/logs/live_backing.log
TEST-PASS | browser/modules/test/browser/browser_UsageTelemetry_interaction.js | Expected to see the correct value for menu-selectAll in menu_bar. - 1 == 1 -
[task 2025-02-16T16:55:42.759Z] 16:55:42 INFO - Leaving test bound mainMenu
[task 2025-02-16T16:55:42.759Z] 16:55:42 INFO - Entering test bound preferences
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - Console message: [JavaScript Error: "NS_ERROR_FAILURE: failed to get file: DfltDwnld" {file: "resource://testing-common/AppData.sys.mjs" line: 64}]
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - getFile@resource://testing-common/AppData.sys.mjs:64:24
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - _getDirectory@resource://gre/modules/DownloadIntegration.sys.mjs:900:28
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - getSystemDownloadsDirectory@resource://gre/modules/DownloadIntegration.sys.mjs:280:41
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - getSystemDownloadsDirectory@resource://gre/modules/Downloads.sys.mjs:261:37
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - _getDownloadsFolder@chrome://browser/content/preferences/main.js:3732:44
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - _getSystemDownloadFolderDetails@chrome://browser/content/preferences/main.js:3609:35
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - displayDownloadDirPrefTask@chrome://browser/content/preferences/main.js:3590:18
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - displayDownloadDirPref@chrome://browser/content/preferences/main.js:3557:10
[task 2025-02-16T16:55:42.760Z] 16:55:42 INFO - init@chrome://browser/content/preferences/main.js:759:15
[task 2025-02-16T16:55:42.761Z] 16:55:42 INFO - init@chrome://browser/content/preferences/preferences.js:176:22
[task 2025-02-16T16:55:42.761Z] 16:55:42 INFO - gotoPref@chrome://browser/content/preferences/preferences.js:360:16
[task 2025-02-16T16:55:42.761Z] 16:55:42 INFO - init_all@chrome://browser/content/preferences/preferences.js:252:3
[task 2025-02-16T16:55:42.761Z] 16:55:42 INFO - EventListener.handleEvent*@chrome://browser/content/preferences/preferences.js:186:10
[task 2025-02-16T16:55:42.761Z] 16:55:42 INFO -
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - Console message: [JavaScript Error: "NS_ERROR_FAILURE: failed to get file: Desk" {file: "resource://testing-common/AppData.sys.mjs" line: 64}]
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - getFile@resource://testing-common/AppData.sys.mjs:64:24
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - _getDownloadsFolder@chrome://browser/content/preferences/main.js:3730:32
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - _getSystemDownloadFolderDetails@chrome://browser/content/preferences/main.js:3610:33
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - async*displayDownloadDirPrefTask@chrome://browser/content/preferences/main.js:3590:18
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - displayDownloadDirPref@chrome://browser/content/preferences/main.js:3557:10
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - init@chrome://browser/content/preferences/main.js:759:15
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - init@chrome://browser/content/preferences/preferences.js:176:22
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - gotoPref@chrome://browser/content/preferences/preferences.js:360:16
[task 2025-02-16T16:55:42.762Z] 16:55:42 INFO - init_all@chrome://browser/content/preferences/preferences.js:252:3
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - EventListener.handleEvent*@chrome://browser/content/preferences/preferences.js:186:10
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO -
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - Buffered messages logged at 16:55:13
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - Console message: [JavaScript Error: "NS_ERROR_FAILURE: failed to get file: Docs" {file: "resource://testing-common/AppData.sys.mjs" line: 64}]
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - getFile@resource://testing-common/AppData.sys.mjs:64:24
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - @resource:///modules/backup/BackupService.sys.mjs:108:26
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - takeMeasurements@resource:///modules/backup/BackupService.sys.mjs:2918:22
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - async*onIdle@resource:///modules/backup/BackupService.sys.mjs:3358:12
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - onObserve@resource:///modules/backup/BackupService.sys.mjs:3285:14
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO - @resource:///modules/backup/BackupService.sys.mjs:3192:12
[task 2025-02-16T16:55:42.763Z] 16:55:42 INFO -
[task 2025-02-16T16:55:42.764Z] 16:55:42 INFO - Buffered messages finished
[task 2025-02-16T16:55:42.765Z] 16:55:42 INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_UsageTelemetry_interaction.js | Test timed out -
[task 2025-02-16T16:55:42.765Z] 16:55:42 INFO - GECKO(20346) | Completed ShutdownLeaks collections in process 20346
[task 2025-02-16T16:55:42.765Z] 16:55:42 INFO - TEST-START | Shutdown
[task 2025-02-16T16:55:42.766Z] 16:55:42 INFO - Browser Chrome Test Summary
[task 2025-02-16T16:55:42.766Z] 16:55:42 INFO - Passed: 974
[task 2025-02-16T16:55:42.766Z] 16:55:42 INFO - Failed: 1
[task 2025-02-16T16:55:42.767Z] 16:55:42 INFO - Todo: 0
[task 2025-02-16T16:55:42.767Z] 16:55:42 INFO - Mode: e10s
[task 2025-02-16T16:55:42.767Z] 16:55:42 INFO - *** End BrowserChrome Test Results ***
[task 2025-02-16T16:55:42.768Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.768Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.768Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.768Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.769Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.769Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.770Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.770Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.770Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.771Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.771Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.771Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.772Z] 16:55:42 INFO - GECKO(20346) | Exiting due to channel error.
[task 2025-02-16T16:55:42.772Z] 16:55:42 INFO - TEST-INFO | Main app process: exit 0
[task 2025-02-16T16:55:42.772Z] 16:55:42 INFO - TEST-UNEXPECTED-FAIL | browser/modules/test/browser/browser_UsageTelemetry_interaction.js | Application shut down (without crashing) in the middle of a test!
[task 2025-02-16T16:55:42.772Z] 16:55:42 INFO - TEST-INFO took 44620ms
INFO - runtests.py | Application ran for: 0:01:57.408723
[task 2025-02-16T16:55:42.773Z] 16:55:42 INFO - zombiecheck | Reading PID log: /tmp/tmpxsg30hx2pidlog
[task 2025-02-16T16:55:42.773Z] 16:55:42 INFO - ==> process 20346 launched child process 20422
[task 2025-02-16T16:55:42.774Z] 16:55:42 INFO - zombiecheck | Checking for orphan process with PID: 20422
[task 2025-02-16T16:55:42.774Z] 16:55:42 INFO - Stopping web server
[task 2025-02-16T16:55:42.774Z] 16:55:42 INFO - Server shut down.
[task 2025-02-16T16:55:42.775Z] 16:55:42 INFO - Web server killed.
[task 2025-02-16T16:55:42.775Z] 16:55:42 INFO - Stopping web socket server
[task 2025-02-16T16:55:42.775Z] 16:55:42 INFO - Stopping ssltunnel
[task 2025-02-16T16:55:42.776Z] 16:55:42 INFO - Stopping gst for v4l2loopback
[task 2025-02-16T16:55:42.776Z] 16:55:42 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2025-02-16T16:55:42.776Z] 16:55:42 INFO - runtests.py | Running tests: end.
[task 2025-02-16T16:55:42.892Z] 16:55:42 INFO - Buffered messages finished
[task 2025-02-16T16:55:42.893Z] 16:55:42 INFO - Running manifest: dom/base/test/fmm/browser.toml
[task 2025-02-16T16:55:42.963Z] 16:55:42 INFO - Setting pipeline to PAUSED ...
[task 2025-02-16T16:55:42.963Z] 16:55:42 INFO - Pipeline is PREROLLING ...
[task 2025-02-16T16:55:42.981Z] 16:55:42 INFO - Pipeline is PREROLLED ...
[task 2025-02-16T16:55:42.981Z] 16:55:42 INFO - Setting pipeline to PLAYING ...
[task 2025-02-16T16:55:42.981Z] 16:55:42 INFO - New clock: GstSystemClock
[task 2025-02-16T16:55:43.014Z] 16:55:43 INFO - Got EOS from element "pipeline0".
[task 2025-02-16T16:55:43.015Z] 16:55:43 INFO - Execution ended after 0:00:00.033461418
[task 2025-02-16T16:55:43.015Z] 16:55:43 INFO - Setting pipeline to PAUSED ...
[task 2025-02-16T16:55:43.015Z] 16:55:43 INFO - Setting pipeline to READY ...
[task 2025-02-16T16:55:43.017Z] 16:55:43 INFO - (gst-launch-1.0:23893): GStreamer-CRITICAL **: 16:55:43.014: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2025-02-16T16:55:43.017Z] 16:55:43 INFO - Setting pipeline to NULL ...
[task 2025-02-16T16:55:43.017Z] 16:55:43 INFO - Freeing pipeline ...
[task 2025-02-16T16:55:43.575Z] 16:55:43 INFO - PID 23908 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2025-02-16T16:55:43.575Z] 16:55:43 INFO -
[task 2025-02-16T16:55:43.588Z] 16:55:43 INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-e', "const _PROFILE_PATH = '/tmp/tmphwllpr16.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = '/builds/worker/workspace/build/tests/bin/components';", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2025-02-16T16:55:43.589Z] 16:55:43 INFO - runtests.py | Server pid: 23910
[task 2025-02-16T16:55:43.590Z] 16:55:43 INFO - runtests.py | Websocket server pid: 23911
[task 2025-02-16T16:55:43.597Z] 16:55:43 INFO - runtests.py | SSL tunnel pid: 23912
[task 2025-02-16T16:55:43.698Z] 16:55:43 INFO - use http3 server: 0
[task 2025-02-16T16:55:43.700Z] 16:55:43 INFO - runtests.py | Running with scheme: http
[task 2025-02-16T16:55:43.700Z] 16:55:43 INFO - runtests.py | Running with e10s: True
[task 2025-02-16T16:55:43.700Z] 16:55:43 INFO - runtests.py | Running with fission: True
[task 2025-02-16T16:55:43.701Z] 16:55:43 INFO - runtests.py | Running with cross-origin iframes: False
[task 2025-02-16T16:55:43.701Z] 16:55:43 INFO - runtests.py | Running with socketprocess_e10s: True
[task 2025-02-16T16:55:43.701Z] 16:55:43 INFO - runtests.py | Running tests: start.
[task 2025-02-16T16:55:43.701Z] 16:55:43 INFO -
[task 2025-02-16T16:55:43.784Z] 16:55:43 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmphwllpr16.mozrunner
[task 2025-02-16T16:55:43.789Z] 16:55:43 INFO - runtests.py | Application pid: 23946
[task 2025-02-16T16:55:43.789Z] 16:55:43 INFO - TEST-INFO | started process GECKO(23946)
[task 2025-02-16T16:55:44.167Z] 16:55:44 INFO - GECKO(23946) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2025-02-16T16:55:44.182Z] 16:55:44 INFO - GECKO(23946) | 1739724944181 Marionette INFO Marionette enabled
[task 2025-02-16T16:55:44.183Z] 16:55:44 INFO - GECKO(23946) | 1739724944182 Marionette TRACE Received observer notification final-ui-startup
[task 2025-02-16T16:55:44.231Z] 16:55:44 INFO - GECKO(23946) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2025-02-16T16:55:44.237Z] 16:55:44 INFO - GECKO(23946) | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2025-02-16T16:55:44.273Z] 16:55:44 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_socket_pid24037.log
[task 2025-02-16T16:55:44.281Z] 16:55:44 INFO - GECKO(23946) | 1739724944280 Marionette INFO Listening on port 2828
[task 2025-02-16T16:55:44.283Z] 16:55:44 INFO - GECKO(23946) | 1739724944282 Marionette DEBUG Marionette is listening
[task 2025-02-16T16:55:44.296Z] 16:55:44 INFO - GECKO(23946) | 1739724944295 Marionette DEBUG Accepted connection 0 from 127.0.0.1:55198
[task 2025-02-16T16:55:44.308Z] 16:55:44 INFO - GECKO(23946) | 1739724944307 Marionette DEBUG Accepted connection 1 from 127.0.0.1:55200
[task 2025-02-16T16:55:44.309Z] 16:55:44 INFO - GECKO(23946) | 1739724944307 Marionette DEBUG Closed connection 0
[task 2025-02-16T16:55:44.413Z] 16:55:44 INFO - GECKO(23946) | 1739724944412 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2025-02-16T16:55:44.415Z] 16:55:44 INFO - GECKO(23946) | 1739724944414 Marionette DEBUG Waiting for initial application window
[task 2025-02-16T16:55:44.464Z] 16:55:44 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_tab_pid24058.log
[task 2025-02-16T16:55:44.470Z] 16:55:44 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_rdd_pid24067.log
[task 2025-02-16T16:55:44.594Z] 16:55:44 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_tab_pid24102.log
[task 2025-02-16T16:55:46.376Z] 16:55:46 INFO - GECKO(23946) | console.error: ({})
[task 2025-02-16T16:55:47.164Z] 16:55:47 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_utility_pid24154.log
[task 2025-02-16T16:55:47.186Z] 16:55:47 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_tab_pid24163.log
[task 2025-02-16T16:55:47.211Z] 16:55:47 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_tab_pid24181.log
[task 2025-02-16T16:55:47.213Z] 16:55:47 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_tab_pid24185.log
[task 2025-02-16T16:55:47.261Z] 16:55:47 INFO - GECKO(23946) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmphwllpr16.mozrunner/runtests_leaks_tab_pid24229.log
[task 2025-02-16T16:55:47.334Z] 16:55:47 INFO - GECKO(23946) | 1739724947331 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2025-02-16T16:55:47.339Z] 16:55:47 INFO - GECKO(23946) | 1739724947337 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2025-02-16T16:55:47.339Z] 16:55:47 INFO - GECKO(23946) | 1739724947337 RemoteAgent TRACE [9] ProgressListener Setting unload timer (5000ms)
<...>
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 23•8 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Reporter | ||
Comment 26•7 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=527613569&repo=try
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 31•5 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
| Comment hidden (Intermittent Failures Robot) |
| Reporter | ||
Comment 33•2 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=551035943&repo=try
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Comment 43•1 month ago
|
||
I've taken a bit of a run at this, but nothing in the log or the test would explain to me why this has gotten more frequent since, say, March 24. Popping this out to hopefully a more-accurate component.
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
Description
•