Closed Bug 1516049 Opened 5 years ago Closed 5 years ago

Intermittent toolkit/mozapps/update/tests/browser/browser_updatesCompleteAndPartialPatchesWithBadCompleteSize.js | A promise chain failed to handle a rejection: request is null - stack: handleCriticalWriteFailure@

Categories

(Toolkit :: Application Update, defect, P5)

defect

Tracking

()

RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Assigned: robert.strong.bugs)

References

Details

(Keywords: intermittent-failure)

Filed by: dluca [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=218427430&repo=autoland

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

 INFO - TEST-START | toolkit/mozapps/update/tests/browser/browser_updatesCompleteAndPartialPatchesWithBadCompleteSize.js
15:20:59     INFO - TEST-INFO | started process screencapture
15:20:59     INFO - TEST-INFO | screencapture: exit 0
15:20:59     INFO - Buffered messages logged at 15:20:59
15:20:59     INFO - Entering test bound setDefaults
15:20:59     INFO - Buffered messages finished
15:20:59     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/browser/browser_updatesCompleteAndPartialPatchesWithBadCompleteSize.js | A promise chain failed to handle a rejection: request is null - stack: handleCriticalWriteFailure@jar:file:///Users/cltbld/tasks/task_1545432972/build/application/Firefox%20NightlyDebug.app/Contents/Resources/omni.ja!/components/nsUpdateService.js:1136:9
15:20:59     INFO - handleCriticalWriteResult@jar:file:///Users/cltbld/tasks/task_1545432972/build/application/Firefox%20NightlyDebug.app/Contents/Resources/omni.ja!/components/nsUpdateService.js:1179:5
15:20:59     INFO - UM__saveUpdatesXML/<@jar:file:///Users/cltbld/tasks/task_1545432972/build/application/Firefox%20NightlyDebug.app/Contents/Resources/omni.ja!/components/nsUpdateService.js:2902:30
15:20:59     INFO - Rejection date: Fri Dec 21 2018 15:20:59 GMT-0800 (Pacific Standard Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 257
15:20:59     INFO - Stack trace:
15:20:59     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:257
15:20:59     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1131
15:20:59     INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
15:20:59     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:995
15:20:59     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:focusedOrLoaded/<:803
15:20:59     INFO - Leaving test bound setDefaults
15:20:59     INFO - Entering test bound testCompleteAndPartialPatchesWithBadCompleteSize
15:20:59     INFO - 15:20:59:415 | TEST-INFO | chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/shared.js | [setUpdateURL : 182] setting app.update.url to http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getCanApplyUpdates - bypass the write since elevation can be used on Mac OS X
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getCanStageUpdates - staging updates is disabled by preference app.update.staging.enabled
15:20:59     INFO - GECKO(2348) | *** AUS:SVC readStringFromFile - file doesn't exist: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - GECKO(2348) | *** AUS:SVC readStatusFile - status: null, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - GECKO(2348) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker: checkForUpdates, force: false
15:20:59     INFO - GECKO(2348) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker:checkForUpdates - sending request to: http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - Console message: AUS:SVC getCanApplyUpdates - bypass the write since elevation can be used on Mac OS X
15:20:59     INFO - Console message: AUS:SVC getCanStageUpdates - staging updates is disabled by preference app.update.staging.enabled
15:20:59     INFO - Console message: AUS:SVC readStringFromFile - file doesn't exist: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - Console message: AUS:SVC readStatusFile - status: null, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - Console message: AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
15:20:59     INFO - Console message: AUS:SVC Checker: checkForUpdates, force: false
15:20:59     INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
15:20:59     INFO - Console message: AUS:SVC Checker:getUpdateURL - update URL: http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - Console message: AUS:SVC Checker:checkForUpdates - sending request to: http://example.com/browser/toolkit/mozapps/update/tests/browser/update.sjs?invalidCompleteSize=1&promptWaitTime=0&appVersion=66.0a1
15:20:59     INFO - GECKO(2348) | [Parent 2348, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/netwerk/url-classifier/UrlClassifierCommon.cpp, line 121
15:20:59     INFO - GECKO(2348) | [Parent 2348, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
15:20:59     INFO - GECKO(2348) | [Parent 2348, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker:onLoad - request completed downloading document
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker:onLoad - Getting sslStatus failed.
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Checker:onLoad - number of updates available: 1
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getElevationRequired - recursively testing write access on /Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug.app
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getElevationRequired - able to write to application bundle, elevation not required
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getCanApplyUpdates - bypass the write since elevation can be used on Mac OS X
15:20:59     INFO - GECKO(2348) | *** AUS:SVC UpdateService:_selectAndInstallUpdate - download the update
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Creating Downloader
15:20:59     INFO - GECKO(2348) | *** AUS:SVC UpdateService:_downloadUpdate
15:20:59     INFO - GECKO(2348) | *** AUS:SVC readStringFromFile - file doesn't exist: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - GECKO(2348) | *** AUS:SVC readStatusFile - status: null, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:downloadUpdate - url: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.mar, interval: null
15:20:59     INFO - Console message: AUS:SVC Checker:onLoad - request completed downloading document
15:20:59     INFO - Console message: AUS:SVC Checker:onLoad - Getting sslStatus failed.
15:20:59     INFO - Console message: AUS:SVC Checker:onLoad - number of updates available: 1
15:20:59     INFO - Console message: AUS:SVC getElevationRequired - recursively testing write access on /Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug.app
15:20:59     INFO - Console message: AUS:SVC getElevationRequired - able to write to application bundle, elevation not required
15:20:59     INFO - Console message: AUS:SVC getCanApplyUpdates - bypass the write since elevation can be used on Mac OS X
15:20:59     INFO - Console message: AUS:SVC UpdateService:_selectAndInstallUpdate - download the update
15:20:59     INFO - Console message: AUS:SVC Creating Downloader
15:20:59     INFO - Console message: AUS:SVC UpdateService:_downloadUpdate
15:20:59     INFO - Console message: AUS:SVC readStringFromFile - file doesn't exist: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - Console message: AUS:SVC readStatusFile - status: null, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.status
15:20:59     INFO - Console message: AUS:SVC Downloader:downloadUpdate - url: http://example.com/browser/toolkit/mozapps/update/tests/browser/simple.mar, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug/updates/0/update.mar, interval: null
15:20:59     INFO - GECKO(2348) | [Parent 2348, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
15:20:59     INFO - GECKO(2348) | [Parent 2348, Main Thread] WARNING: Found a principal with no URI, assuming third-party request: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 226
15:20:59     INFO - GECKO(2348) | *** 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
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:onProgress - progress: 1404/1404
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:onProgress - progress: 1404/1404
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:onStopRequest - 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
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:_verifyDownload called
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
15:20:59     INFO - GECKO(2348) | *** AUS:SVC shouldUseService - returning false
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getElevationRequired - recursively testing write access on /Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug.app
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getElevationRequired - able to write to application bundle, elevation not required
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getCanStageUpdates - staging updates is disabled by preference app.update.staging.enabled
15:20:59     INFO - GECKO(2348) | *** AUS:SVC Downloader:onStopRequest - setting state to: pending
15:20:59     INFO - GECKO(2348) | *** AUS:SVC getCanStageUpdates - staging updates is disabled by preference app.update.staging.enabled
15:20:59     INFO - GECKO(2348) | *** AUS:SVC showUpdateDownloaded - Notifying observers that an update was downloaded. topic: update-downloaded, status: pending
15:20:59     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
15:20:59     INFO - Console message: AUS:SVC Downloader:onProgress - progress: 1404/1404
15:20:59     INFO - Console message: AUS:SVC Downloader:onProgress - progress: 1404/1404
15:20:59     INFO - Console message: AUS:SVC Downloader:onStopRequest - 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
15:20:59     INFO - Console message: AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
15:20:59     INFO - Console message: AUS:SVC Downloader:_verifyDownload called
15:20:59     INFO - Console message: AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
15:20:59     INFO - Console message: AUS:SVC shouldUseService - returning false
15:20:59     INFO - Console message: AUS:SVC getElevationRequired - recursively testing write access on /Users/cltbld/tasks/task_1545432972/build/application/Firefox NightlyDebug.app
15:20:59     INFO - Console message: AUS:SVC getElevationRequired - able to write to application bundle, elevation not required
15:20:59     INFO - Console message: AUS:SVC getCanStageUpdates - staging updates is disabled by preference app.update.staging.enabled
15:20:59     INFO - Console message: AUS:SVC Downloader:onStopRequest - setting state to: pending
15:20:59     INFO - GECKO(2348) | --DOMWINDOW == 11 (0x127007c00) [pid = 2349] [serial = 8] [outer = 0x0] [url = about:blank]
15:20:59     INFO - GECKO(2348) | --DOMWINDOW == 10 (0x127007400) [pid = 2349] [serial = 7] [outer = 0x0] [url = about:blank]
15:20:59     INFO - Console message: AUS:SVC getCanStageUpdates - staging updates is disabled by preference app.update.staging.enabled
15:20:59     INFO - GECKO(2348) | --DOMWINDOW == 9 (0x127006800) [pid = 2349] [serial = 6] [outer = 0x0] [url = about:blank]
15:20:59     INFO - GECKO(2348) | --DOMWINDOW == 8 (0x11cfa9400) [pid = 2349] [serial = 5] [outer = 0x0] [url = about:blank]
15:20:59     INFO - Console message: AUS:SVC showUpdateDownloaded - Notifying observers that an update was downloaded. topic: update-downloaded, status: pending
15:20:59     INFO - TEST-PASS | toolkit/mozapps/update/tests/browser/browser_updatesCompleteAndPartialPatchesWithBadCompleteSize.js | The right notification showed up. - 
15:20:59     INFO - TEST-PASS | toolkit/mozapps/update/tests/browser/browser_updatesCompleteAndPartialPatchesWithBadCompleteSize.js | update-restart notification is showing - 
15:20:59     INFO - Leaving test bound testCompleteAndPartialPatchesWithBadCompleteSize
15:20:59     INFO - GECKO(2348) | MEMORY STAT | vsize 4378MB | residentFast 353MB | heapAllocated 119MB
15:20:59     INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_updatesCompleteAndPartialPatchesWithBadCompleteSize.js | took 607ms
Depends on: 1499004

I hope to fix this in bug 1499004

Assignee: nobody → robert.strong.bugs
Status: NEW → ASSIGNED

This should be fixed by the patch that landed in bug 1499004 so resolving as fixed.

Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.