Closed
Bug 1784613
Opened 3 years ago
Closed 3 years ago
Intermittent JavaScript error: chrome://remote/content/marionette/driver.js, line 2270: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIObserverService.removeObserver]
Categories
(Core :: JavaScript Engine, defect, P5)
Core
JavaScript Engine
Tracking
()
RESOLVED
DUPLICATE
of bug 1784625
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, Whiteboard: [retriggered])
Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=387234918&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/asFalAuOS12Jh2Vk3w0zxQ/runs/0/artifacts/public/logs/live_backing.log
[task 2022-08-13T11:15:14.356Z] 11:15:14 INFO - Profile path is Z:\task_166038576644937\build\tmp2q2qm7yl.mozrunner
[task 2022-08-13T11:15:14.356Z] 11:15:14 INFO - Starting fixture servers
[task 2022-08-13T11:15:14.357Z] 11:15:14 INFO - 1660389314356 Marionette DEBUG Closed connection 1
[task 2022-08-13T11:15:14.357Z] 11:15:14 INFO - 1660389314357 Marionette DEBUG Accepted connection 2 from 127.0.0.1:49936
[task 2022-08-13T11:15:14.452Z] 11:15:14 INFO - 1660389314456 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2022-08-13T11:15:14.458Z] 11:15:14 INFO - 1660389314457 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2022-08-13T11:15:14.505Z] 11:15:14 INFO - 1660389314509 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: compositor:created, aData: null
[task 2022-08-13T11:15:14.524Z] 11:15:14 INFO - 1660389314525 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-08-13T11:15:14.528Z] 11:15:14 INFO - 1660389314527 Marionette DEBUG Waiting for initial application window
[task 2022-08-13T11:15:14.555Z] 11:15:14 INFO - 1660389314562 Toolkit.Telemetry TRACE TelemetrySession::observe - user-interaction-active notified.
[task 2022-08-13T11:15:14.590Z] 11:15:14 INFO - 1660389314603 Toolkit.Telemetry TRACE TelemetrySession::observe - xul-window-visible notified.
[task 2022-08-13T11:15:14.646Z] 11:15:14 INFO - 1660389314652 Toolkit.Telemetry TRACE ClientID::_doLoadClientID
[task 2022-08-13T11:15:14.655Z] 11:15:14 INFO - 1660389314654 Toolkit.Telemetry TRACE ClientID::_saveClientID
[task 2022-08-13T11:15:14.656Z] 11:15:14 INFO - 1660389314656 Toolkit.Telemetry TRACE ClientID::_doLoadClientID: New client ID loaded and persisted.
[task 2022-08-13T11:15:14.657Z] 11:15:14 INFO - 1660389314656 Toolkit.Telemetry TRACE TelemetrySend::setup
[task 2022-08-13T11:15:14.657Z] 11:15:14 INFO - 1660389314656 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2022-08-13T11:15:14.667Z] 11:15:14 INFO - 1660389314667 Toolkit.Telemetry TRACE TelemetryStorage::_scanPendingPings
[task 2022-08-13T11:15:14.668Z] 11:15:14 INFO - 1660389314667 Toolkit.Telemetry TRACE TelemetryStorage::_migrateAppDataPings
[task 2022-08-13T11:15:14.668Z] 11:15:14 INFO - 1660389314667 Toolkit.Telemetry TRACE TelemetryStorage::_iterateAppDataPings
[task 2022-08-13T11:15:14.741Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry INFO TelemetrySend::_checkPendingPings - pending ping count: 0
[task 2022-08-13T11:15:14.755Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySend::_checkPendingPings - no pending pings
[task 2022-08-13T11:15:14.756Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetryStorage::_enforcePendingPingsQuota
[task 2022-08-13T11:15:14.756Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySend::Scheduler::triggerSendingPings - active send task: false, immediately: true
[task 2022-08-13T11:15:14.757Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySend::Scheduler::_doSendTask iteration
[task 2022-08-13T11:15:14.757Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySend::Scheduler::_doSendTask - pending: 0, current: 0
[task 2022-08-13T11:15:14.757Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySend::Scheduler::_doSendTask - can send - pending: 0, current: 0
[task 2022-08-13T11:15:14.758Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySend::Scheduler::_doSendTask - no pending pings, bailing out
[task 2022-08-13T11:15:14.758Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetrySession::delayedInit
[task 2022-08-13T11:15:14.758Z] 11:15:14 INFO - 1660389314754 Toolkit.Telemetry TRACE TelemetryStorage::_popAndPerformQueuedOperation - Performing queued operation.
[task 2022-08-13T11:15:14.759Z] 11:15:14 INFO - 1660389314755 Toolkit.Telemetry INFO TelemetryStorage::_loadSessionData - can not load session data file: {"operation":"open","path":"Z:\\task_166038576644937\\build\\tmp2q2qm7yl.mozrunner\\datareporting\\session-state.json","winLastError":2}
[task 2022-08-13T11:15:14.759Z] 11:15:14 INFO - 1660389314755 Toolkit.Telemetry TRACE TelemetryStorage::_popAndPerformQueuedOperation - Performing queued operation.
[task 2022-08-13T11:15:14.786Z] 11:15:14 INFO - 1660389314794 Toolkit.Telemetry TRACE TelemetryStorage::loadPingfile - unreadable ping Z:\task_166038576644937\build\tmp2q2qm7yl.mozrunner\datareporting\aborted-session-ping: {"operation":"open","path":"Z:\\task_166038576644937\\build\\tmp2q2qm7yl.mozrunner\\datareporting\\aborted-session-ping","winLastError":2}
[task 2022-08-13T11:15:14.795Z] 11:15:14 INFO - 1660389314794 Toolkit.Telemetry TRACE TelemetryStorage::loadAbortedSessionPing - no such file
[task 2022-08-13T11:15:14.796Z] 11:15:14 INFO - 1660389314794 Toolkit.Telemetry TRACE TelemetryController::checkAbortedSessionPing - found aborted-session ping: false
[task 2022-08-13T11:15:14.796Z] 11:15:14 INFO - 1660389314794 Toolkit.Telemetry TRACE TelemetrySession::_saveAbortedSessionPing
[task 2022-08-13T11:15:14.796Z] 11:15:14 INFO - 1660389314794 Toolkit.Telemetry TRACE TelemetrySession::getSessionPayload - reason: aborted-session, clearSubsession: false
[task 2022-08-13T11:15:14.797Z] 11:15:14 INFO - 1660389314795 Toolkit.Telemetry TRACE TelemetrySession::assemblePayloadWithMeasurements - reason: aborted-session, submitting subsession data: true
[task 2022-08-13T11:15:14.797Z] 11:15:14 INFO - 1660389314797 Toolkit.Telemetry TRACE TelemetryController::saveAbortedSessionPing
[task 2022-08-13T11:15:14.797Z] 11:15:14 INFO - 1660389314797 Toolkit.Telemetry TRACE TelemetryController::assemblePing - Type main, aOptions {"addClientId":true,"addEnvironment":true}
[task 2022-08-13T11:15:14.798Z] 11:15:14 INFO - 1660389314798 Toolkit.Telemetry TRACE TelemetryStorage::saveAbortedSessionPing - ping path: Z:\task_166038576644937\build\tmp2q2qm7yl.mozrunner\datareporting\aborted-session-ping
[task 2022-08-13T11:15:14.799Z] 11:15:14 INFO - 1660389314799 Toolkit.Telemetry TRACE TelemetryStorage::_popAndPerformQueuedOperation - Performing queued operation.
[task 2022-08-13T11:15:14.799Z] 11:15:14 INFO - 1660389314799 Toolkit.Telemetry TRACE TelemetryStorage::savePingToFile - path: Z:\task_166038576644937\build\tmp2q2qm7yl.mozrunner\datareporting\aborted-session-ping
[task 2022-08-13T11:15:14.811Z] 11:15:14 INFO - 1660389314822 Toolkit.Telemetry TRACE TelemetryEnvironment::registerChangeListener for TelemetrySession::onEnvironmentChange
[task 2022-08-13T11:15:14.823Z] 11:15:14 INFO - 1660389314823 Toolkit.Telemetry TRACE TelemetryScheduler::init
[task 2022-08-13T11:15:14.824Z] 11:15:14 INFO - 1660389314823 Toolkit.Telemetry TRACE TelemetryScheduler::_rescheduleTimeout - isUserIdle: false
[task 2022-08-13T11:15:14.824Z] 11:15:14 INFO - 1660389314824 Toolkit.Telemetry TRACE TelemetryScheduler::_rescheduleTimeout - scheduling next tick for Sat Aug 13 2022 11:20:14 GMT+0000 (Greenwich Mean Time)
[task 2022-08-13T11:15:14.825Z] 11:15:14 INFO - 1660389314824 Toolkit.Telemetry TRACE TelemetryController::scheduleNewProfilePing
[task 2022-08-13T11:15:14.825Z] 11:15:14 INFO - 1660389314824 Toolkit.Telemetry TRACE TelemetryStorage::cleanArchiveTask
[task 2022-08-13T11:15:14.825Z] 11:15:14 INFO - 1660389314824 Toolkit.Telemetry TRACE TelemetryStorage::removeFHRDatabase
[task 2022-08-13T11:15:14.828Z] 11:15:14 INFO - 1660389314827 Toolkit.Telemetry TRACE TelemetryEventPing::::Starting up.
[task 2022-08-13T11:15:14.828Z] 11:15:14 INFO - 1660389314827 Toolkit.Telemetry TRACE TelemetryPrioPing::Starting up.
[task 2022-08-13T11:15:14.828Z] 11:15:14 INFO - 1660389314827 Toolkit.Telemetry TRACE TelemetryController::saveUninstallPing
[task 2022-08-13T11:15:14.829Z] 11:15:14 INFO - 1660389314828 Toolkit.Telemetry INFO TelemetryController::saveUninstallPing - otherInstalls: 0
[task 2022-08-13T11:15:14.829Z] 11:15:14 INFO - 1660389314828 Toolkit.Telemetry TRACE TelemetryController::assemblePing - Type uninstall, aOptions {"addClientId":true,"addEnvironment":true}
[task 2022-08-13T11:15:14.833Z] 11:15:14 INFO - 1660389314832 Toolkit.Telemetry TRACE TelemetryStorage::savePingToFile - path: C:\ProgramData\Mozilla-1de4eec8-1241-4177-a864-e594e8d1fb38\uninstall_ping_7ABDA0CBFEFDDCF2_33b033e2-6c42-475e-8dd8-78083f232dae.json
[task 2022-08-13T11:15:15.369Z] 11:15:15 INFO - 1660389315373 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2022-08-13T11:15:15.375Z] 11:15:15 INFO - 1660389315374 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2022-08-13T11:15:15.377Z] 11:15:15 INFO - 1660389315377 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2022-08-13T11:15:15.377Z] 11:15:15 INFO - 1660389315377 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2022-08-13T11:15:15.398Z] 11:15:15 INFO - 1660389315398 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2022-08-13T11:15:15.400Z] 11:15:15 INFO - 1660389315399 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2022-08-13T11:15:15.521Z] 11:15:15 INFO - 1660389315528 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: sessionstore-windows-restored, aData: null
[task 2022-08-13T11:15:15.542Z] 11:15:15 INFO - 1660389315546 Toolkit.Telemetry TRACE TelemetrySession::observe - sessionstore-windows-restored notified.
[task 2022-08-13T11:15:15.547Z] 11:15:15 INFO - 1660389315547 Toolkit.Telemetry TRACE TelemetrySession::gatherStartup
[task 2022-08-13T11:15:15.548Z] 11:15:15 INFO - 1660389315547 Toolkit.Telemetry INFO TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2022-08-13T11:15:15.548Z] 11:15:15 INFO - 1660389315547 Toolkit.Telemetry TRACE TelemetryReportingPolicy::_shouldNotify - User already notified or bypassing the policy.
[task 2022-08-13T11:15:15.558Z] 11:15:15 INFO - 1660389315558 Toolkit.Telemetry TRACE TelemetryEnvironment::_startWatchingPrefs - [object Map]
[task 2022-08-13T11:15:15.560Z] 11:15:15 INFO - 1660389315559 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.urlbar.quicksuggest.migrationVersion
[task 2022-08-13T11:15:15.560Z] 11:15:15 INFO - 1660389315559 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.urlbar.quicksuggest.scenario
[task 2022-08-13T11:15:15.561Z] 11:15:15 INFO - 1660389315560 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: firefox-suggest-update, aData: null
[task 2022-08-13T11:15:15.566Z] 11:15:15 INFO - console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at Z:\\task_166038576644937\\build\\tmp2q2qm7yl.mozrunner\\search.json.mozlz4", (void 0)))
[task 2022-08-13T11:15:15.574Z] 11:15:15 INFO - 1660389315574 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.default.sites
[task 2022-08-13T11:15:15.575Z] 11:15:15 INFO - 1660389315574 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.section.topstories.options
[task 2022-08-13T11:15:15.575Z] 11:15:15 INFO - 1660389315574 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.topsites
[task 2022-08-13T11:15:15.576Z] 11:15:15 INFO - 1660389315574 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.hideTopSitesTitle
[task 2022-08-13T11:15:15.576Z] 11:15:15 INFO - 1660389315575 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.showSponsored
[task 2022-08-13T11:15:15.577Z] 11:15:15 INFO - 1660389315575 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.showSponsoredTopSites
[task 2022-08-13T11:15:15.577Z] 11:15:15 INFO - 1660389315575 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.pocketCta
[task 2022-08-13T11:15:15.578Z] 11:15:15 INFO - 1660389315575 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.showSearch
[task 2022-08-13T11:15:15.578Z] 11:15:15 INFO - 1660389315575 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.snippets
[task 2022-08-13T11:15:15.579Z] 11:15:15 INFO - 1660389315576 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.topSitesRows
[task 2022-08-13T11:15:15.580Z] 11:15:15 INFO - 1660389315576 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.telemetry.ut.events
[task 2022-08-13T11:15:15.580Z] 11:15:15 INFO - 1660389315576 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.telemetry.structuredIngestion.endpoint
[task 2022-08-13T11:15:15.581Z] 11:15:15 INFO - 1660389315576 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includeVisited
[task 2022-08-13T11:15:15.582Z] 11:15:15 INFO - 1660389315576 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includeBookmarks
[task 2022-08-13T11:15:15.582Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includePocket
[task 2022-08-13T11:15:15.582Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.includeDownloads
[task 2022-08-13T11:15:15.583Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.highlights.rows
[task 2022-08-13T11:15:15.583Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.section.topstories.rows
[task 2022-08-13T11:15:15.583Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.sectionOrder
[task 2022-08-13T11:15:15.584Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.improvesearch.noDefaultSearchTile
[task 2022-08-13T11:15:15.584Z] 11:15:15 INFO - 1660389315577 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.improvesearch.topSiteSearchShortcuts.searchEngines
[task 2022-08-13T11:15:15.584Z] 11:15:15 INFO - 1660389315578 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.improvesearch.topSiteSearchShortcuts.havePinned
[task 2022-08-13T11:15:15.585Z] 11:15:15 INFO - 1660389315578 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.asrouter.devtoolsEnabled
[task 2022-08-13T11:15:15.585Z] 11:15:15 INFO - 1660389315578 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.asrouter.providers.onboarding
[task 2022-08-13T11:15:15.586Z] 11:15:15 INFO - 1660389315578 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.flight.blocks
[task 2022-08-13T11:15:15.586Z] 11:15:15 INFO - 1660389315578 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.config
[task 2022-08-13T11:15:15.586Z] 11:15:15 INFO - 1660389315578 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.endpoints
[task 2022-08-13T11:15:15.587Z] 11:15:15 INFO - 1660389315579 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.region-basic-layout
[task 2022-08-13T11:15:15.587Z] 11:15:15 INFO - 1660389315579 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.spoc.impressions
[task 2022-08-13T11:15:15.587Z] 11:15:15 INFO - 1660389315579 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.endpointSpocsClear
[task 2022-08-13T11:15:15.588Z] 11:15:15 INFO - 1660389315579 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.discoverystream.rec.impressions
[task 2022-08-13T11:15:15.589Z] 11:15:15 INFO - 1660389315579 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.showRecentSaves
[task 2022-08-13T11:15:15.589Z] 11:15:15 INFO - 1660389315579 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.aboutpreferences
[task 2022-08-13T11:15:15.589Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.newtabinit
[task 2022-08-13T11:15:15.590Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.places
[task 2022-08-13T11:15:15.590Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.prefs
[task 2022-08-13T11:15:15.591Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.sections
[task 2022-08-13T11:15:15.591Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.section.highlights
[task 2022-08-13T11:15:15.592Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.system.topstories
[task 2022-08-13T11:15:15.592Z] 11:15:15 INFO - 1660389315580 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.systemtick
[task 2022-08-13T11:15:15.593Z] 11:15:15 INFO - 1660389315581 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.telemetry
[task 2022-08-13T11:15:15.593Z] 11:15:15 INFO - 1660389315581 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.favicon
[task 2022-08-13T11:15:15.593Z] 11:15:15 INFO - 1660389315581 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.system.topsites
[task 2022-08-13T11:15:15.594Z] 11:15:15 INFO - 1660389315581 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.recommendationprovider
[task 2022-08-13T11:15:15.594Z] 11:15:15 INFO - 1660389315581 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.feeds.discoverystreamfeed
[task 2022-08-13T11:15:15.595Z] 11:15:15 INFO - 1660389315584 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.activity-stream.impressionId
[task 2022-08-13T11:15:15.596Z] 11:15:15 INFO - 1660389315596 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2022-08-13T11:15:15.945Z] 11:15:15 INFO - 1660389315945 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: doh-rollout.balrog-migration-done
[task 2022-08-13T11:15:15.963Z] 11:15:15 INFO - 1660389315963 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.contextual-services.contextId
[task 2022-08-13T11:15:16.087Z] 11:15:16 INFO - 1660389316088 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: browser-search-service, aData: settings-update-complete
[task 2022-08-13T11:15:16.159Z] 11:15:16 INFO - 1660389316162 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2022-08-13T11:15:16.177Z] 11:15:16 INFO - 1660389316176 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: distribution-customization-complete, aData: null
[task 2022-08-13T11:15:16.177Z] 11:15:16 INFO - 1660389316177 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.bookmarks.restore_default_bookmarks
[task 2022-08-13T11:15:16.209Z] 11:15:16 INFO - 1660389316215 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2022-08-13T11:15:16.226Z] 11:15:16 INFO - 1660389316225 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2022-08-13T11:15:16.250Z] 11:15:16 INFO - 1660389316254 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2022-08-13T11:15:16.265Z] 11:15:16 INFO - 1660389316265 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: extensions.webextensions.uuids
[task 2022-08-13T11:15:16.273Z] 11:15:16 INFO - 1660389316272 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: browser-search-service, aData: init-complete
[task 2022-08-13T11:15:16.273Z] 11:15:16 INFO - 1660389316272 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateSearchEngine - isInitialized: true
[task 2022-08-13T11:15:16.275Z] 11:15:16 INFO - 1660389316274 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.urlbar.placeholderName
[task 2022-08-13T11:15:16.321Z] 11:15:16 INFO - 1660389316324 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.newtabpage.storageVersion
[task 2022-08-13T11:27:14.457Z] 11:27:14 INFO - JavaScript error: chrome://remote/content/marionette/driver.js, line 2270: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIObserverService.removeObserver]
[task 2022-08-13T11:28:26.362Z] 11:28:26 ERROR - Process has been unexpectedly closed (Exit code: 572) (Reason: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s))
[task 2022-08-13T11:28:26.362Z] 11:28:26 ERROR - Traceback (most recent call last):
[task 2022-08-13T11:28:26.362Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - return func(*args, **kwargs)
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 659, in _send_message
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - msg = self.client.request(name, params)
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 383, in request
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - return self.receive()
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 220, in receive
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - raise socket.timeout(
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - socket.timeout: Connection timed out after 360.0s
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - During handling of the above exception, another exception occurred:
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.373Z] 11:28:26 ERROR - Traceback (most recent call last):
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - return func(*args, **kwargs)
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1238, in start_session
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - m._handle_socket_failure()
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 743, in _handle_socket_failure
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - reraise(
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - raise value.with_traceback(tb)
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - return func(*args, **kwargs)
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.374Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 659, in _send_message
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - msg = self.client.request(name, params)
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 383, in request
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - return self.receive()
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 220, in receive
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - raise socket.timeout(
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - OSError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - During handling of the above exception, another exception occurred:
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - Traceback (most recent call last):
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_harness\runtests.py", line 108, in cli
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - failed = harness_instance.run()
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_harness\runtests.py", line 82, in run
[task 2022-08-13T11:28:26.375Z] 11:28:26 ERROR - runner.run_tests(tests)
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 986, in run_tests
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - self.fixture_servers = self.start_fixture_servers()
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 1086, in start_fixture_servers
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - if self.appName == "fennec":
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 803, in appName
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - self._appName = self.capabilities.get("browserName")
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_harness\runner\base.py", line 793, in capabilities
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - self.marionette.start_session()
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - m._handle_socket_failure()
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 743, in _handle_socket_failure
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - reraise(
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - raise value.with_traceback(tb)
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - return func(*args, **kwargs)
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1238, in start_session
[task 2022-08-13T11:28:26.376Z] 11:28:26 ERROR - resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - m._handle_socket_failure()
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 743, in _handle_socket_failure
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - reraise(
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - raise value.with_traceback(tb)
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - return func(*args, **kwargs)
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\marionette.py", line 659, in _send_message
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - msg = self.client.request(name, params)
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 383, in request
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - return self.receive()
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 220, in receive
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - raise socket.timeout(
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - OSError: Process has been unexpectedly closed (Exit code: 572) (Reason: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s))
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.420Z] 11:28:26 ERROR - Return code: 1
[task 2022-08-13T11:28:26.421Z] 11:28:26 ERROR - No suite end message was emitted by this harness.
[task 2022-08-13T11:28:26.421Z] 11:28:26 ERROR - No checks run.
[task 2022-08-13T11:28:26.421Z] 11:28:26 ERROR - # TBPL FAILURE #
|
||
Comment 1•3 years ago
|
||
Failure log snippet:
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - File "Z:\task_166038576644937\build\venv\lib\site-packages\marionette_driver\transport.py", line 220, in receive
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - raise socket.timeout(
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR - OSError: Process has been unexpectedly closed (Exit code: 572) (Reason: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s))
[task 2022-08-13T11:28:26.377Z] 11:28:26 ERROR -
Seems the same issue as Bug 1784625.
See Also: → 1784625
Whiteboard: [retriggered]
|
||
Updated•3 years ago
|
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
You need to log in
before you can comment on or make changes to this bug.
Description
•