Closed Bug 1545868 Opened 6 months ago Closed 6 months ago

Intermittent toolkit/mozapps/update/tests/browser/browser_telemetry_partial_stageFailure_complete_stageFailure.js | Test timed out -

Categories

(Toolkit :: Application Update, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1545712

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered])

#[markdown(off)]
Filed by: dluca [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=241473554&repo=mozilla-central

https://queue.taskcluster.net/v1/task/KTOvQeK2ROGoKzmlgwgVMA/runs/0/artifacts/public/logs/live_backing.log

TEST-START | toolkit/mozapps/update/tests/browser/browser_telemetry_partial_stageFailure_complete_stageFailure.js
18:54:05 INFO - GECKO(10764) | --DOMWINDOW == 2 (00000176EFA85400) [pid = 6388] [serial = 3] [outer = 0000000000000000] [url = about:blank]
18:54:05 INFO - GECKO(10764) | DLL blocklist was unable to intercept AppInit DLLs.
18:54:05 INFO - GECKO(10764) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1555696559\appdata\local\temp\tmpi3wgcw.mozrunner\runtests_leaks_tab_pid140.log
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Creating UpdateService
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Logging current UpdateService status:
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - able to apply updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC gCanStageUpdatesSession - testing write access Z:\task_1555696559\build\application\firefox\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC gCanStageUpdatesSession - testing write access Z:\task_1555696559\build\application\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC gCanStageUpdatesSession - able to stage updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Elevation required: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Update being handled by other instance: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloading: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC End of UpdateService status
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - able to apply updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanUseBits - Not using BITS. Disabled by pref.
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | [Parent 720, StreamTrans #3] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 371
18:54:06 INFO - GECKO(10764) | [Parent 720, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 994
18:54:06 INFO - GECKO(10764) | [Parent 720, Main Thread] WARNING: '!parent', file z:/build/build/src/netwerk/ipc/NeckoParent.cpp, line 955
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker: checkForUpdates, force: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Creating UpdateService
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Logging current UpdateService status:
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - able to apply updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Elevation required: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Update being handled by other instance: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloading: false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC End of UpdateService status
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker:checkForUpdates - sending request to: http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker:onLoad - request completed downloading document
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker:onLoad - Getting sslStatus failed.
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Checker:onLoad - number of updates available: 1
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: C:\ProgramData\Mozilla\updates\41F89285821333F7\active-update.xml
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanApplyUpdates - able to apply updates
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateService:_selectAndInstallUpdate - download the update
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Creating Downloader
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateService:_downloadUpdate
18:54:06 INFO - GECKO(10764) | *** AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.status
18:54:06 INFO - GECKO(10764) | *** AUS:SVC readStatusFile - status: null, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.status
18:54:06 INFO - GECKO(10764) | *** AUS:SVC getCanUseBits - Not using BITS. Disabled by pref.
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:downloadUpdate - Starting nsIIncrementalDownload with url: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.mar, interval: 0
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onStartRequest - original URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, final URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onProgress - progress: 1419/1419
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onProgress - progress: 1419/1419
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onStopRequest - downloader: nsIIncrementalDownload, original URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, final URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, status: 0
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:_verifyDownload called
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onStopRequest - setting state to: pending
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC Downloader:onStopRequest - attempting to stage update: App Update Test
18:54:06 INFO - GECKO(10764) | *** AUS:SVC readStatusFile - status: applied, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.status
18:54:06 INFO - GECKO(10764) | *** AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\bt.result
18:54:06 INFO - GECKO(10764) | *** AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\bt.result
18:54:06 INFO - GECKO(10764) | *** AUS:SVC isServiceInstalled - returning true
18:54:06 INFO - GECKO(10764) | *** AUS:SVC shouldUseService - returning false
18:54:06 INFO - GECKO(10764) | *** AUS:SVC UpdateManager:refreshUpdateStatus - Notifying observers that the update was staged. topic: update-staged, status: applied
18:54:06 INFO - GECKO(10764) | *** AUS:SVC showUpdateDownloaded - Notifying observers that an update was downloaded. topic: update-downloaded, status: applied
18:54:13 INFO - GECKO(10764) | --DOMWINDOW == 2 (0000020C91797400) [pid = 4164] [serial = 2] [outer = 0000000000000000] [url = about:blank]
18:55:01 INFO - GECKO(10764) | --DOCSHELL 00000176EBA78000 == 0 [pid = 6388] [id = {775c7130-0bb2-4637-a292-b81c1eb10f45}] [url = about:blank]
18:55:01 INFO - GECKO(10764) | --DOMWINDOW == 1 (00000176F1915020) [pid = 6388] [serial = 1] [outer = 0000000000000000] [url = about:blank]
18:55:05 INFO - GECKO(10764) | --DOMWINDOW == 0 (00000176F1D7BC00) [pid = 6388] [serial = 4] [outer = 0000000000000000] [url = about:blank]
18:59:26 INFO - [1560, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/places/Database.cpp, line 552
18:59:26 INFO - [1560, Main Thread] WARNING: Unable to get a connection to vacuum database: file z:/build/build/src/storage/VacuumManager.cpp, line 128
18:59:26 INFO - [1560, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/xpcom/base/nsSystemInfo.cpp, line 111
18:59:26 INFO - [1560, IPDL Background] WARNING: '!gBaseDirPath', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 2925
18:59:26 INFO - [1560, IPDL Background] WARNING: profile-do-change must precede QuotaManager::GetOrCreate(): file z:/build/build/src/dom/quota/ActorsParent.cpp, line 2926
18:59:26 INFO - [1560, IPDL Background] WARNING: '!quotaManager', file z:/build/build/src/dom/quota/ActorsParent.cpp, line 6602
19:08:26 INFO - JavaScript error: resource://gre/modules/PlacesExpiration.jsm, line 762: TypeError: this._db is undefined
19:09:06 INFO - TEST-INFO | started process screenshot
19:09:06 INFO - TEST-INFO | screenshot: exit 0
19:09:06 INFO - Buffered messages logged at 18:54:05
19:09:06 INFO - Entering test bound setupTestCommon
19:09:06 INFO - Leaving test bound setupTestCommon
19:09:06 INFO - Entering test bound telemetry_partial_stageFailure_complete_stageFailure
19:09:06 INFO - Buffered messages logged at 18:54:06
19:09:06 INFO - 18:54:06:016 | TEST-INFO | chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/shared.js | [setUpdateURL : 220] setting app.update.url to http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
19:09:06 INFO - Console message: AUS:SVC Creating UpdateService
19:09:06 INFO - Console message: AUS:SVC Logging current UpdateService status:
19:09:06 INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - able to apply updates
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC gCanStageUpdatesSession - testing write access Z:\task_1555696559\build\application\firefox\update.test
19:09:06 INFO - Console message: AUS:SVC gCanStageUpdatesSession - testing write access Z:\task_1555696559\build\application\update.test
19:09:06 INFO - Console message: AUS:SVC gCanStageUpdatesSession - able to stage updates
19:09:06 INFO - Console message: AUS:SVC Elevation required: false
19:09:06 INFO - Console message: AUS:SVC Update being handled by other instance: false
19:09:06 INFO - Console message: AUS:SVC Downloading: false
19:09:06 INFO - Console message: AUS:SVC End of UpdateService status
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - able to apply updates
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC getCanUseBits - Not using BITS. Disabled by pref.
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
19:09:06 INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
19:09:06 INFO - Console message: AUS:SVC Checker: checkForUpdates, force: false
19:09:06 INFO - Console message: AUS:SVC Creating UpdateService
19:09:06 INFO - Console message: AUS:SVC Logging current UpdateService status:
19:09:06 INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - able to apply updates
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC Elevation required: false
19:09:06 INFO - Console message: AUS:SVC Update being handled by other instance: false
19:09:06 INFO - Console message: AUS:SVC Downloading: false
19:09:06 INFO - Console message: AUS:SVC End of UpdateService status
19:09:06 INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
19:09:06 INFO - Console message: AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
19:09:06 INFO - Console message: AUS:SVC Checker:checkForUpdates - sending request to: http://example.com/browser/toolkit/mozapps/update/tests/browser/app_update.sjs?detailsURL=http://example.com/&appVersion=68.0a1
19:09:06 INFO - Console message: AUS:SVC Checker:onLoad - request completed downloading document
19:09:06 INFO - Console message: AUS:SVC Checker:onLoad - Getting sslStatus failed.
19:09:06 INFO - Console message: AUS:SVC Checker:onLoad - number of updates available: 1
19:09:06 INFO - Console message: AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: C:\ProgramData\Mozilla\updates\41F89285821333F7\active-update.xml
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\41F89285821333F7\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - testing write access Z:\task_1555696559\build\application\firefox\update.test
19:09:06 INFO - Console message: AUS:SVC getCanApplyUpdates - able to apply updates
19:09:06 INFO - Console message: AUS:SVC UpdateService:_selectAndInstallUpdate - download the update
19:09:06 INFO - Console message: AUS:SVC Creating Downloader
19:09:06 INFO - Console message: AUS:SVC UpdateService:_downloadUpdate
19:09:06 INFO - Console message: AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.status
19:09:06 INFO - Console message: AUS:SVC readStatusFile - status: null, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.status
19:09:06 INFO - Console message: AUS:SVC getCanUseBits - Not using BITS. Disabled by pref.
19:09:06 INFO - Console message: AUS:SVC Downloader:downloadUpdate - Starting nsIIncrementalDownload with url: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.mar, interval: 0
19:09:06 INFO - Console message: AUS:SVC Downloader:onStartRequest - original URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, final URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar
19:09:06 INFO - Console message: AUS:SVC Downloader:onProgress - progress: 1419/1419
19:09:06 INFO - Console message: AUS:SVC Downloader:onProgress - progress: 1419/1419
19:09:06 INFO - Console message: AUS:SVC Downloader:onStopRequest - downloader: nsIIncrementalDownload, original URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, final URI spec: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, status: 0
19:09:06 INFO - Console message: AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
19:09:06 INFO - Console message: AUS:SVC Downloader:_verifyDownload called
19:09:06 INFO - Console message: AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC Downloader:onStopRequest - setting state to: pending
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC Downloader:onStopRequest - attempting to stage update: App Update Test
19:09:06 INFO - Console message: AUS:SVC readStatusFile - status: applied, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\update.status
19:09:06 INFO - Console message: AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\bt.result
19:09:06 INFO - Console message: AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\41F89285821333F7\updates\0\bt.result
19:09:06 INFO - Console message: AUS:SVC isServiceInstalled - returning true
19:09:06 INFO - Console message: AUS:SVC shouldUseService - returning false
19:09:06 INFO - Console message: AUS:SVC UpdateManager:refreshUpdateStatus - Notifying observers that the update was staged. topic: update-staged, status: applied
19:09:06 INFO - Console message: AUS:SVC showUpdateDownloaded - Notifying observers that an update was downloaded. topic: update-downloaded, status: applied
19:09:06 INFO - Buffered messages logged at 18:55:35
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 9
19:09:06 INFO - Buffered messages logged at 18:57:05
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 8
19:09:06 INFO - Buffered messages logged at 18:58:00
19:09:06 INFO - Console message: [JavaScript Error: "Polling for changes failed: Unexpected content-type "text/html;charset=utf-8"." {file: "resource://services-settings/remote-settings.js" line: 208}]
19:09:06 INFO - remoteSettingsFunction/remoteSettings.pollChanges@resource://services-settings/remote-settings.js:208:13
19:09:06 INFO - async*notify@resource://services-settings/RemoteSettingsComponents.jsm:22:20
19:09:06 INFO - TM_notify/<@resource://gre/modules/UpdateTimerManager.jsm:192:48
19:09:06 INFO - TM_notify@resource://gre/modules/UpdateTimerManager.jsm:239:7
19:09:06 INFO -
19:09:06 INFO - Buffered messages logged at 18:58:35
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 7
19:09:06 INFO - Buffered messages logged at 19:00:05
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 6
19:09:06 INFO - Buffered messages logged at 19:01:35
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 5
19:09:06 INFO - Buffered messages logged at 19:03:05
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 4
19:09:06 INFO - Buffered messages logged at 19:04:35
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 3
19:09:06 INFO - Buffered messages logged at 19:06:05
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 2
19:09:06 INFO - Buffered messages logged at 19:07:36
19:09:06 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
19:09:06 INFO - Buffered messages finished
19:09:06 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/browser/browser_telemetry_partial_stageFailure_complete_stageFailure.js | Test timed out -
19:09:06 INFO - GECKO(10764) | [CodeCoverage] Requested flush for 720.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] flush completed.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] JS flush completed.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] Requested flush for 7856.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] Requested flush for 6232.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] Requested flush for 4164.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] Requested flush for 6388.
19:09:07 INFO - GECKO(10764) | [CodeCoverage] Requested flush for 140.
19:09:12 INFO - GECKO(10764) | [CodeCoverage] flush completed.
19:09:12 INFO - GECKO(10764) | [CodeCoverage] JS flush completed.
19:09:17 INFO - GECKO(10764) | [CodeCoverage] flush completed.
19:09:17 INFO - GECKO(10764) | [CodeCoverage] JS flush completed.
19:09:21 INFO - GECKO(10764) | [CodeCoverage] flush completed.
19:09:22 INFO - GECKO(10764) | [CodeCoverage] JS flush completed.
19:09:26 INFO - GECKO(10764) | [CodeCoverage] flush completed.
19:09:26 INFO - GECKO(10764) | [CodeCoverage] JS flush completed.
19:09:31 INFO - GECKO(10764) | [CodeCoverage] flush completed.
19:09:31 INFO - GECKO(10764) | [CodeCoverage] JS flush completed.
19:09:31 INFO - 19:09:31:570 | TEST-INFO | chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/shared.js | [removeDirRecursive : 529] attempting to remove directory. Path: Z:\task_1555696559\build\application\firefox\updated
19:09:31 INFO - GECKO(10764) | MEMORY STAT | vsize 2104494MB | vsizeMaxContiguous 67497696MB | residentFast 491MB | heapAllocated 97MB
19:09:31 INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_telemetry_partial_stageFailure_complete_stageFailure.js | took 925915ms

It seems that https://bugzilla.mozilla.org/show_bug.cgi?id=1539154 caused this failure. The failures are on windows10-64-ccov platform, debug build type.
https://hg.mozilla.org/mozilla-central/rev/723b79068fb090492592eb6552a6d64225f2d30d

Robert, can you please take a look at this?

Flags: needinfo?(robert.strong.bugs)
Regressed by: 1539154
Whiteboard: [retriggered]

This is due to bug 1545712... I'll fix this there

Flags: needinfo?(robert.strong.bugs)

I submitted a patch in bug 1545712 that fixes this on Saturday.

This was due to the code coverage build's mozconfig not having everything it needed to run update tests. I fixed this in bug 1545712 by making the config option needed the default.

Status: NEW → RESOLVED
Closed: 6 months ago
Resolution: --- → DUPLICATE
Duplicate of bug: 1545712
You need to log in before you can comment on or make changes to this bug.