Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Templates updates sometimes fail, requiring manual dpkg --configure -a run #428

Open
eloquence opened this issue Jan 23, 2020 · 8 comments
Open

Templates updates sometimes fail, requiring manual dpkg --configure -a run #428

eloquence opened this issue Jan 23, 2020 · 8 comments
Labels
bug needs reproducing

Comments

@eloquence
Copy link
Member

eloquence commented Jan 23, 2020 •
edited
Loading

On both Nina's and my laptop, I've experienced template update failures (not reported by the cron job, but reported by the Qubes updater, or the script if run in a terminal), most recently in sd-proxy-buster-template. The failures were usually "easily" resolvable by running dpkg --configure -a in the impacted template, which the error message itself recommended.

In the most recent cases, it was due to the file /etc/machine-id being in a modified state (I've never edited or touched the file). These may have been leftover issues from the Whonix/Buster transition, but I'm filing it anyway in case we encounter it again or there's more that we can do to mitigate such issues.
@eloquence
Copy link
Member Author

All I did in the dpkg --configure -a run is to confirm that the existing version of the file can be used. Would it be possible to automate the answer to such prompts, so that updates don't fail in those cases?

@adrelanos
Copy link
Contributor

I am not sure what was modifying /etc/machine-id. Was discussed here:

https://forums.whonix.org/t/setting-up-anon-base-files-shows-machine-id-prompt-followed-by-ominous-warning/8045

And the this added:

https://github.com/Whonix/whonix-legacy/blob/master/debian/whonix-legacy.preinst

machine_id() {
   if [ -f "/var/lib/whonix/do_once/${FUNCNAME}_version_1" ]; then
      return 0
   fi

   local existing_machine_id shared_machine_id

   existing_machine_id="$(cat /etc/machine-id)"
   ## Same as anon-base-files.
   shared_machine_id="b08dfa6083e7567a1921a715000001fb"

   if [ "$existing_machine_id" = "$shared_machine_id" ]; then
      ## No need to touch it.
      true
   else
      echo "$shared_machine_id" > /etc/machine-id
   fi

   mkdir --parents "/var/lib/whonix/do_once"
   touch "/var/lib/whonix/do_once/${FUNCNAME}_version_1"
}

All I did in the dpkg --configure -a run is to confirm that the existing version of the file can be used. Would it be possible to automate the answer to such prompts, so that updates don't fail in those cases?

There is...

apt-get-noninteractive(8) -- wrapper to run apt-get non-interactively

https://github.com/Whonix/usability-misc/blob/master/usr/bin/apt-get-noninteractive

Would probably be easy to invent that for dpkg too. Let me know if you need that - then I could add that to Whonix/usability-misc.

adrelanos pushed a commit to adrelanos/usability-misc that referenced this issue Feb 13, 2020
add new usability wrapper /usr/bin/dpkg-noninteractive
b3d775e 
freedomofpress/securedrop-workstation#428
adrelanos pushed a commit to Kicksecure/usability-misc that referenced this issue Feb 13, 2020
add dpkg-noninteractive man page
e22135d 
freedomofpress/securedrop-workstation#428
@rocodes
Copy link
Contributor

rocodes commented Feb 25, 2020

Can report that I have experienced this as well and solved as above. If I encounter it again I will share more detailed information.

@conorsch conorsch mentioned this issue Feb 28, 2020
Closed
@sssoleileraaa
Copy link
Contributor

just fyi, ran into this updater issue twice today:

2020-05-20 17:00:52,880 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating sd-log-buster-template. Please contact your administrator.
2020-05-20 17:00:52,880 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'sd-log-buster-template', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 17:00:52,880 - sdw_updater_gui.Updater:386(apply_dom0_state) INFO: Applying dom0 state
2020-05-20 17:00:52,886 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 95%
2020-05-20 17:01:40,926 - sdw_updater_gui.Updater:392(apply_dom0_state) ERROR: Failed to dom0 state
2020-05-20 17:01:40,927 - sdw_updater_gui.Updater:393(apply_dom0_state) ERROR: Command '['sudo', 'qubesctl', '--show-output', 'state.highstate']' returned non-zero exit status 1
2020-05-20 17:01:40,927 - sdw_updater_gui.Updater:421(shutdown_and_start_vms) INFO: Shutting down SDW TemplateVMs for updates
2020-05-20 17:01:48,388 - sdw_updater_gui.Updater:425(shutdown_and_start_vms) INFO: Shutting down SDW AppVMs for updates
2020-05-20 17:02:11,007 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-usb
2020-05-20 17:02:16,357 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-whonix
2020-05-20 17:02:20,646 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-firewall
2020-05-20 17:02:25,849 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-net
2020-05-20 17:02:29,480 - sdw_updater_gui.Updater:449(shutdown_and_start_vms) INFO: Starting fedora-based system VMs after updates
2020-05-20 17:02:29,796 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-net: b'disp-mgmt-whonix-ws-15\ndom0\nwhonix-ws-15\n'
2020-05-20 17:02:43,546 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-firewall: b'disp-mgmt-whonix-ws-15\ndom0\nsys-net\nwhonix-ws-15\n'
2020-05-20 17:02:53,303 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-whonix: b'disp-mgmt-whonix-ws-15\ndom0\nsys-firewall\nsys-net\nwhonix-ws-15\n'
2020-05-20 17:03:01,839 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-usb: b'disp-mgmt-whonix-ws-15\ndom0\nsys-firewall\nsys-net\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:03:17,912 - sdw_updater_gui.Updater:453(shutdown_and_start_vms) INFO: Starting SDW VMs after updates
2020-05-20 17:03:18,816 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-log: b'disp-mgmt-whonix-ws-15\ndom0\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:03:47,218 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-gpg: b'disp-mgmt-whonix-ws-15\ndom0\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:04:13,748 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-whonix: b'disp-mgmt-whonix-ws-15\ndom0\nsd-gpg\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:04:25,768 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-proxy: b'disp-mgmt-whonix-ws-15\ndom0\nsd-gpg\nsd-log\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:04:57,120 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-app: b'dom0\nsd-gpg\nsd-log\nsd-proxy\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 17:05:22,704 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in sd-app
2020-05-20 17:05:27,513 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in dom0
2020-05-20 17:05:27,514 - sdw_updater_gui.UpdaterApp:130(upgrade_status) INFO: Signal: upgrade_status {'sd-log': <UpdateStatus.UPDATES_FAILED: '3'>, 'fedora': <UpdateStatus.UPDATES_OK: '0'>, 'apply_dom0': '3', 'recommended_action': <UpdateStatus.UPDATES_FAILED: '3'>}
2020-05-20 17:05:27,517 - sdw_updater_gui.UpdaterApp:153(upgrade_status) INFO: Error upgrading VMs

Running the following in sd-log fixed the issue:

sudo apt update
sudo dpkg --configure -a
sudo apt upgrade

@eloquence
Copy link
Member Author

Do you still have any output from the dpkg --configure -a run about which package required manual attn?

@sssoleileraaa
Copy link
Contributor

No I don't but here are the full logs for launcher.log today (I believe I only ran the updater twice and both times failed until i manually updated):

2020-05-20 15:18:04,778 - __main__:40(main) INFO: Starting SecureDrop Launcher
2020-05-20 15:18:04,829 - sdw_updater_gui.Updater:488(should_launch_updater) INFO: Update interval expired: launching updater.
2020-05-20 15:18:08,258 - sdw_updater_gui.UpdaterApp:58(__init__) INFO: Starting UpdateThread
2020-05-20 15:18:08,296 - sdw_updater_gui.Updater:53(check_all_updates) INFO: Checking for all updates
2020-05-20 15:18:08,297 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-viewer:sd-viewer-buster-template
2020-05-20 15:18:45,744 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-viewer-buster-template or cannot check for updates
2020-05-20 15:18:45,745 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-viewer-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:18:50,932 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-whonix:whonix-gw-15
2020-05-20 15:18:50,932 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 11%
2020-05-20 15:20:39,789 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for whonix-gw-15 or cannot check for updates
2020-05-20 15:20:39,789 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'whonix-gw-15', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:20:46,578 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-log:sd-log-buster-template
2020-05-20 15:20:46,579 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 22%
2020-05-20 15:21:16,383 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-log-buster-template or cannot check for updates
2020-05-20 15:21:16,384 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-log-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:21:21,695 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-gpg:securedrop-workstation-buster
2020-05-20 15:21:21,700 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 33%
2020-05-20 15:21:56,546 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for securedrop-workstation-buster or cannot check for updates
2020-05-20 15:21:56,547 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'securedrop-workstation-buster', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:22:02,159 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-devices:sd-devices-buster-template
2020-05-20 15:22:02,165 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 44%
2020-05-20 15:22:34,058 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-devices-buster-template or cannot check for updates
2020-05-20 15:22:34,058 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-devices-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:22:39,190 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 55%
2020-05-20 15:23:40,231 - sdw_updater_gui.Updater:105(_check_updates_dom0) INFO: dom0 is up to date
2020-05-20 15:23:40,231 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-proxy:sd-proxy-buster-template
2020-05-20 15:23:40,231 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 66%
2020-05-20 15:24:13,289 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-proxy-buster-template or cannot check for updates
2020-05-20 15:24:13,289 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-proxy-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:24:18,401 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-app:sd-app-buster-template
2020-05-20 15:24:18,402 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 77%
2020-05-20 15:24:18,413 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 88%
2020-05-20 15:24:48,146 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-app-buster-template or cannot check for updates
2020-05-20 15:24:48,146 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-app-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 15:24:53,438 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 100%
2020-05-20 15:24:53,439 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 1 in sd-app
2020-05-20 15:25:20,260 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 1 in dom0
2020-05-20 15:25:20,261 - sdw_updater_gui.UpdaterApp:70(update_status) INFO: Signal: update_status {'sd-viewer': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'recommended_action': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-app': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-gpg': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-devices': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'fedora': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-log': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-proxy': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'dom0': <UpdateStatus.UPDATES_OK: '0'>, 'sd-whonix': <UpdateStatus.UPDATES_REQUIRED: '1'>}
2020-05-20 15:25:20,262 - sdw_updater_gui.UpdaterApp:75(update_status) INFO: Updates required
2020-05-20 15:27:18,439 - sdw_updater_gui.UpdaterApp:194(apply_all_updates) INFO: Starting UpgradeThread
2020-05-20 15:27:18,441 - sdw_updater_gui.Updater:80(apply_updates) INFO: Applying all updates
2020-05-20 15:27:18,442 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-viewer:sd-viewer-buster-template
2020-05-20 15:30:32,457 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: sd-viewer-buster-template update successful
2020-05-20 15:30:32,457 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-app:sd-app-buster-template
2020-05-20 15:30:32,465 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 7%
2020-05-20 15:33:13,692 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: sd-app-buster-template update successful
2020-05-20 15:33:13,693 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-gpg:securedrop-workstation-buster
2020-05-20 15:33:13,698 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 20%
2020-05-20 15:36:17,547 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: securedrop-workstation-buster update successful
2020-05-20 15:36:17,548 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-devices:sd-devices-buster-template
2020-05-20 15:36:17,553 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 32%
2020-05-20 15:39:40,031 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating sd-devices-buster-template. Please contact your administrator.
2020-05-20 15:39:40,031 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'sd-devices-buster-template', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 15:39:40,031 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating fedora:fedora-30
2020-05-20 15:39:40,036 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 45%
2020-05-20 15:44:20,002 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: fedora-30 update successful
2020-05-20 15:44:20,002 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-log:sd-log-buster-template
2020-05-20 15:44:20,007 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 57%
2020-05-20 15:45:27,211 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating sd-log-buster-template. Please contact your administrator.
2020-05-20 15:45:27,212 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'sd-log-buster-template', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 15:45:27,212 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-proxy:sd-proxy-buster-template
2020-05-20 15:45:27,217 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 70%
2020-05-20 15:46:35,806 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating sd-proxy-buster-template. Please contact your administrator.
2020-05-20 15:46:35,806 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'sd-proxy-buster-template', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 15:46:35,806 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-whonix:whonix-gw-15
2020-05-20 15:46:35,811 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 82%
2020-05-20 15:47:30,723 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating whonix-gw-15. Please contact your administrator.
2020-05-20 15:47:30,723 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'whonix-gw-15', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 15:47:30,723 - sdw_updater_gui.Updater:386(apply_dom0_state) INFO: Applying dom0 state
2020-05-20 15:47:30,729 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 95%
2020-05-20 15:47:55,037 - sdw_updater_gui.Updater:389(apply_dom0_state) INFO: Dom0 state applied
2020-05-20 15:47:55,037 - sdw_updater_gui.Updater:421(shutdown_and_start_vms) INFO: Shutting down SDW TemplateVMs for updates
2020-05-20 15:47:56,087 - sdw_updater_gui.Updater:425(shutdown_and_start_vms) INFO: Shutting down SDW AppVMs for updates
2020-05-20 15:48:17,401 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-usb
2020-05-20 15:48:22,518 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-whonix
2020-05-20 15:48:26,638 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-firewall
2020-05-20 15:48:29,450 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-net
2020-05-20 15:48:32,213 - sdw_updater_gui.Updater:449(shutdown_and_start_vms) INFO: Starting fedora-based system VMs after updates
2020-05-20 15:48:32,372 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-net: b'dom0\n'
2020-05-20 15:48:44,395 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-firewall: b'dom0\nsys-net\n'
2020-05-20 15:48:52,271 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-whonix: b'dom0\nsys-firewall\nsys-net\n'
2020-05-20 15:48:59,949 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-usb: b'dom0\nsys-firewall\nsys-net\nsys-whonix\n'
2020-05-20 15:49:13,420 - sdw_updater_gui.Updater:453(shutdown_and_start_vms) INFO: Starting SDW VMs after updates
2020-05-20 15:49:14,009 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-log: b'dom0\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 15:49:35,570 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-gpg: b'dom0\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 15:49:55,108 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-whonix: b'dom0\nsd-gpg\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 15:50:03,553 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-proxy: b'dom0\nsd-gpg\nsd-log\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 15:50:30,257 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-app: b'dom0\nsd-gpg\nsd-log\nsd-proxy\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 15:50:54,202 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in sd-app
2020-05-20 15:50:58,484 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in dom0
2020-05-20 15:50:58,486 - sdw_updater_gui.UpdaterApp:130(upgrade_status) INFO: Signal: upgrade_status {'sd-viewer': <UpdateStatus.UPDATES_OK: '0'>, 'recommended_action': <UpdateStatus.UPDATES_FAILED: '3'>, 'sd-whonix': <UpdateStatus.UPDATES_FAILED: '3'>, 'apply_dom0': '0', 'sd-gpg': <UpdateStatus.UPDATES_OK: '0'>, 'sd-devices': <UpdateStatus.UPDATES_FAILED: '3'>, 'fedora': <UpdateStatus.UPDATES_OK: '0'>, 'sd-proxy': <UpdateStatus.UPDATES_FAILED: '3'>, 'sd-log': <UpdateStatus.UPDATES_FAILED: '3'>, 'sd-app': <UpdateStatus.UPDATES_OK: '0'>}
2020-05-20 15:50:58,492 - sdw_updater_gui.UpdaterApp:153(upgrade_status) INFO: Error upgrading VMs
2020-05-20 16:26:27,405 - __main__:40(main) INFO: Starting SecureDrop Launcher
2020-05-20 16:26:27,466 - sdw_updater_gui.Updater:514(should_launch_updater) INFO: Preceding update failed, launching updater.
2020-05-20 16:26:31,158 - sdw_updater_gui.UpdaterApp:58(__init__) INFO: Starting UpdateThread
2020-05-20 16:26:31,247 - sdw_updater_gui.Updater:53(check_all_updates) INFO: Checking for all updates
2020-05-20 16:26:31,247 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-viewer:sd-viewer-buster-template
2020-05-20 16:27:10,940 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-viewer-buster-template is up to date
2020-05-20 16:27:10,941 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-gpg:securedrop-workstation-buster
2020-05-20 16:27:10,945 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 11%
2020-05-20 16:27:42,136 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: securedrop-workstation-buster is up to date
2020-05-20 16:27:42,141 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 22%
2020-05-20 16:28:55,879 - sdw_updater_gui.Updater:105(_check_updates_dom0) INFO: dom0 is up to date
2020-05-20 16:28:55,880 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-whonix:whonix-gw-15
2020-05-20 16:28:55,884 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 33%
2020-05-20 16:29:14,389 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for whonix-gw-15 or cannot check for updates
2020-05-20 16:29:14,389 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'whonix-gw-15', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 16:29:19,008 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-log:sd-log-buster-template
2020-05-20 16:29:19,008 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 44%
2020-05-20 16:29:19,017 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 55%
2020-05-20 16:29:45,183 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-log-buster-template or cannot check for updates
2020-05-20 16:29:45,184 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-log-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 16:29:50,264 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-app:sd-app-buster-template
2020-05-20 16:29:50,269 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 66%
2020-05-20 16:30:22,516 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-app-buster-template is up to date
2020-05-20 16:30:22,516 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-devices:sd-devices-buster-template
2020-05-20 16:30:22,521 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 77%
2020-05-20 16:30:54,675 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-devices-buster-template is up to date
2020-05-20 16:30:54,675 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-proxy:sd-proxy-buster-template
2020-05-20 16:30:54,680 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 88%
2020-05-20 16:31:21,539 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-proxy-buster-template or cannot check for updates
2020-05-20 16:31:21,539 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-proxy-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 16:31:26,561 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 100%
2020-05-20 16:31:26,562 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 1 in sd-app
2020-05-20 16:31:49,929 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 1 in dom0
2020-05-20 16:31:49,930 - sdw_updater_gui.UpdaterApp:70(update_status) INFO: Signal: update_status {'recommended_action': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-viewer': <UpdateStatus.UPDATES_OK: '0'>, 'dom0': <UpdateStatus.UPDATES_OK: '0'>, 'sd-whonix': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'fedora': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-gpg': <UpdateStatus.UPDATES_OK: '0'>, 'sd-log': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-devices': <UpdateStatus.UPDATES_OK: '0'>, 'sd-app': <UpdateStatus.UPDATES_OK: '0'>, 'sd-proxy': <UpdateStatus.UPDATES_REQUIRED: '1'>}
2020-05-20 16:31:49,930 - sdw_updater_gui.UpdaterApp:75(update_status) INFO: Updates required
2020-05-20 16:36:04,320 - sdw_updater_gui.UpdaterApp:194(apply_all_updates) INFO: Starting UpgradeThread
2020-05-20 16:36:04,323 - sdw_updater_gui.Updater:80(apply_updates) INFO: Applying all updates
2020-05-20 16:36:04,323 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-whonix:whonix-gw-15
2020-05-20 16:37:54,748 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: whonix-gw-15 update successful
2020-05-20 16:37:54,749 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating fedora:fedora-30
2020-05-20 16:37:54,754 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 20%
2020-05-20 16:39:09,209 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: fedora-30 update successful
2020-05-20 16:39:09,209 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-log:sd-log-buster-template
2020-05-20 16:39:09,214 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 45%
2020-05-20 16:41:28,633 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating sd-log-buster-template. Please contact your administrator.
2020-05-20 16:41:28,634 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'sd-log-buster-template', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 16:41:28,634 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-proxy:sd-proxy-buster-template
2020-05-20 16:41:28,639 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 70%
2020-05-20 16:44:10,315 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: sd-proxy-buster-template update successful
2020-05-20 16:44:10,315 - sdw_updater_gui.Updater:386(apply_dom0_state) INFO: Applying dom0 state
2020-05-20 16:44:10,321 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 95%
2020-05-20 16:44:35,285 - sdw_updater_gui.Updater:389(apply_dom0_state) INFO: Dom0 state applied
2020-05-20 16:44:35,285 - sdw_updater_gui.Updater:421(shutdown_and_start_vms) INFO: Shutting down SDW TemplateVMs for updates
2020-05-20 16:44:36,332 - sdw_updater_gui.Updater:425(shutdown_and_start_vms) INFO: Shutting down SDW AppVMs for updates
2020-05-20 16:44:57,042 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-usb
2020-05-20 16:45:01,673 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-whonix
2020-05-20 16:45:05,752 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-firewall
2020-05-20 16:45:08,469 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-net
2020-05-20 16:45:10,950 - sdw_updater_gui.Updater:449(shutdown_and_start_vms) INFO: Starting fedora-based system VMs after updates
2020-05-20 16:45:11,123 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-net: b'dom0\n'
2020-05-20 16:45:23,331 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-firewall: b'dom0\nsys-net\n'
2020-05-20 16:45:31,532 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-whonix: b'dom0\nsys-firewall\nsys-net\n'
2020-05-20 16:45:39,420 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-usb: b'dom0\nsys-firewall\nsys-net\nsys-whonix\n'
2020-05-20 16:45:52,769 - sdw_updater_gui.Updater:453(shutdown_and_start_vms) INFO: Starting SDW VMs after updates
2020-05-20 16:45:53,375 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-log: b'dom0\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 16:46:15,952 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-gpg: b'dom0\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 16:46:37,182 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-whonix: b'dom0\nsd-gpg\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 16:46:45,987 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-proxy: b'dom0\nsd-gpg\nsd-log\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 16:47:09,449 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-app: b'dom0\nsd-gpg\nsd-log\nsd-proxy\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 16:47:34,273 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in sd-app
2020-05-20 16:47:38,873 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in dom0
2020-05-20 16:47:38,874 - sdw_updater_gui.UpdaterApp:130(upgrade_status) INFO: Signal: upgrade_status {'recommended_action': <UpdateStatus.UPDATES_FAILED: '3'>, 'sd-whonix': <UpdateStatus.UPDATES_OK: '0'>, 'fedora': <UpdateStatus.UPDATES_OK: '0'>, 'apply_dom0': '0', 'sd-log': <UpdateStatus.UPDATES_FAILED: '3'>, 'sd-proxy': <UpdateStatus.UPDATES_OK: '0'>}
2020-05-20 16:47:38,879 - sdw_updater_gui.UpdaterApp:153(upgrade_status) INFO: Error upgrading VMs
2020-05-20 16:53:10,674 - __main__:40(main) INFO: Starting SecureDrop Launcher
2020-05-20 16:53:10,677 - sdw_updater_gui.Updater:514(should_launch_updater) INFO: Preceding update failed, launching updater.
2020-05-20 16:53:10,743 - sdw_updater_gui.UpdaterApp:58(__init__) INFO: Starting UpdateThread
2020-05-20 16:53:10,744 - sdw_updater_gui.Updater:53(check_all_updates) INFO: Checking for all updates
2020-05-20 16:54:05,568 - sdw_updater_gui.Updater:105(_check_updates_dom0) INFO: dom0 is up to date
2020-05-20 16:54:05,569 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-viewer:sd-viewer-buster-template
2020-05-20 16:54:05,569 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 11%
2020-05-20 16:54:05,583 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 22%
2020-05-20 16:54:43,131 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-viewer-buster-template is up to date
2020-05-20 16:54:43,132 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-app:sd-app-buster-template
2020-05-20 16:54:43,137 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 33%
2020-05-20 16:55:18,463 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-app-buster-template is up to date
2020-05-20 16:55:18,464 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-log:sd-log-buster-template
2020-05-20 16:55:18,469 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 44%
2020-05-20 16:55:48,707 - sdw_updater_gui.Updater:141(_check_updates_debian) ERROR: Updates required for sd-log-buster-template or cannot check for updates
2020-05-20 16:55:48,708 - sdw_updater_gui.Updater:144(_check_updates_debian) ERROR: Command '['qvm-run', 'sd-log-buster-template', '[[ $(apt list --upgradable | wc -l) -eq 1 ]]']' returned non-zero exit status 1
2020-05-20 16:55:53,827 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-proxy:sd-proxy-buster-template
2020-05-20 16:55:53,828 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 55%
2020-05-20 16:56:29,344 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-proxy-buster-template is up to date
2020-05-20 16:56:29,344 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-whonix:whonix-gw-15
2020-05-20 16:56:29,350 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 66%
2020-05-20 16:56:55,384 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: whonix-gw-15 is up to date
2020-05-20 16:56:55,384 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-gpg:securedrop-workstation-buster
2020-05-20 16:56:55,389 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 77%
2020-05-20 16:57:30,905 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: securedrop-workstation-buster is up to date
2020-05-20 16:57:30,906 - sdw_updater_gui.Updater:129(_check_updates_debian) INFO: Checking for updates sd-devices:sd-devices-buster-template
2020-05-20 16:57:30,911 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 88%
2020-05-20 16:58:06,709 - sdw_updater_gui.Updater:152(_check_updates_debian) INFO: sd-devices-buster-template is up to date
2020-05-20 16:58:06,710 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 100%
2020-05-20 16:58:06,710 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 1 in sd-app
2020-05-20 16:58:06,876 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 1 in dom0
2020-05-20 16:58:06,877 - sdw_updater_gui.UpdaterApp:70(update_status) INFO: Signal: update_status {'dom0': <UpdateStatus.UPDATES_OK: '0'>, 'fedora': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-viewer': <UpdateStatus.UPDATES_OK: '0'>, 'sd-app': <UpdateStatus.UPDATES_OK: '0'>, 'sd-log': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'recommended_action': <UpdateStatus.UPDATES_REQUIRED: '1'>, 'sd-proxy': <UpdateStatus.UPDATES_OK: '0'>, 'sd-whonix': <UpdateStatus.UPDATES_OK: '0'>, 'sd-gpg': <UpdateStatus.UPDATES_OK: '0'>, 'sd-devices': <UpdateStatus.UPDATES_OK: '0'>}
2020-05-20 16:58:06,877 - sdw_updater_gui.UpdaterApp:75(update_status) INFO: Updates required
2020-05-20 16:59:00,837 - sdw_updater_gui.UpdaterApp:194(apply_all_updates) INFO: Starting UpgradeThread
2020-05-20 16:59:00,843 - sdw_updater_gui.Updater:80(apply_updates) INFO: Applying all updates
2020-05-20 16:59:00,843 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating fedora:fedora-30
2020-05-20 17:00:25,560 - sdw_updater_gui.Updater:202(_apply_updates_vm) INFO: fedora-30 update successful
2020-05-20 17:00:25,560 - sdw_updater_gui.Updater:181(_apply_updates_vm) INFO: Updating sd-log:sd-log-buster-template
2020-05-20 17:00:25,570 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 45%
2020-05-20 17:00:52,880 - sdw_updater_gui.Updater:197(_apply_updates_vm) ERROR: An error has occurred updating sd-log-buster-template. Please contact your administrator.
2020-05-20 17:00:52,880 - sdw_updater_gui.Updater:200(_apply_updates_vm) ERROR: Command '['sudo', 'qubesctl', '--skip-dom0', '--targets', 'sd-log-buster-template', 'state.sls', 'update.qubes-vm']' returned non-zero exit status 20
2020-05-20 17:00:52,880 - sdw_updater_gui.Updater:386(apply_dom0_state) INFO: Applying dom0 state
2020-05-20 17:00:52,886 - sdw_updater_gui.UpdaterApp:173(update_progress_bar) INFO: Signal: Progress 95%
2020-05-20 17:01:40,926 - sdw_updater_gui.Updater:392(apply_dom0_state) ERROR: Failed to dom0 state
2020-05-20 17:01:40,927 - sdw_updater_gui.Updater:393(apply_dom0_state) ERROR: Command '['sudo', 'qubesctl', '--show-output', 'state.highstate']' returned non-zero exit status 1
2020-05-20 17:01:40,927 - sdw_updater_gui.Updater:421(shutdown_and_start_vms) INFO: Shutting down SDW TemplateVMs for updates
2020-05-20 17:01:48,388 - sdw_updater_gui.Updater:425(shutdown_and_start_vms) INFO: Shutting down SDW AppVMs for updates
2020-05-20 17:02:11,007 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-usb
2020-05-20 17:02:16,357 - sdw_updater_gui.Updater:433(shutdown_and_start_vms) INFO: Safely shutting down system VM: sys-whonix
2020-05-20 17:02:20,646 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-firewall
2020-05-20 17:02:25,849 - sdw_updater_gui.Updater:440(shutdown_and_start_vms) INFO: Killing system VM: sys-net
2020-05-20 17:02:29,480 - sdw_updater_gui.Updater:449(shutdown_and_start_vms) INFO: Starting fedora-based system VMs after updates
2020-05-20 17:02:29,796 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-net: b'disp-mgmt-whonix-ws-15\ndom0\nwhonix-ws-15\n'
2020-05-20 17:02:43,546 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-firewall: b'disp-mgmt-whonix-ws-15\ndom0\nsys-net\nwhonix-ws-15\n'
2020-05-20 17:02:53,303 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-whonix: b'disp-mgmt-whonix-ws-15\ndom0\nsys-firewall\nsys-net\nwhonix-ws-15\n'
2020-05-20 17:03:01,839 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sys-usb: b'disp-mgmt-whonix-ws-15\ndom0\nsys-firewall\nsys-net\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:03:17,912 - sdw_updater_gui.Updater:453(shutdown_and_start_vms) INFO: Starting SDW VMs after updates
2020-05-20 17:03:18,816 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-log: b'disp-mgmt-whonix-ws-15\ndom0\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:03:47,218 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-gpg: b'disp-mgmt-whonix-ws-15\ndom0\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:04:13,748 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-whonix: b'disp-mgmt-whonix-ws-15\ndom0\nsd-gpg\nsd-log\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:04:25,768 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-proxy: b'disp-mgmt-whonix-ws-15\ndom0\nsd-gpg\nsd-log\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\nwhonix-ws-15\n'
2020-05-20 17:04:57,120 - sdw_updater_gui.Updater:473(_safely_start_vm) INFO: VMs running before start of sd-app: b'dom0\nsd-gpg\nsd-log\nsd-proxy\nsd-whonix\nsys-firewall\nsys-net\nsys-usb\nsys-whonix\n'
2020-05-20 17:05:22,704 - sdw_updater_gui.Updater:248(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in sd-app
2020-05-20 17:05:27,513 - sdw_updater_gui.Updater:261(_write_updates_status_flag_to_disk) INFO: Setting update flag to 3 in dom0
2020-05-20 17:05:27,514 - sdw_updater_gui.UpdaterApp:130(upgrade_status) INFO: Signal: upgrade_status {'sd-log': <UpdateStatus.UPDATES_FAILED: '3'>, 'fedora': <UpdateStatus.UPDATES_OK: '0'>, 'apply_dom0': '3', 'recommended_action': <UpdateStatus.UPDATES_FAILED: '3'>}
2020-05-20 17:05:27,517 - sdw_updater_gui.UpdaterApp:153(upgrade_status) INFO: Error upgrading VMs

I also looked for 'error' or 'fail' in /var/log/apt/history.log and /var/log/dpkg.log and saw nothing. Manually scanning through these very exciting logs confirmed that they look normal.

@zenmonkeykstop
Copy link
Contributor

@eaon saw this issue recently during a Whonix update, so it's still a thing - leaving open for now

@zenmonkeykstop
Copy link
Contributor

Needs reproducing on Qubes 4.2.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug needs reproducing
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
5 participants
@eloquence @adrelanos @zenmonkeykstop @sssoleileraaa @rocodes