Closed
Bug 2000503
Opened 7 months ago
Closed 7 months ago
Intermittent telemetry/marionette/tests/client/test_fog_usage_reporting.py TestUsageReporting.test_usage_deletion_request | AssertionError: Error waiting for ping: Timed out after 60.0 seconds | single tracking bug
Categories
(Toolkit :: Telemetry, defect, P5)
Toolkit
Telemetry
Tracking
()
RESOLVED
DUPLICATE
of bug 2000502
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=536009271&repo=autoland&task=VFq1RbU-T4u5jDSECOnhKA.0
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VFq1RbU-T4u5jDSECOnhKA/runs/0/artifacts/public/logs/live_backing.log
[task 2025-11-17T06:33:29.448+00:00] 06:33:29 INFO - TEST-START | telemetry/marionette/tests/client/test_fog_usage_reporting.py TestUsageReporting.test_usage_deletion_request
[task 2025-11-17T06:33:29.450+00:00] 06:33:29 INFO - Application command: /opt/worker/tasks/task_176335640097646/build/application/Firefox Nightly.app/Contents/MacOS/firefox -marionette -remote-allow-system-access -foreground -profile /opt/worker/tasks/task_176335640097646/build/tmp4y10dulj.mozrunner
[task 2025-11-17T06:33:29.925+00:00] 06:33:29 INFO - 1763361209925 Toolkit.Telemetry TRACE TelemetryController::observe - profile-after-change notified.
[task 2025-11-17T06:33:29.926+00:00] 06:33:29 INFO - 1763361209925 Toolkit.Telemetry TRACE TelemetryController::setupTelemetry
[task 2025-11-17T06:33:29.927+00:00] 06:33:29 INFO - 1763361209927 Toolkit.Telemetry TRACE TelemetryReportingPolicy::setup
[task 2025-11-17T06:33:29.927+00:00] 06:33:29 INFO - 1763361209927 Toolkit.Telemetry INFO TelemetryReportingPolicy::get termsOfUseAcceptedDate - No date stored yet.
[task 2025-11-17T06:33:29.927+00:00] 06:33:29 INFO - 1763361209927 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2025-11-17T06:33:29.927+00:00] 06:33:29 INFO - 1763361209927 Toolkit.Telemetry TRACE TelemetrySession::earlyInit
[task 2025-11-17T06:33:29.930+00:00] 06:33:29 INFO - 1763361209930 Toolkit.Telemetry INFO TelemetryReportingPolicy::get termsOfUseAcceptedDate - No date stored yet.
[task 2025-11-17T06:33:29.930+00:00] 06:33:29 INFO - 1763361209930 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2025-11-17T06:33:29.931+00:00] 06:33:29 INFO - 1763361209930 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_shouldNotifyDataReportingPolicy - User already notified or bypassing the policy.
[task 2025-11-17T06:33:29.945+00:00] 06:33:29 INFO - 1763361209944 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: using default preonboarding message
[task 2025-11-17T06:33:29.945+00:00] 06:33:29 INFO - 1763361209945 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: setting currentPolicyVersion from Nimbus feature (4)
[task 2025-11-17T06:33:29.945+00:00] 06:33:29 INFO - 1763361209945 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: setting minimumPolicyVersion from Nimbus feature (4)
[task 2025-11-17T06:33:29.946+00:00] 06:33:29 INFO - 1763361209945 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: setting firstRunURL from Nimbus feature ('https://www.mozilla.org/privacy/firefox/')
[task 2025-11-17T06:33:29.946+00:00] 06:33:29 INFO - 1763361209945 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2025-11-17T06:33:29.946+00:00] 06:33:29 INFO - 1763361209945 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_shouldShowTOU - User bypassing the policy.
[task 2025-11-17T06:33:29.946+00:00] 06:33:29 INFO - 1763361209945 Toolkit.Telemetry TRACE UpdatePing::init - enabled: true
[task 2025-11-17T06:33:29.949+00:00] 06:33:29 INFO - 1763361209949 Toolkit.Telemetry TRACE TelemetryEnvironment::constructor
[task 2025-11-17T06:33:29.957+00:00] 06:33:29 INFO - 1763361209957 Toolkit.Telemetry TRACE TelemetryEnvironment::_getGFXData - Only one display adapter detected.
[task 2025-11-17T06:33:29.959+00:00] 06:33:29 INFO - 1763361209959 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateSearchEngine - ignoring early call
[task 2025-11-17T06:33:29.961+00:00] 06:33:29 INFO - 1763361209960 Toolkit.Telemetry TRACE TelemetryEnvironment::registerChangeListener for CrashAnnotator
[task 2025-11-17T06:33:29.967+00:00] 06:33:29 INFO - 1763361209966 Marionette INFO Marionette enabled
[task 2025-11-17T06:33:30.055+00:00] 06:33:30 INFO - 1763361210055 Marionette TRACE Received observer notification final-ui-startup
[task 2025-11-17T06:33:30.061+00:00] 06:33:30 INFO - 1763361210061 Toolkit.Telemetry INFO UsageReporting::adoptDataReportingPreference: setting usage reporting preference to true
[task 2025-11-17T06:33:30.071+00:00] 06:33:30 INFO - 2025-11-17 06:33:30.070 Firefox Nightly GPU Helper[4835:49628] Failure on line 686 in function id scheduleApplicationNotification(LSNotificationCode, NSWorkspaceNotificationCenter *): noErr == _LSModifyNotification(notificationID, 1, &code, 0, NULL, NULL, NULL)
[task 2025-11-17T06:33:30.143+00:00] 06:33:30 INFO - 2025-11-17 06:33:30.143 Firefox Nightly GPU Helper[4835:49628] Error received in message reply handler: Connection invalid
[task 2025-11-17T06:33:30.143+00:00] 06:33:30 INFO - 2025-11-17 06:33:30.143 Firefox Nightly GPU Helper[4835:49646] Connection Invalid error for service com.apple.hiservices-xpcservice.
[task 2025-11-17T06:33:30.151+00:00] 06:33:30 INFO - 1763361210151 Marionette INFO Listening on port 2828
[task 2025-11-17T06:33:30.152+00:00] 06:33:30 INFO - 1763361210152 Marionette DEBUG Marionette is listening
[task 2025-11-17T06:33:30.299+00:00] 06:33:30 INFO - 1763361210298 Marionette DEBUG Accepted connection 0 from 127.0.0.1:49551
[task 2025-11-17T06:33:30.319+00:00] 06:33:30 INFO - 1763361210318 Marionette DEBUG Closed connection 0
[task 2025-11-17T06:33:30.319+00:00] 06:33:30 INFO - 1763361210318 Marionette DEBUG Accepted connection 1 from 127.0.0.1:49552
[task 2025-11-17T06:33:30.406+00:00] 06:33:30 INFO - 1763361210405 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2025-11-17T06:33:30.414+00:00] 06:33:30 INFO - 1763361210414 Marionette DEBUG Waiting for initial application window
[task 2025-11-17T06:33:30.874+00:00] 06:33:30 INFO - 1763361210873 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2025-11-17T06:33:30.874+00:00] 06:33:30 INFO - 1763361210873 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2025-11-17T06:33:31.017+00:00] 06:33:31 INFO - 1763361211017 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2025-11-17T06:33:31.017+00:00] 06:33:31 INFO - 1763361211017 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2025-11-17T06:33:31.019+00:00] 06:33:31 INFO - 1763361211018 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: compositor:created, aData: null
[task 2025-11-17T06:33:31.066+00:00] 06:33:31 INFO - 1763361211065 Toolkit.Telemetry TRACE TelemetrySession::observe - xul-window-visible notified.
[task 2025-11-17T06:33:31.410+00:00] 06:33:31 INFO - 1763361211410 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: sessionstore-windows-restored, aData: null
[task 2025-11-17T06:33:31.411+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetrySession::observe - sessionstore-windows-restored notified.
[task 2025-11-17T06:33:31.411+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetrySession::gatherStartup
[task 2025-11-17T06:33:31.412+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetryReportingPolicy::updateTOUPrefsForLegacyUsers - did not accept TOU during initial experimentation phase, no action required.
[task 2025-11-17T06:33:31.412+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: using default preonboarding message
[task 2025-11-17T06:33:31.412+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: setting currentPolicyVersion from Nimbus feature (4)
[task 2025-11-17T06:33:31.412+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: setting minimumPolicyVersion from Nimbus feature (4)
[task 2025-11-17T06:33:31.413+00:00] 06:33:31 INFO - 1763361211411 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_configureFromNimbus: setting firstRunURL from Nimbus feature ('https://www.mozilla.org/privacy/firefox/')
[task 2025-11-17T06:33:31.413+00:00] 06:33:31 INFO - 1763361211412 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2025-11-17T06:33:31.413+00:00] 06:33:31 INFO - 1763361211412 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_shouldShowTOU - User bypassing the policy.
[task 2025-11-17T06:33:31.413+00:00] 06:33:31 INFO - 1763361211412 Toolkit.Telemetry INFO TelemetryReportingPolicy::get termsOfUseAcceptedDate - No date stored yet.
[task 2025-11-17T06:33:31.413+00:00] 06:33:31 INFO - 1763361211412 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2025-11-17T06:33:31.414+00:00] 06:33:31 INFO - 1763361211412 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_shouldNotifyDataReportingPolicy - User already notified or bypassing the policy.
[task 2025-11-17T06:33:31.414+00:00] 06:33:31 INFO - 1763361211412 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_delayedSetup: neither TOU or legacy data reporting policy will show, no further action required
[task 2025-11-17T06:33:31.424+00:00] 06:33:31 INFO - 1763361211424 Toolkit.Telemetry TRACE TelemetryEnvironment::_startWatchingPrefs - [object Map]
[task 2025-11-17T06:33:31.435+00:00] 06:33:31 INFO - 1763361211435 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: toolkit.profiles.storeID
[task 2025-11-17T06:33:31.445+00:00] 06:33:31 INFO - 1763361211444 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.default.sites
[task 2025-11-17T06:33:31.445+00:00] 06:33:31 INFO - 1763361211444 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.topsites
[task 2025-11-17T06:33:31.445+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.hideTopSitesTitle
[task 2025-11-17T06:33:31.446+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.showSponsored
[task 2025-11-17T06:33:31.446+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.system.showSponsored
[task 2025-11-17T06:33:31.446+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.unifiedAds.blockedAds
[task 2025-11-17T06:33:31.447+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.system.showWeather
[task 2025-11-17T06:33:31.447+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.system.showWeatherOptIn
[task 2025-11-17T06:33:31.447+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.optIn-region-weather-config
[task 2025-11-17T06:33:31.448+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.weather.optInDisplayed
[task 2025-11-17T06:33:31.448+00:00] 06:33:31 INFO - 1763361211445 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.weather.optInAccepted
[task 2025-11-17T06:33:31.448+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.weather.staticData.enabled
[task 2025-11-17T06:33:31.449+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.weather.temperatureUnits
[task 2025-11-17T06:33:31.449+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.weather.placement
[task 2025-11-17T06:33:31.449+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.pocketCta
[task 2025-11-17T06:33:31.449+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.showSearch
[task 2025-11-17T06:33:31.450+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.topSitesRows
[task 2025-11-17T06:33:31.450+00:00] 06:33:31 INFO - 1763361211446 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.telemetry
[task 2025-11-17T06:33:31.450+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.telemetry.ut.events
[task 2025-11-17T06:33:31.450+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.telemetry.structuredIngestion.endpoint
[task 2025-11-17T06:33:31.451+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includeVisited
[task 2025-11-17T06:33:31.451+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includeBookmarks
[task 2025-11-17T06:33:31.451+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includeDownloads
[task 2025-11-17T06:33:31.451+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.rows
[task 2025-11-17T06:33:31.452+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.topstories.rows
[task 2025-11-17T06:33:31.452+00:00] 06:33:31 INFO - 1763361211447 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.sectionOrder
[task 2025-11-17T06:33:31.452+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.newtabWallpapers.customWallpaper.uploadedPreviously
[task 2025-11-17T06:33:31.453+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.newtabAdSize.leaderboard
[task 2025-11-17T06:33:31.453+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.newtabAdSize.leaderboard.position
[task 2025-11-17T06:33:31.453+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.newtabAdSize.billboard
[task 2025-11-17T06:33:31.453+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.newtabAdSize.billboard.position
[task 2025-11-17T06:33:31.454+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.newtabAdSize.mediumRectangle
[task 2025-11-17T06:33:31.454+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.promoCard.enabled
[task 2025-11-17T06:33:31.454+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.enabled
[task 2025-11-17T06:33:31.455+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.personalization.enabled
[task 2025-11-17T06:33:31.455+00:00] 06:33:31 INFO - 1763361211448 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.customizeMenuPanel.enabled
[task 2025-11-17T06:33:31.455+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.contextualAds.enabled
[task 2025-11-17T06:33:31.455+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.personalization.inferred.enabled
[task 2025-11-17T06:33:31.456+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.dailyBrief.enabled
[task 2025-11-17T06:33:31.456+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.dailyBrief.sectionId
[task 2025-11-17T06:33:31.456+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.shortcuts.personalization.enabled
[task 2025-11-17T06:33:31.456+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.shortcuts.force_log.enabled
[task 2025-11-17T06:33:31.456+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.attribution.enabled
[task 2025-11-17T06:33:31.457+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.cards.thumbsUpDown.enabled
[task 2025-11-17T06:33:31.457+00:00] 06:33:31 INFO - 1763361211449 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.sections.following
<...>
[task 2025-11-17T06:33:35.860+00:00] 06:33:35 INFO - [Parent 4844: DOM Worker]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.860+00:00] 06:33:35 INFO - [Parent 4844: DOM Worker]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.861+00:00] 06:33:35 INFO - [Parent 4844: DOM Worker,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.861+00:00] 06:33:35 INFO - [Parent 4844: DOM Worker,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.861+00:00] 06:33:35 INFO - [Parent 4844: DOM Worker,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.861+00:00] 06:33:35 INFO - [Parent 4844: DOM Worker,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.861+00:00] 06:33:35 INFO - [Parent 4844: ImageBridgeChld,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.861+00:00] 06:33:35 INFO - [Parent 4844: TRR Background,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.862+00:00] 06:33:35 INFO - [Parent 4844: GMPThread,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.862+00:00] 06:33:35 INFO - [Parent 4844: IPC Launch,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.862+00:00] 06:33:35 INFO - [Parent 4844: IPC Launch,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.862+00:00] 06:33:35 INFO - [Parent 4844: ImageIO,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.862+00:00] 06:33:35 INFO - [Parent 4844: HTML5 Parser,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.863+00:00] 06:33:35 INFO - [Parent 4844: IPDL Background,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.863+00:00] 06:33:35 INFO - [Parent 4844: IPDL Background,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.863+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #12,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.863+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #9,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.863+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #9,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.864+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #3,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.864+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #11,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.864+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #2,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.864+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #2,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.864+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #11,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.865+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #12,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.865+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #1,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.865+00:00] 06:33:35 INFO - [Parent 4844: StreamTrans #1,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.866+00:00] 06:33:35 INFO - [Parent 4844: Timer]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.866+00:00] 06:33:35 INFO - [Parent 4844: Timer,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.866+00:00] 06:33:35 INFO - [Parent 4844: BackgroundThreadPool #1,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.867+00:00] 06:33:35 INFO - [Parent 4844: BackgroundThreadPool #1,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.867+00:00] 06:33:35 INFO - [Parent 4844: BgIOThreadPool #2,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.867+00:00] 06:33:35 INFO - [Parent 4844: BgIOThreadPool #2,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.868+00:00] 06:33:35 INFO - [Parent 4844: BgIOThreadPool #3,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.868+00:00] 06:33:35 INFO - [Parent 4844: BgIOThreadPool #1,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.868+00:00] 06:33:35 INFO - [Parent 4844: BgIOThreadPool #1,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.868+00:00] 06:33:35 INFO - [Parent 4844: BHMgr Monitor,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.869+00:00] 06:33:35 INFO - [Parent 4844: Compositor,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.869+00:00] 06:33:35 INFO - [Parent 4844: Permission,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.869+00:00] 06:33:35 INFO - [Parent 4844: Permission,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.870+00:00] 06:33:35 INFO - [Parent 4844: osclientcerts,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.870+00:00] 06:33:35 INFO - [Parent 4844: BHMgr Processor,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:35.870+00:00] 06:33:35 INFO - [Parent 4844: Compositor,SHDRCV]: I/glean_core::* [glean_core::dispatcher::global] Failed to launch a task on the queue. Discarding task.
[task 2025-11-17T06:33:37.807+00:00] 06:33:37 INFO - pingserver pings_handler received 'main' ping with reason 'shutdown'
[task 2025-11-17T06:33:37.947+00:00] 06:33:37 INFO - fog_ping_server pings_handler received 'events' ping with reason 'startup', seq 0
[task 2025-11-17T06:33:37.980+00:00] 06:33:37 INFO - fog_ping_server pings_handler received 'messaging-system' ping with reason 'None', seq 1
[task 2025-11-17T06:33:37.987+00:00] 06:33:37 INFO - fog_ping_server pings_handler received 'baseline' ping with reason 'active', seq 0
[task 2025-11-17T06:33:37.991+00:00] 06:33:37 INFO - Child process with id "4844" has been marked as detached because it is no longer in the managed process group. Keeping reference to the process id "4860" which is the new child process.
[task 2025-11-17T06:33:37.992+00:00] 06:33:37 INFO - fog_ping_server pings_handler received 'usage-reporting' ping
[task 2025-11-17T06:33:37.994+00:00] 06:33:37 INFO - fog_ping_server pings_handler received 'temp-fog-initial-state' ping with reason 'startup', seq 0
[task 2025-11-17T06:33:37.998+00:00] 06:33:37 INFO - fog_ping_server pings_handler received 'health' ping with reason 'pre_init', seq 0
[task 2025-11-17T06:33:38.005+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'health' ping with reason 'post_init', seq 1
[task 2025-11-17T06:33:38.050+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'nimbus-targeting-context' ping with reason 'None', seq 1
[task 2025-11-17T06:33:38.072+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'messaging-system' ping with reason 'None', seq 2
[task 2025-11-17T06:33:38.083+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'messaging-system' ping with reason 'None', seq 3
[task 2025-11-17T06:33:38.086+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'baseline' ping with reason 'active', seq 1
[task 2025-11-17T06:33:38.088+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'usage-reporting' ping
[task 2025-11-17T06:33:38.090+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'temp-fog-initial-state' ping with reason 'startup', seq 1
[task 2025-11-17T06:33:38.093+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'health' ping with reason 'pre_init', seq 2
[task 2025-11-17T06:33:38.094+00:00] 06:33:38 INFO - fog_ping_server pings_handler received 'health' ping with reason 'post_init', seq 3
[task 2025-11-17T06:33:38.095+00:00] 06:33:38 INFO - wait_for_pings running action 'disable_usage_reporting'.
[task 2025-11-17T06:33:56.559+00:00] 06:33:56 INFO - pingserver pings_handler received 'main' ping with reason 'environment-change'
[task 2025-11-17T06:34:37.945+00:00] 06:34:37 INFO - fog_ping_server pings_handler received 'usage-deletion-request' ping
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - TEST-UNEXPECTED-FAIL | telemetry/marionette/tests/client/test_fog_usage_reporting.py TestUsageReporting.test_usage_deletion_request | AssertionError: Error waiting for ping: Timed out after 60.0 seconds
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - Traceback (most recent call last):
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - File "/opt/worker/tasks/task_176335640097646/build/venv/lib/python3.11/site-packages/marionette_harness/marionette_test/testcases.py", line 193, in run
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - testMethod()
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - File "/opt/worker/tasks/task_176335640097646/build/tests/telemetry/marionette/tests/client/test_fog_usage_reporting.py", line 132, in test_usage_deletion_request
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - ping2 = self.wait_for_ping(
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - ^^^^^^^^^^^^^^^^^^^
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - File "/opt/worker/tasks/task_176335640097646/build/venv/lib/python3.11/site-packages/telemetry_harness/testcase.py", line 137, in wait_for_ping
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - [ping] = self.wait_for_pings(
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - ^^^^^^^^^^^^^^^^^^^^
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - File "/opt/worker/tasks/task_176335640097646/build/venv/lib/python3.11/site-packages/telemetry_harness/testcase.py", line 129, in wait_for_pings
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - self.fail(f"Error waiting for ping: {e}")
[task 2025-11-17T06:34:38.263+00:00] 06:34:38 INFO - TEST-INFO took 68775ms
[task 2025-11-17T06:34:38.287+00:00] 06:34:38 INFO - TEST-START | telemetry/marionette/tests/client/test_fog_usage_reporting.py TestUsageReporting.test_usage_reporting_independent_from_telemetry
|
||
Updated•7 months ago
|
You need to log in
before you can comment on or make changes to this bug.
Description
•