Closed Bug 1710890 Opened 4 years ago Closed 3 years ago

Nightly can't install automatic update, always ask to download

Categories

(Toolkit :: Application Update, defect)

Firefox 90
Desktop
Windows 10
defect

Tracking

()

RESOLVED FIXED
Tracking Status
firefox90 --- affected

People

(Reporter: ian.henderson, Unassigned)

Details

Attachments

(9 files)

Attached image Help -> About

When I download the latest version I get an error, as the URL https://www.mozilla.org/en-GB/sco/firefox/channel/desktop/#nightly does not appear to be correct.

Blocks: 1710891
Summary: Update of nightly URL appears wrong → Nightly can't install automatic update, always ask to download
No longer blocks: 1710891

Could you please attach an update log showing this problem happening? You can get this by following these steps:

  1. Navigate the browser to about:config.
  2. Set the pref app.update.log to true.
  3. Close the browser.
  4. Re-open the browser.
  5. Open the Help->About window. Verify that it shows that updating failed.
  6. Open the Browser Console (Control+Shift+J or Hamburger Menu->More Tools->Browser Console)
  7. Set the filter to be AUS:SVC to show only the update-related log messages
  8. Attach the resulting log to this bug.
Flags: needinfo?(ian.henderson)
Attached file AUS:SVC log
I could not find a way to save to log info, so here it is in txt format:

I could not find a way to save to log info, so here it is in txt format:
AUS:SVC Creating UpdateService
AUS:SVC Logging current UpdateService status:
AUS:SVC UpdateService.canUsuallyCheckForUpdates - able to check for updates
AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\6F193CCC56814779\update.test
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanApplyUpdates - bypass the write checks because the Windows Maintenance Service can be used
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Elevation required: false
AUS:SVC Other instance of the application currently running: false
AUS:SVC Downloading: false
AUS:SVC End of UpdateService status
AUS:SVC UpdateService.canUsuallyCheckForUpdates - able to check for updates
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC getCanUseBits - BITS can be used to download updates
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\active-update.xml
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Checker: checkForUpdates, force: true
AUS:SVC Creating UpdateService
AUS:SVC Logging current UpdateService status:
AUS:SVC UpdateService.canUsuallyCheckForUpdates - able to check for updates
AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\6F193CCC56814779\update.test
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanApplyUpdates - bypass the write checks because the Windows Maintenance Service can be used
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Elevation required: false
AUS:SVC Other instance of the application currently running: false
AUS:SVC Downloading: false
AUS:SVC End of UpdateService status
AUS:SVC UpdateService.canUsuallyCheckForUpdates - able to check for updates
AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
AUS:SVC waitForOtherInstances - beginning polling
AUS:SVC waitForOtherInstances - no other instances found, exiting
AUS:SVC Checker:getUpdateURL - update URL: https://aus5.mozilla.org/update/6/Firefox/90.0a1/20210511093339/WINNT_x86_64-msvc-x64/sco/nightly/Windows_NT%2010.0.0.0.19042.928%20(x64)/ISET:SSE4_2,MEM:16147/default/default/update.xml?force=1
AUS:SVC Checker:checkForUpdates - sending request to: https://aus5.mozilla.org/update/6/Firefox/90.0a1/20210511093339/WINNT_x86_64-msvc-x64/sco/nightly/Windows_NT%2010.0.0.0.19042.928%20(x64)/ISET:SSE4_2,MEM:16147/default/default/update.xml?force=1
AUS:SVC Checker:onLoad - request completed downloading document
AUS:SVC Checker:onLoad - Getting sslStatus failed.
AUS:SVC Checker:onLoad - number of updates available: 1
AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\6F193CCC56814779\update.test
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanApplyUpdates - bypass the write checks because the Windows Maintenance Service can be used
AUS:SVC Creating Downloader
AUS:SVC UpdateService:_downloadUpdate
AUS:SVC getCanUseBits - BITS can be used to download updates
AUS:SVC Downloader:_canUseBits - Patch is able to use BITS download
AUS:SVC Downloader:downloadUpdate - Starting BITS download with url: https://archive.mozilla.org/pub/firefox/nightly/partials/2021/05/2021-05-12-09-42-13-mozilla-central-l10n/firefox-mozilla-central-90.0a1-win64-sco-20210511093339-20210512094213.partial.mar, updateDir: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\downloading, filename: update.mar
AUS:SVC Downloader:downloadUpdate - BITS download running. BITS ID: {BBB148A0-A833-42B5-B2BA-57B33A83D157}
AUS:SVC Downloader:onProgress - progress: 4718592/9108452
AUS:SVC Downloader:onProgress - progress: 9108452/9108452
AUS:SVC Downloader:onStopRequest - downloader: BITS, status: 0
AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
AUS:SVC Downloader:_verifyDownload called
AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - setting state to: pending-service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - attempting to stage update: Nightly 90.0a1
AUS:SVC readStatusFile - status: failed: 57, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\update.status
AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC handleFallbackToCompleteUpdate - install of partial patch failed, downloading complete patch
AUS:SVC Creating Downloader
AUS:SVC UpdateService:_downloadUpdate
AUS:SVC Downloader:_selectPatch - found existing patch with state: failed
AUS:SVC getCanUseBits - BITS can be used to download updates
AUS:SVC Downloader:_canUseBits - Patch is able to use BITS download
AUS:SVC Downloader:downloadUpdate - Starting BITS download with url: https://archive.mozilla.org/pub/firefox/nightly/2021/05/2021-05-12-09-42-13-mozilla-central-l10n/firefox-90.0a1.sco.win64.complete.mar, updateDir: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\downloading, filename: update.mar
AUS:SVC promiseLangPacksUpdated - waiting for language pack updates to stage.
AUS:SVC UpdateManager:refreshUpdateStatus - Notifying observers that the update was staged. topic: update-staged, status: downloading
AUS:SVC Downloader:downloadUpdate - BITS download running. BITS ID: {0AC2D0A2-95A8-40B6-B505-27E109F3AF5D}
AUS:SVC Downloader:onProgress - progress: 6291456/61809155
AUS:SVC Downloader:onProgress - progress: 13107200/61809155
AUS:SVC Downloader:onProgress - progress: 19922944/61809155
AUS:SVC Downloader:onProgress - progress: 27000832/61809155
AUS:SVC Downloader:onProgress - progress: 33816576/61809155
AUS:SVC Downloader:onProgress - progress: 40632320/61809155
AUS:SVC Downloader:onProgress - progress: 47448064/61809155
AUS:SVC Downloader:onProgress - progress: 54525952/61809155
AUS:SVC Downloader:onProgress - progress: 61341696/61809155
AUS:SVC Downloader:onProgress - progress: 61809155/61809155
AUS:SVC Downloader:onStopRequest - downloader: BITS, status: 0
AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
AUS:SVC Downloader:_verifyDownload called
AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - setting state to: pending-service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - attempting to stage update: Nightly 90.0a1
AUS:SVC readStatusFile - status: failed: 57, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\update.status
AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC handleFallbackToCompleteUpdate - install of complete or only one patch offered failed. Notifying observers. topic: update-error, status: unknown, update.patchCount: 2, oldType: complete
AUS:SVC promiseLangPacksUpdated - waiting for language pack updates to stage.
AUS:SVC UpdateManager:refreshUpdateStatus - Notifying observers that the update was staged. topic: update-staged, status: failed
AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\6F193CCC56814779\active-update.xml
AUS:SVC UpdateService:removeDownloadListener - Warning: Didn't remove non-existent listener
AUS:SVC Checker:onLoad - request completed downloading document
AUS:SVC Checker:onLoad - Getting sslStatus failed.
AUS:SVC Checker:onLoad - number of updates available: 1
AUS:SVC getCanApplyUpdates - testing write access C:\ProgramData\Mozilla\updates\6F193CCC56814779\update.test
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanApplyUpdates - bypass the write checks because the Windows Maintenance Service can be used
AUS:SVC Creating Downloader
AUS:SVC UpdateService:_downloadUpdate
AUS:SVC getCanUseBits - BITS can be used to download updates
AUS:SVC Downloader:_canUseBits - Patch is able to use BITS download
AUS:SVC Downloader:downloadUpdate - Starting BITS download with url: https://archive.mozilla.org/pub/firefox/nightly/partials/2021/05/2021-05-12-09-42-13-mozilla-central-l10n/firefox-mozilla-central-90.0a1-win64-sco-20210511093339-20210512094213.partial.mar, updateDir: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\downloading, filename: update.mar
AUS:SVC Downloader:downloadUpdate - BITS download running. BITS ID: {BBB148A0-A833-42B5-B2BA-57B33A83D157}
AUS:SVC Downloader:onProgress - progress: 4718592/9108452
AUS:SVC Downloader:onProgress - progress: 9108452/9108452
AUS:SVC Downloader:onStopRequest - downloader: BITS, status: 0
AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
AUS:SVC Downloader:_verifyDownload called
AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - setting state to: pending-service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - attempting to stage update: Nightly 90.0a1
AUS:SVC readStatusFile - status: failed: 57, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\update.status
AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC handleFallbackToCompleteUpdate - install of partial patch failed, downloading complete patch
AUS:SVC Creating Downloader
AUS:SVC UpdateService:_downloadUpdate
AUS:SVC Downloader:_selectPatch - found existing patch with state: failed
AUS:SVC getCanUseBits - BITS can be used to download updates
AUS:SVC Downloader:_canUseBits - Patch is able to use BITS download
AUS:SVC Downloader:downloadUpdate - Starting BITS download with url: https://archive.mozilla.org/pub/firefox/nightly/2021/05/2021-05-12-09-42-13-mozilla-central-l10n/firefox-90.0a1.sco.win64.complete.mar, updateDir: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\downloading, filename: update.mar
AUS:SVC promiseLangPacksUpdated - waiting for language pack updates to stage.
AUS:SVC UpdateManager:refreshUpdateStatus - Notifying observers that the update was staged. topic: update-staged, status: downloading
AUS:SVC Downloader:downloadUpdate - BITS download running. BITS ID: {0AC2D0A2-95A8-40B6-B505-27E109F3AF5D}
AUS:SVC Downloader:onProgress - progress: 6291456/61809155
AUS:SVC Downloader:onProgress - progress: 13107200/61809155
AUS:SVC Downloader:onProgress - progress: 19922944/61809155
AUS:SVC Downloader:onProgress - progress: 27000832/61809155
AUS:SVC Downloader:onProgress - progress: 33816576/61809155
AUS:SVC Downloader:onProgress - progress: 40632320/61809155
AUS:SVC Downloader:onProgress - progress: 47448064/61809155
AUS:SVC Downloader:onProgress - progress: 54525952/61809155
AUS:SVC Downloader:onProgress - progress: 61341696/61809155
AUS:SVC Downloader:onProgress - progress: 61809155/61809155
AUS:SVC Downloader:onStopRequest - downloader: BITS, status: 0
AUS:SVC Downloader:onStopRequest - status: 0, current fail: 0, max fail: 10, retryTimeout: 2000
AUS:SVC Downloader:_verifyDownload called
AUS:SVC Downloader:_verifyDownload downloaded size == expected size.
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - setting state to: pending-service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC isServiceInstalled - returning true
AUS:SVC shouldUseService - returning true
AUS:SVC getCanStageUpdates - able to stage updates using the service
AUS:SVC Downloader:onStopRequest - attempting to stage update: Nightly 90.0a1
AUS:SVC readStatusFile - status: failed: 57, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\update.status
AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\6F193CCC56814779\updates\0\bt.result
AUS:SVC handleFallbackToCompleteUpdate - install of complete or only one patch offered failed. Notifying observers. topic: update-error, status: unknown, update.patchCount: 2, oldType: complete
AUS:SVC promiseLangPacksUpdated - waiting for language pack updates to stage.
AUS:SVC UpdateManager:refreshUpdateStatus - Notifying observers that the update was staged. topic: update-staged, status: failed
AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\6F193CCC56814779\active-update.xml
AUS:SVC UpdateService:removeDownloadListener - Warning: Didn't remove non-existent listener

Flags: needinfo?(ian.henderson)

There are two unexpected things going on in that log. The first is an error indicating that the Mozilla Maintenance Service can't be used because Nightly wasn't installed with the installer. Did you install with the installer? If so, could you check to see that the necessary Maintenance Service Registry key exists? You can check by following these steps:
(These steps are for an installation located in C:\Program Files\Firefox Nightly. From your update log, it looks like that is where your installation is. Let me know if that isn't correct)

  1. Open the Run window with Win+R
  2. Type in regedit and click Ok
  3. Navigate to the key HKEY_LOCAL_MACHINE\SOFTWARE\Mozilla\MaintenanceService\35562fadc262dec332219264bffef2fb

If the key doesn't exist, can you try installing again with the installer and seeing if that fixes the problem?

The other unexpected thing going on here is that we aren't falling back to doing a update without the Maintenance Service when the Service fails. It looks like the relevant code for that lives here, which does indeed suggest that we would not fall back to using the Maintenance Service in this case. That seems odd to me and I think that we should probably change that code to allow that fall back to happen.

Flags: needinfo?(ian.henderson)
Attached image RegEdit extract

This is a snapshot from my current registry

Flags: needinfo?(ian.henderson)

The Nightly did not update originally, so I uninstalled it and reinstalled "Firefox Installer.sco(1).exe"

The snapshot above was taken after I reinstalled.

That seems unusual. Could you attach your most recent maintenance service log? It should be located at C:\Program Files (x86)\Mozilla Maintenance Service\logs\maintenanceservice.log.

Attached file maintenanceservice.log

I have attached the 4 most recent maintenance logs, which were all dated last week, although I installed Nightly today.

I am going to sign off for tonight; but will respond tomorrow to any requests

(In reply to ian.henderson from comment #12)

I have attached the 4 most recent maintenance logs, which were all dated last week, although I installed Nightly today.

I had thought that the maintenance service was running, but exiting with a failure. But, from the timing and the log contents, it is clear that the maintenance service isn't running at all. Therefore, I would like to see the updater logs. You can find them with these steps:

  1. Open Nightly
  2. Navigate to about:support.
  3. Near the top, you should see "Update Folder". Click on the "Open Folder" button.
  4. From that directory, open the updates directory.
  5. Attach last-update.log and backup-update.log to the bug.

Sorry that we're having so much trouble pinning down the source of the problem here. Hopefully this will be the last log that I need.

Attached file last-update.log
Attached file backup-update.log

Not to worry. I am hugely excited to see FF in Scots and will do whatI can do to sort out gremlins, even though they appear to be on my loca. system only.

Huh. Those logs end unexpectedly early. I'm not really sure what to think of that. Maybe your updater is crashing? It's hard to say. There's kind of a lot of weird things going on here that I'm not currently able to explain.

It would be ideal if I could reproduce your problem on my machine so that I could debug it in-person. So far, unfortunately, I have been unable to do so. I wonder if the problem might be related to us having different operating system versions? Could you tell me what version and language of Windows you have installed? I'm hoping that if I use a virtual machine running the same version, I may be able to reproduce your problem.

Flags: needinfo?(ian.henderson)

Here is my version info:

Edition Windows 10 Pro
Version 20H2
Installed on ‎20/‎11/‎2020
OS build 19042.928
Experience Windows Feature Experience Pack 120.2212.551.0

I am running it with en-GB

Flags: needinfo?(ian.henderson)

Great, thank you. I'll report back when I've had a chance to try this out in a VM to see if I can reproduce the problem.

I had our tech team uninstall FF sco-GB Nightly. Install en-GB Nightly and update. Uninstall en-GB Nightly and install sco-GB Nightly and it seems to have worked.
Sorry for putting you to this trouble.

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED

Glad you were able to get it working, and thanks for letting us know!

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: