Closed
Bug 1949557
Opened 28 days ago
Closed 3 days ago
Intermittent browser/components/shopping/tests/browser/browser_shoppingsidebar_telemetry.js | single tracking bug
Categories
(Firefox :: Shopping, defect, P5)
Firefox
Shopping
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=495887437&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Tx8bP0OgTMabYuSZxniqyw/runs/0/artifacts/public/logs/live_backing.log
[task 2025-02-20T18:42:27.051Z] 18:42:27 INFO - TEST-START | browser/components/shopping/tests/browser/browser_shoppingsidebar_telemetry.js
[task 2025-02-20T18:42:27.144Z] 18:42:27 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:42:27.144Z] 18:42:27 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:42:27.145Z] 18:42:27 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:42:27.145Z] 18:42:27 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:42:27.145Z] 18:42:27 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:42:27.145Z] 18:42:27 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:42:27.145Z] 18:42:27 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:42:27.145Z] 18:42:27 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:42:27.801Z] 18:42:27 INFO - GECKO(1057) | console.error: (new Error("Fetching HPKE config from https://example.com/ohttp-config failed with error 404", "resource://gre/modules/HPKEConfigManager.sys.mjs", 58))
[task 2025-02-20T18:42:27.805Z] 18:42:27 INFO - GECKO(1057) | console.error: (new Error("OHTTP was configured for shopping but we couldn't get a valid config.", "chrome://global/content/shopping/ShoppingProduct.mjs", 494))
[task 2025-02-20T18:42:27.882Z] 18:42:27 INFO - GECKO(1057) | console.error: (new Error("Fetching HPKE config from https://example.com/ohttp-config failed with error 404", "resource://gre/modules/HPKEConfigManager.sys.mjs", 58))
[task 2025-02-20T18:42:27.886Z] 18:42:27 INFO - GECKO(1057) | console.error: (new Error("OHTTP was configured for shopping but we couldn't get a valid config.", "chrome://global/content/shopping/ShoppingProduct.mjs", 494))
[task 2025-02-20T18:42:27.888Z] 18:42:27 INFO - GECKO(1057) | console.error: "Failed to fetch product analysis data" (new Error("request failed", "resource:///actors/ShoppingSidebarChild.sys.mjs", 435))
[task 2025-02-20T18:42:28.203Z] 18:42:28 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:42:28.204Z] 18:42:28 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:42:28.205Z] 18:42:28 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:42:28.206Z] 18:42:28 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:42:28.207Z] 18:42:28 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:42:28.210Z] 18:42:28 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:42:28.210Z] 18:42:28 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:42:28.212Z] 18:42:28 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:42:29.175Z] 18:42:29 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:42:29.176Z] 18:42:29 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:42:29.176Z] 18:42:29 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:42:29.176Z] 18:42:29 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:42:29.177Z] 18:42:29 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:42:29.178Z] 18:42:29 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:42:29.178Z] 18:42:29 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:42:29.178Z] 18:42:29 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:43:57.231Z] 18:43:57 INFO - TEST-INFO | started process screentopng
[task 2025-02-20T18:43:57.435Z] 18:43:57 INFO - TEST-INFO | screentopng: exit 0
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Buffered messages logged at 18:42:27
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Entering test bound test_shopping_sidebar_displayed
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "https://example.com/product/B09TJGHL5F" line: 0}]
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - TEST-PASS | browser/components/shopping/tests/browser/browser_shoppingsidebar_telemetry.js | Sidebar should be visible. - true == true -
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Buffered messages finished
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - TEST-UNEXPECTED-FAIL | browser/components/shopping/tests/browser/browser_shoppingsidebar_telemetry.js | Test timed out -
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - GECKO(1057) | Completed ShutdownLeaks collections in process 1057
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - TEST-START | Shutdown
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Browser Chrome Test Summary
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Passed: 363
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Failed: 1
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Todo: 0
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - Mode: e10s
[task 2025-02-20T18:43:57.436Z] 18:43:57 INFO - *** End BrowserChrome Test Results ***
[task 2025-02-20T18:43:57.452Z] 18:43:57 INFO - GECKO(1057) | 1740077037450 Marionette TRACE Received observer notification quit-application
[task 2025-02-20T18:43:57.453Z] 18:43:57 INFO - GECKO(1057) | 1740077037450 Marionette INFO Stopped listening on port 2828
[task 2025-02-20T18:43:57.457Z] 18:43:57 INFO - GECKO(1057) | 1740077037455 Marionette DEBUG Marionette stopped listening
[task 2025-02-20T18:44:02.350Z] 18:44:02 INFO - GECKO(1057) | [Parent 1057, IPC I/O Parent] WARNING: Process 2330 may be hanging at shutdown; will wait for up to 40000ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:203
[task 2025-02-20T18:44:02.772Z] 18:44:02 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:02.773Z] 18:44:02 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:02.773Z] 18:44:02 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:02.774Z] 18:44:02 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:02.775Z] 18:44:02 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:02.775Z] 18:44:02 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:02.862Z] 18:44:02 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:02.863Z] 18:44:02 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:02.865Z] 18:44:02 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:02.867Z] 18:44:02 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:02.869Z] 18:44:02 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:02.870Z] 18:44:02 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:02.950Z] 18:44:02 INFO - GECKO(1057) | [Parent 1057, IPC I/O Parent] WARNING: Process 2371 may be hanging at shutdown; will wait for up to 39400ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:203
[task 2025-02-20T18:44:03.321Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.322Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.323Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.324Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.325Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.325Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.355Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.356Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.357Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.358Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.359Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.360Z] 18:44:03 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:44:03.361Z] 18:44:03 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:44:03.361Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.439Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.441Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.442Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.443Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.444Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.445Z] 18:44:03 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:44:03.445Z] 18:44:03 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:44:03.446Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.550Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.552Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.554Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.556Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.557Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.558Z] 18:44:03 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:44:03.560Z] 18:44:03 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:44:03.560Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.560Z] 18:44:03 INFO - GECKO(1057) | [Parent 1057, IPC I/O Parent] WARNING: Process 1149 may be hanging at shutdown; will wait for up to 38800ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:203
[task 2025-02-20T18:44:03.574Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.576Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.577Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.578Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.578Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.579Z] 18:44:03 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:44:03.580Z] 18:44:03 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:44:03.581Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.751Z] 18:44:03 INFO - GECKO(1057) | [Parent 1057, IPC I/O Parent] WARNING: Process 1287 may be hanging at shutdown; will wait for up to 38600ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_posix_sigchld.cc:203
[task 2025-02-20T18:44:03.788Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.788Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.788Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.788Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.788Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.789Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.849Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:03.849Z] 18:44:03 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:03.850Z] 18:44:03 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:03.851Z] 18:44:03 INFO - GECKO(1057) | 31 16288 nsComponentManagerImpl
[task 2025-02-20T18:44:03.852Z] 18:44:03 INFO - GECKO(1057) | 2 288 libfontconfig.so
[task 2025-02-20T18:44:03.853Z] 18:44:03 INFO - GECKO(1057) | 1 9496 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:44:03.854Z] 18:44:03 INFO - GECKO(1057) | 1 4104 style::bloom::BLOOM_KEY
[task 2025-02-20T18:44:03.855Z] 18:44:03 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:05.680Z] 18:44:05 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:05.681Z] 18:44:05 INFO - GECKO(1057) | Suppressions used:
[task 2025-02-20T18:44:05.681Z] 18:44:05 INFO - GECKO(1057) | count bytes template
[task 2025-02-20T18:44:05.681Z] 18:44:05 INFO - GECKO(1057) | 27 13328 nsComponentManagerImpl
[task 2025-02-20T18:44:05.682Z] 18:44:05 INFO - GECKO(1057) | 633 18115 libfontconfig.so
[task 2025-02-20T18:44:05.682Z] 18:44:05 INFO - GECKO(1057) | 3 28488 style::sharing::SHARING_CACHE_KEY
[task 2025-02-20T18:44:05.683Z] 18:44:05 INFO - GECKO(1057) | 3 12312 style::bloom::BLOOM_KEY
[task 2025-02-20T18:44:05.683Z] 18:44:05 INFO - GECKO(1057) | 4 36 libglib-2.0.so
[task 2025-02-20T18:44:05.684Z] 18:44:05 INFO - GECKO(1057) | -----------------------------------------------------
[task 2025-02-20T18:44:05.774Z] 18:44:05 INFO - TEST-INFO | Main app process: exit 0
[task 2025-02-20T18:44:05.774Z] 18:44:05 INFO - TEST-UNEXPECTED-FAIL | browser/components/shopping/tests/browser/browser_shoppingsidebar_telemetry.js | Application shut down (without crashing) in the middle of a test!
[task 2025-02-20T18:44:05.774Z] 18:44:05 INFO - TEST-INFO took 97951ms
[task 2025-02-20T18:44:05.775Z] 18:44:05 INFO - runtests.py | Application ran for: 0:03:13.039576
[task 2025-02-20T18:44:05.776Z] 18:44:05 INFO - zombiecheck | Reading PID log: /tmp/tmp6di0tssspidlog
[task 2025-02-20T18:44:05.776Z] 18:44:05 INFO - ==> process 1057 launched child process 1149
<...>
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - zombiecheck | Checking for orphan process with PID: 1149
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - Stopping web server
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - Server shut down.
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - Web server killed.
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - Stopping web socket server
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - Stopping ssltunnel
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - Stopping gst for v4l2loopback
[task 2025-02-20T18:44:05.801Z] 18:44:05 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2025-02-20T18:44:05.801Z] 18:44:05 INFO - runtests.py | Running tests: end.
[task 2025-02-20T18:44:05.842Z] 18:44:05 INFO - Buffered messages finished
[task 2025-02-20T18:44:05.844Z] 18:44:05 INFO - Running manifest: dom/serviceworkers/test/browser-dFPI.toml:dom/serviceworkers/test/browser-common.toml
[task 2025-02-20T18:44:05.844Z] 18:44:05 INFO - The following extra prefs will be set:
[task 2025-02-20T18:44:05.845Z] 18:44:05 INFO - network.cookie.cookieBehavior=5
[task 2025-02-20T18:44:05.901Z] 18:44:05 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2025-02-20T18:44:05.904Z] 18:44:05 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2025-02-20T18:44:05.912Z] 18:44:05 INFO - Setting pipeline to PAUSED ...
[task 2025-02-20T18:44:05.913Z] 18:44:05 INFO - Pipeline is PREROLLING ...
[task 2025-02-20T18:44:05.914Z] 18:44:05 INFO - Pipeline is PREROLLED ...
[task 2025-02-20T18:44:05.915Z] 18:44:05 INFO - Setting pipeline to PLAYING ...
[task 2025-02-20T18:44:05.915Z] 18:44:05 INFO - New clock: GstSystemClock
[task 2025-02-20T18:44:05.948Z] 18:44:05 INFO - Got EOS from element "pipeline0".
[task 2025-02-20T18:44:05.949Z] 18:44:05 INFO - Execution ended after 0:00:00.033558910
[task 2025-02-20T18:44:05.949Z] 18:44:05 INFO - Setting pipeline to PAUSED ...
[task 2025-02-20T18:44:05.949Z] 18:44:05 INFO - Setting pipeline to READY ...
[task 2025-02-20T18:44:05.950Z] 18:44:05 INFO - (gst-launch-1.0:2493): GStreamer-CRITICAL **: 18:44:05.948: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2025-02-20T18:44:05.950Z] 18:44:05 INFO - Setting pipeline to NULL ...
[task 2025-02-20T18:44:05.951Z] 18:44:05 INFO - Freeing pipeline ...
[task 2025-02-20T18:44:06.731Z] 18:44:06 INFO - PID 2510 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2025-02-20T18:44:06.731Z] 18:44:06 INFO -
[task 2025-02-20T18:44:06.743Z] 18:44:06 INFO - Increasing default timeout to 90 seconds (asan or debug)
[task 2025-02-20T18:44:06.744Z] 18:44:06 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2025-02-20T18:44:06.747Z] 18:44:06 INFO - LSan enabled.
[task 2025-02-20T18:44:06.748Z] 18:44:06 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2025-02-20T18:44:06.748Z] 18:44:06 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2025-02-20T18:44:06.751Z] 18:44:06 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2025-02-20T18:44:06.754Z] 18:44:06 INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-e', "const _PROFILE_PATH = '/tmp/tmpzeh7531n.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-20T18:44:06.754Z] 18:44:06 INFO - runtests.py | Server pid: 2516
[task 2025-02-20T18:44:06.755Z] 18:44:06 INFO - runtests.py | Websocket server pid: 2517
[task 2025-02-20T18:44:06.756Z] 18:44:06 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2025-02-20T18:44:06.760Z] 18:44:06 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2025-02-20T18:44:06.761Z] 18:44:06 INFO - runtests.py | SSL tunnel pid: 2520
[task 2025-02-20T18:44:06.962Z] 18:44:06 INFO - use http3 server: 0
[task 2025-02-20T18:44:06.963Z] 18:44:06 INFO - runtests.py | Running with scheme: http
[task 2025-02-20T18:44:06.964Z] 18:44:06 INFO - runtests.py | Running with e10s: True
[task 2025-02-20T18:44:06.964Z] 18:44:06 INFO - runtests.py | Running with fission: True
[task 2025-02-20T18:44:06.965Z] 18:44:06 INFO - runtests.py | Running with cross-origin iframes: False
[task 2025-02-20T18:44:06.965Z] 18:44:06 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2025-02-20T18:44:06.965Z] 18:44:06 INFO - runtests.py | Running tests: start.
[task 2025-02-20T18:44:06.965Z] 18:44:06 INFO -
[task 2025-02-20T18:44:06.973Z] 18:44:06 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpzeh7531n.mozrunner
[task 2025-02-20T18:44:06.977Z] 18:44:06 INFO - runtests.py | Application pid: 2549
[task 2025-02-20T18:44:06.977Z] 18:44:06 INFO - TEST-INFO | started process GECKO(2549)
[task 2025-02-20T18:44:08.174Z] 18:44:08 INFO - GECKO(2549) | 1740077048173 Marionette INFO Marionette enabled
[task 2025-02-20T18:44:08.178Z] 18:44:08 INFO - GECKO(2549) | 1740077048177 Marionette TRACE Received observer notification final-ui-startup
[task 2025-02-20T18:44:08.339Z] 18:44:08 INFO - GECKO(2549) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2025-02-20T18:44:08.402Z] 18:44:08 INFO - GECKO(2549) | 1740077048401 Marionette INFO Listening on port 2828
[task 2025-02-20T18:44:08.407Z] 18:44:08 INFO - GECKO(2549) | 1740077048406 Marionette DEBUG Marionette is listening
[task 2025-02-20T18:44:08.445Z] 18:44:08 INFO - GECKO(2549) | 1740077048444 Marionette DEBUG Accepted connection 0 from 127.0.0.1:43070
[task 2025-02-20T18:44:08.502Z] 18:44:08 INFO - GECKO(2549) | 1740077048501 Marionette DEBUG Closed connection 0
[task 2025-02-20T18:44:08.503Z] 18:44:08 INFO - GECKO(2549) | 1740077048501 Marionette DEBUG Accepted connection 1 from 127.0.0.1:43072
[task 2025-02-20T18:44:08.936Z] 18:44:08 INFO - GECKO(2549) | 1740077048935 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2025-02-20T18:44:08.949Z] 18:44:08 INFO - GECKO(2549) | 1740077048948 Marionette DEBUG Waiting for initial application window
[task 2025-02-20T18:44:10.684Z] 18:44:10 INFO - GECKO(2549) | console.error: ({})
[task 2025-02-20T18:44:13.851Z] 18:44:13 INFO - GECKO(2549) | 1740077053850 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2025-02-20T18:44:13.888Z] 18:44:13 INFO - GECKO(2549) | 1740077053887 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2025-02-20T18:44:13.890Z] 18:44:13 INFO - GECKO(2549) | 1740077053889 RemoteAgent TRACE [9] ProgressListener Setting unload timer (40000ms)
[task 2025-02-20T18:44:13.894Z] 18:44:13 INFO - GECKO(2549) | 1740077053893 RemoteAgent TRACE [9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2025-02-20T18:44:13.896Z] 18:44:13 INFO - GECKO(2549) | 1740077053893 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2025-02-20T18:44:13.896Z] 18:44:13 INFO - GECKO(2549) | 1740077053893 RemoteAgent TRACE [9] ProgressListener Stop: has error=false url=about:blank
[task 2025-02-20T18:44:13.940Z] 18:44:13 INFO - GECKO(2549) | 1740077053937 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"4da17aa6-a030-4662-88f3-3b6f9b7e4db0","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"137.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0","moz:buildID":"20250220173848","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":2549,"moz:profile":"/tmp/tmpzeh7531n.mozrunner","moz:shutdownTimeout":300000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2025-02-20T18:44:13.970Z] 18:44:13 INFO - GECKO(2549) | 1740077053969 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmppx554x14.zip","temporary":false}]
[task 2025-02-20T18:44:14.216Z] 18:44:14 INFO - GECKO(2549) | 1740077054215 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2025-02-20T18:44:14.249Z] 18:44:14 INFO - GECKO(2549) | 1740077054247 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpw9kqyx6f.zip","temporary":false}]
[task 2025-02-20T18:44:14.307Z] 18:44:14 INFO - GECKO(2549) | 1740077054306 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2025-02-20T18:44:14.315Z] 18:44:14 INFO - GECKO(2549) | 1740077054314 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2025-02-20T18:44:14.316Z] 18:44:14 INFO - GECKO(2549) | 1740077054314 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2025-02-20T18:44:14.318Z] 18:44:14 INFO - GECKO(2549) | 1740077054317 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2025-02-20T18:44:14.319Z] 18:44:14 INFO - GECKO(2549) | 1740077054318 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2025-02-20T18:44:14.322Z] 18:44:14 INFO - GECKO(2549) | 1740077054320 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, v. 2.0. If a copy of the MPL was not distr ... s which flavor and url to load.\nlet ev = new CustomEvent(\"mochitest-load\", { detail: [flavor, url] });\nwin.dispatchEvent(ev);","args":[{"flavor":"browser-chrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":2171,"filename":"tests/mochitest/runtests.py"}]
[task 2025-02-20T18:44:14.336Z] 18:44:14 INFO - GECKO(2549) | 1740077054335 RemoteAgent TRACE WebDriverProcessData actor created for PID 2549
[task 2025-02-20T18:44:14.341Z] 18:44:14 INFO - GECKO(2549) | 1740077054339 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2025-02-20T18:44:14.411Z] 18:44:14 INFO - GECKO(2549) | 1740077054410 RemoteAgent TRACE Received observer notification domwindowopened
[task 2025-02-20T18:44:14.434Z] 18:44:14 INFO - GECKO(2549) | 1740077054433 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2025-02-20T18:44:14.450Z] 18:44:14 INFO - GECKO(2549) | 1740077054447 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2025-02-20T18:44:14.450Z] 18:44:14 INFO - GECKO(2549) | 1740077054448 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2025-02-20T18:44:14.503Z] 18:44:14 INFO - GECKO(2549) | 1740077054501 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2025-02-20T18:44:14.504Z] 18:44:14 INFO - GECKO(2549) | 1740077054503 Marionette TRACE [1] MarionetteCommands actor destroyed for window id 2
[task 2025-02-20T18:44:14.510Z] 18:44:14 INFO - GECKO(2549) | 1740077054509 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2025-02-20T18:44:14.545Z] 18:44:14 INFO - runtests.py | Waiting for browser...
[task 2025-02-20T18:44:14.554Z] 18:44:14 INFO - GECKO(2549) | 1740077054553 Marionette DEBUG Closed connection 1
[task 2025-02-20T18:44:14.832Z] 18:44:14 INFO - *** Start BrowserChrome Test Results ***
[task 2025-02-20T18:44:14.901Z] 18:44:14 INFO - checking window state
[task 2025-02-20T18:44:15.019Z] 18:44:15 INFO - TEST-START | dom/serviceworkers/test/browser_antitracking.js
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 days ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 3 days ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•