sys-whonix (sdwdate) not properly resuming from suspend

Qubes-Whonix
1

Hello there,

Qubes 4.1.
sdwdate: 3:19.7-1

With all updates installed under whonix-gw-16, when the system wakes up from suspend, sys-whonix keeps a whole core at 100% CPU usage and Terminal opened prior of going into suspend are unresponsive. sdwdate-gui tor-control-panel can be opened, but trying to Restart Tor or any action happening there will freeze that window as well.

If I keep open a terminal to have top running, it doesn’t update anymore and timestamp is stuck to the time system suspended.

If I launch qvm-run --no-gui --pass-io sys-whonix "ps fauxwwww" I see that sdwdate’s invocation of tor-circuit-established-check is consuming more and more memory (90%) and then swapping is occuring, even though its parent is launching it through timeout --kill-after=5s, which never occurs. xen-balloning and systemd --user are also eating a lot of CPU. Traffic is not routed through tor.

Note that qvm-run calls are not always successful either, and sudo killall sdwdate or sudo systemctl stop sdwdate doesn’t reestablish windows availability, even though the processes disappeared.

Any debugging insights here?

Reinstalling whonix-gw-16 through qvm-template reinstall whonix-gw-16 doesn’t show behavior, which is deploying sdwdate: 3:19.1-1. Upgrading whonix-gw-16 to latest exhibits those behaviors again. Not sure the problem is directly linked to sdwdate.

Cloned whonix-gw-16 to whonix-gw-16_latest. Now trying to downgrade some packages to isolate the problem source.

1 Like
2

under sys-whonix:

echo xen > /sys/devices/system/clocksource/clocksource0/current_clocksource

Fixes the issue. Troubleshooted with @marmarek.

tsc is not a right clock source for the moment. To have tsc right, some tasks need to be done first:

Whonix wanted to get rid of xen clock source:

https://phabricator.whonix.org/T389

But for the moment, putting xen as exclusive clocksource would be the transient solution.

1 Like
3

The current issue also affects sys-firewall and sys-net, but after the first boot issue, available clocksources do not contain tsc anymore, only xen.

So the issue happens only on first resume path of each boot. Maybe that is why not everyone is getting crazy on this issue?

1 Like
4

But even on first resume, it doesn’t result in swapping / 100%CPU in sys-firewall, right? There must be some specific interaction with Whonix that makes it this bad (my bet is on sdwdate).

I think it should be fixed with Properly suspend all VMs, not only those with PCI devices by marmarek · Pull Request #473 · QubesOS/qubes-core-admin · GitHub, but there are a couple of prerequisites for this change (see PR description). In the meantime, I think it’s time to revert to clocksource=xen.

1 Like
5

Nope. Timestamps under sys-whonix are litterally frozen for 10 minutes on resume.

sys-firewall log extract for timestamps:

Jun 10 15:39:12 sys-firewall qrexec-agent[1083]: 2022-06-10 15:39:12.552 qrexec-agent[1083]: qrexec-agent-data.c:244:handle_new_process_common: executed: root:QUBESRPC qubes.SuspendPreAll dom0 (pid 1085)
Jun 10 15:39:22 sys-firewall qrexec-agent[1131]: 2022-06-10 15:39:22.151 qrexec-agent[1131]: qrexec-agent-data.c:244:handle_new_process_common: executed: root:QUBESRPC qubes.SuspendPostAll dom0 (pid 1133)
Jun 10 15:39:55 sys-firewall audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-sync-time comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 10 15:39:55 sys-firewall kernel: audit: type=1131 audit(1654889995.059:150): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=qubes-sync-time comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 10 15:39:55 sys-firewall kernel: clocksource: timekeeping watchdog on CPU0: Marking clocksource 'tsc' as unstable because the skew is too large:
Jun 10 15:39:55 sys-firewall kernel: clocksource:                       'xen' wd_now: 6eabada90a7 wd_last: 6e76127152d mask: ffffffffffffffff
Jun 10 15:39:55 sys-firewall kernel: clocksource:                       'tsc' cs_now: fffffefc75a01aa2 cs_last: 10ca9accd19 mask: ffffffffffffffff
Jun 10 15:39:55 sys-firewall kernel: tsc: Marking TSC unstable due to clocksource watchdog
Jun 10 15:39:55 sys-firewall kernel: clocksource: Checking clocksource tsc synchronization from CPU 1.
Jun 10 15:39:55 sys-firewall kernel: clocksource: Override clocksource tsc is unstable and not HRT compatible - cannot switch while in HRT/NOHZ mode
Jun 10 15:39:55 sys-firewall kernel: clocksource: Switched to clocksource xen

sys-whonix:

Jun 10 17:38:56 host qrexec-agent[2084]: 2022-06-10 17:38:56.129 qrexec-agent[2084]: qrexec-agent-data.c:244:handle_new_process_common: executed: root:QUBESRPC qubes.SuspendPreAll dom0 (pid 2086)
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + set -e
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + '[' -f /run/qubes/this-is-templatevm ']'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ id -u
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + '[' '!' 0 = 0 ']'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + command -v qubesdb-read
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + true
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + mkdir --parents /run/sdwdate
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + chown --recursive sdwdate:sdwdate /run/sdwdate
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemctl --no-pager status sdwdate
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Creating /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Creating /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Creating /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Creating /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host suspend-pre[2123]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Creating /run/sdwdate/sdwdate_was_running.status...
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + touch /run/sdwdate/sdwdate_was_running.status
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Done, created /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, created /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, created /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, created /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host suspend-pre[2127]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, created /run/sdwdate/sdwdate_was_running.status.
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Stopping sdwdate...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Stopping sdwdate...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Stopping sdwdate...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Stopping sdwdate...'
Jun 10 17:38:56 host suspend-pre[2130]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Stopping sdwdate...
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemctl --no-pager stop sdwdate
Jun 10 17:38:56 host systemd[1]: Stopping Secure Distributed Web Date...
Jun 10 17:38:56 host sdwdate[823]: 2022-06-10 17:38:56 - sdwdate - INFO - Signal SIGTERM received.
Jun 10 17:38:56 host sdwdate[823]: 2022-06-10 17:38:56 - sdwdate - INFO - Exiting with exit_code '143' because or reason 'signal_handler called'.
Jun 10 17:38:56 host sdwdate[823]: 2022-06-10 17:38:56 - sdwdate - INFO - sdwdate stopped by user or system.
Jun 10 17:38:56 host sdwdate[823]: 2022-06-10 17:38:56 - sdwdate - INFO - sclockadj process not running, ok.
Jun 10 17:38:56 host sdwdate[823]: 2022-06-10 17:38:56 - sdwdate - INFO - Terminated sleep process.
Jun 10 17:38:56 host sdwdate[823]: 2022-06-10 17:38:56 - sdwdate - INFO - End.
Jun 10 17:38:56 host systemd[1]: sdwdate.service: Succeeded.
Jun 10 17:38:56 host systemd[1]: Stopped Secure Distributed Web Date.
Jun 10 17:38:56 host systemd[1]: sdwdate.service: Consumed 17.836s CPU time.
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Done, stopped sdwdate.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, stopped sdwdate.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, stopped sdwdate.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, stopped sdwdate.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host suspend-pre[2136]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, stopped sdwdate.
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Deleting /run/sdwdate/first_success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/first_success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/first_success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/first_success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host suspend-pre[2139]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/first_success...
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + rm --force /run/sdwdate/first_success
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Done, deleted /run/sdwdate/first_success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/first_success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/first_success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/first_success.'
Jun 10 17:38:56 host suspend-pre[2143]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/first_success.
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Deleting /run/sdwdate/success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/success...'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/success...'
Jun 10 17:38:56 host suspend-pre[2146]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Deleting /run/sdwdate/success...
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + rm --force /run/sdwdate/success
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Done, deleted /run/sdwdate/success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/success.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host suspend-pre[2150]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, deleted /run/sdwdate/success.
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + command -v whonix_firewall
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Entering Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Entering Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Entering Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Entering Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:56 host suspend-pre[2153]: 2022-06-10 17:38:56 - /usr/libexec/sdwdate/suspend-pre - INFO - Entering Whonix firewall timesync-fail-closed mode.
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + firewall_mode=timesync-fail-closed
Jun 10 17:38:56 host qubes.SuspendPreAll-dom0[2113]: + whonix_firewall
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: + output_cmd 'INFO - Done, entered Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: ++ date_cmd
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: ++ date --utc '+%Y-%m-%d %T'
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: + msg='2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, entered Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, entered Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: + systemd-cat --identifier=suspend-pre
Jun 10 17:38:57 host qubes.SuspendPreAll-dom0[2113]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, entered Whonix firewall timesync-fail-closed mode.'
Jun 10 17:38:57 host suspend-pre[2290]: 2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-pre - INFO - Done, entered Whonix firewall timesync-fail-closed mode.
Jun 10 17:38:57 host qrexec-agent[2086]: pam_unix(qrexec:session): session closed for user root
Jun 10 17:38:57 host systemd[1]: session-c3.scope: Succeeded.
Jun 10 17:38:57 host qrexec-agent[2084]: 2022-06-10 17:38:57.430 qrexec-agent[2084]: qrexec-agent-data.c:272:handle_new_process_common: pid 2086 exited with 0
Jun 10 17:38:57 host sudo[2362]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot 0 -f
Jun 10 17:38:57 host qrexec-agent[2291]: 2022-06-10 17:38:57.614 qrexec-agent[2291]: qrexec-agent-data.c:244:handle_new_process_common: executed: root:QUBESRPC qubes.SuspendPostAll dom0 (pid 2293)
Jun 10 17:38:57 host qrexec-agent[2293]: pam_unix(qrexec:session): session opened for user root(uid=0) by (uid=0)
Jun 10 17:38:57 host sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2394]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot 0
Jun 10 17:38:57 host sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2394]: pam_unix(sudo:session): session closed for user root
Jun 10 17:38:57 host systemd[1]: Started Session c4 of user root.
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + set -e
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + '[' -f /run/qubes/this-is-templatevm ']'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ id -u
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + '[' '!' 0 = 0 ']'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + command -v qubesdb-read
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + true
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + mkdir --parents /run/sdwdate
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + chown --recursive sdwdate:sdwdate /run/sdwdate
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + test -f /run/sdwdate/sdwdate_was_running.status
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + output_cmd 'INFO - Deleting /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date_cmd
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date -u '+%Y-%m-%d %T'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + msg='2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Deleting /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Deleting /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + systemd-cat --identifier=suspend-post
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Deleting /run/sdwdate/sdwdate_was_running.status...'
Jun 10 17:38:57 host suspend-post[2313]: 2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Deleting /run/sdwdate/sdwdate_was_running.status...
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + rm --force /run/sdwdate/sdwdate_was_running.status
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + output_cmd 'INFO - Done, deleted /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date_cmd
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date -u '+%Y-%m-%d %T'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + msg='2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, deleted /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, deleted /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + systemd-cat --identifier=suspend-post
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, deleted /run/sdwdate/sdwdate_was_running.status.'
Jun 10 17:38:57 host suspend-post[2317]: 2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, deleted /run/sdwdate/sdwdate_was_running.status.
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + output_cmd 'INFO - Running sdwdate-clock-jump...'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date_cmd
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date -u '+%Y-%m-%d %T'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + msg='2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Running sdwdate-clock-jump...'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Running sdwdate-clock-jump...'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + systemd-cat --identifier=suspend-post
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Running sdwdate-clock-jump...'
Jun 10 17:38:57 host suspend-post[2320]: 2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Running sdwdate-clock-jump...
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + sdwdate-clock-jump
Jun 10 17:38:57 host sudo[2327]:     root : PWD=/root ; USER=sdwdate ; COMMAND=/usr/bin/touch /run/sdwdate/clock_jump_do_once
Jun 10 17:38:57 host sudo[2327]: pam_unix(sudo:session): session opened for user sdwdate(uid=111) by (uid=0)
Jun 10 17:38:57 host sudo[2327]: pam_unix(sudo:session): session closed for user sdwdate
Jun 10 17:38:57 host systemd[1]: Starting Secure Distributed Web Date...
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + output_cmd 'INFO - Done, with sdwdate-clock-jump.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date_cmd
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: ++ date -u '+%Y-%m-%d %T'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + msg='2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, with sdwdate-clock-jump.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, with sdwdate-clock-jump.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + echo '2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, with sdwdate-clock-jump.'
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + systemd-cat --identifier=suspend-post
Jun 10 17:38:57 host suspend-post[2333]: 2022-06-10 17:38:57 - /usr/libexec/sdwdate/suspend-post - INFO - Done, with sdwdate-clock-jump.
Jun 10 17:38:57 host qubes.SuspendPostAll-dom0[2304]: + exit 0
Jun 10 17:38:57 host systemd[1]: Condition check resulted in Update time from ClockVM being skipped.
Jun 10 17:38:57 host qrexec-agent[2293]: pam_unix(qrexec:session): session closed for user root
Jun 10 17:38:57 host qrexec-agent[2291]: 2022-06-10 17:38:57.614 qrexec-agent[2291]: qrexec-agent-data.c:272:handle_new_process_common: pid 2293 exited with 0
Jun 10 17:38:57 host sdwdate[2330]: 2022-06-10 17:38:57 - sdwdate - INFO - sdwdate started. PID: 2330
Jun 10 17:38:57 host systemd[1]: Started Secure Distributed Web Date.
Jun 10 17:38:57 host sdwdate[2330]: 2022-06-10 17:38:57 - sdwdate - INFO - Tor socks host: 127.0.0.1 Tor socks port: 9108
Jun 10 17:38:57 host sdwdate[2330]: 2022-06-10 17:38:57 - sdwdate - INFO - Running sdwdate main loop. iteration: 1 / 10000
Jun 10 17:38:57 host tinyproxy[782]: Proxying refused on filtered domain "127.0.0.1"
Jun 10 17:38:57 host sudo[2410]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot 0
Jun 10 17:38:57 host sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2410]: pam_unix(sudo:session): session closed for user root
Jun 10 17:38:57 host sudo[2456]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot 0
Jun 10 17:38:57 host sudo[2456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2456]: pam_unix(sudo:session): session closed for user root
Jun 10 17:38:57 host sudo[2486]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot 1
Jun 10 17:38:57 host sudo[2486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2486]: pam_unix(sudo:session): session closed for user root
Jun 10 17:38:57 host sudo[2502]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot 1
Jun 10 17:38:57 host sudo[2502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2502]: pam_unix(sudo:session): session closed for user root
Jun 10 17:38:57 host sudo[2518]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl --boot -1
Jun 10 17:38:57 host sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:38:57 host sudo[2518]: pam_unix(sudo:session): session closed for user root
Jun 10 17:38:58 host kernel: clocksource: timekeeping watchdog on CPU0: Marking clocksource 'tsc' as unstable because the skew is too large:
Jun 10 17:38:58 host kernel: clocksource:                       'xen' wd_now: f9d4b2a3a9 wd_last: 69052de169 mask: ffffffffffffffff
Jun 10 17:38:58 host kernel: clocksource:                       'tsc' cs_now: 8ab2519683 cs_last: 8a5c9b64e0 mask: ffffffffffffffff
Jun 10 17:38:58 host kernel: tsc: Marking TSC unstable due to clocksource watchdog
Jun 10 17:38:58 host kernel: clocksource: Checking clocksource tsc synchronization from CPU 0.
Jun 10 17:38:58 host kernel: clocksource: Override clocksource tsc is unstable and not HRT compatible - cannot switch while in HRT/NOHZ mode
Jun 10 17:38:58 host kernel: clocksource: Switched to clocksource xen
Jun 10 17:38:57 host systemd[1]: session-c4.scope: Succeeded.
Jun 10 17:39:08 host sdwdate[2330]: 2022-06-10 17:39:08 - sdwdate - INFO - PREPARATION:
Jun 10 17:39:08 host sdwdate[2330]: 2022-06-10 17:39:08 - sdwdate - INFO -
Jun 10 17:39:08 host sdwdate[2330]: __ ### START: ### /usr/libexec/helper-scripts/onion-time-pre-script
Jun 10 17:39:08 host sdwdate[2330]: __ Status: Subsequent run after boot.
Jun 10 17:39:08 host sdwdate[2330]: __ Static Time Sanity Check: Within minimum time 'Fri Feb 18 00:00:00 UTC 2022' and expiration timestamp 'Tue May 17 10:00:00 UTC 2033', ok.
Jun 10 17:39:08 host sdwdate[2330]: __ Tor Bootstrap Result:
Jun 10 17:39:08 host sdwdate[2330]: check_bootstrap_helper_script: /usr/bin/tor-circuit-established-check
Jun 10 17:39:08 host sdwdate[2330]: tor_bootstrap_timeout_type:
Jun 10 17:39:08 host sdwdate[2330]: tor_circuit_established_check_exit_code: 124
Jun 10 17:39:08 host sdwdate[2330]: __ ### END: ### Exiting with exit_code '1' indicating 'wait, show error icon and retry.'.
Jun 10 17:39:08 host sdwdate[2330]: 2022-06-10 17:39:08 - sdwdate - INFO - PREPARATION RESULT: (known) ERROR.
Jun 10 17:39:08 host sdwdate[2330]: 2022-06-10 17:39:08 - sdwdate - INFO -
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - PREPARATION:
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - __ ### START: ### /usr/libexec/helper-scripts/onion-time-pre-script
Jun 10 17:39:10 host sdwdate[2330]: __ Status: Subsequent run after boot.
Jun 10 17:39:10 host sdwdate[2330]: __ Static Time Sanity Check: Within minimum time 'Fri Feb 18 00:00:00 UTC 2022' and expiration timestamp 'Tue May 17 10:00:00 UTC 2033', ok.
Jun 10 17:39:10 host sdwdate[2330]: __ Tor reports: NOTICE BOOTSTRAP PROGRESS=100 TAG=done SUMMARY="Done"
Jun 10 17:39:10 host sdwdate[2330]: __ Tor circuit: established
Jun 10 17:39:10 host sdwdate[2330]: __ Tor Consensus Time Sanity Check: Clock within consensus parameters consensus/valid-after 2022-06-10 17:00:00 and consensus/valid-until 2022-06-10 20:00:00.
Jun 10 17:39:10 host sdwdate[2330]: __ Conclusion: Tor already reports circuit established.
Jun 10 17:39:10 host sdwdate[2330]: __ ### END: ### Exiting with exit_code '0' indicating 'success'.
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - PREPARATION RESULT: SUCCESS.
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO -
Jun 10 17:39:10 host systemd[1]: Stopping User Manager for UID 0...
Jun 10 17:39:10 host systemd[2088]: Stopped target Main User Target.
Jun 10 17:39:10 host systemd[2088]: usertest.service: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Stopped User Test Systemd Unit File.
Jun 10 17:39:10 host systemd[2088]: Stopped target Basic System.
Jun 10 17:39:10 host systemd[2088]: Stopped target Paths.
Jun 10 17:39:10 host systemd[2088]: Stopped target Sockets.
Jun 10 17:39:10 host systemd[2088]: Stopped target Timers.
Jun 10 17:39:10 host systemd[2088]: dirmngr.socket: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Closed GnuPG network certificate management daemon.
Jun 10 17:39:10 host systemd[2088]: gpg-agent-browser.socket: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Jun 10 17:39:10 host systemd[2088]: gpg-agent-extra.socket: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Jun 10 17:39:10 host systemd[2088]: gpg-agent-ssh.socket: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Jun 10 17:39:10 host systemd[2088]: gpg-agent.socket: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Closed GnuPG cryptographic agent and passphrase cache.
Jun 10 17:39:10 host systemd[2088]: Removed slice User Application Slice.
Jun 10 17:39:10 host systemd[2088]: Reached target Shutdown.
Jun 10 17:39:10 host systemd[2088]: systemd-exit.service: Succeeded.
Jun 10 17:39:10 host systemd[2088]: Finished Exit the Session.
Jun 10 17:39:10 host systemd[2088]: Reached target Exit the Session.
Jun 10 17:39:10 host systemd[1]: user@0.service: Succeeded.
Jun 10 17:39:10 host systemd[1]: Stopped User Manager for UID 0.
Jun 10 17:39:10 host systemd[1]: Stopping User Runtime Directory /run/user/0...
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - Initial time fetching in progress...
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - Running sdwdate fetch loop. iteration: 1
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - pool 0: pool_size: 18 url_index: 10 already_picked_number: 1 already_picked_index: [10]
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - pool 1: pool_size: 18 url_index: 5 already_picked_number: 1 already_picked_index: [5]
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - pool 2: pool_size: 25 url_index: 17 already_picked_number: 1 already_picked_index: [17]
Jun 10 17:39:10 host sdwdate[2330]: 2022-06-10 17:39:10 - sdwdate - INFO - requested urls ['http://stormwayszuh4juycoy4kwoww5gvcu2c4tdtpkup667pdwe4qenzwayd.onion', 'https://27m3p2uv7igmj6kvd4ql3cct5h3sdwrsajovkkndeufumzyfhlfev4qd.onion', 'http://xpxduj55x2j27l2qytu2tcetykyfxbjbafin3x4i3ywddzphkbrd3jyd.onion']
Jun 10 17:39:10 host sdwdate[2330]: remote_times.py: url_to_unixtime_command (s):
Jun 10 17:39:10 host sdwdate[2330]: url_to_unixtime 127.0.0.1 9108 http://stormwayszuh4juycoy4kwoww5gvcu2c4tdtpkup667pdwe4qenzwayd.onion 80 true
Jun 10 17:39:10 host sdwdate[2330]: url_to_unixtime 127.0.0.1 9108 https://27m3p2uv7igmj6kvd4ql3cct5h3sdwrsajovkkndeufumzyfhlfev4qd.onion 80 true
Jun 10 17:39:10 host sdwdate[2330]: url_to_unixtime 127.0.0.1 9108 http://xpxduj55x2j27l2qytu2tcetykyfxbjbafin3x4i3ywddzphkbrd3jyd.onion 80 true
Jun 10 17:39:10 host systemd[1]: run-user-0.mount: Succeeded.
Jun 10 17:39:10 host systemd[1087]: run-user-0.mount: Succeeded.
Jun 10 17:39:10 host systemd[1]: user-runtime-dir@0.service: Succeeded.
Jun 10 17:39:10 host systemd[1]: Stopped User Runtime Directory /run/user/0.
Jun 10 17:39:10 host systemd[1]: Removed slice User Slice of UID 0.
Jun 10 17:39:18 host sudo[2576]:     user : PWD=/home/user ; USER=root ; COMMAND=/usr/bin/journalctl
Jun 10 17:39:18 host sudo[2576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jun 10 17:39:24 host sudo[2576]: pam_unix(sudo:session): session closed for user root
Jun 10 17:40:06 host sdwdate[2330]: remote_times.py: i: 0 | done
Jun 10 17:40:13 host sdwdate[2330]: remote_times.py: i: 2 | done
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: i: 1 | done
Jun 10 17:40:17 host sdwdate[2330]: remote 0: http://stormwayszuh4juycoy4kwoww5gvcu2c4tdtpkup667pdwe4qenzwayd.onion
Jun 10 17:40:17 host sdwdate[2330]: * comment: https://web.archive.org/web/20210604190703/https://cryptostorm.is/
Jun 10 17:40:17 host sdwdate[2330]: * took_time     : 56.03 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * half_took_time: 28.02 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * replay_protection_unixtime: 1654882618
Jun 10 17:40:17 host sdwdate[2330]: * remote_unixtime           : 1654883442
Jun 10 17:40:17 host sdwdate[2330]: * consensus/valid-after           : 2022-06-10 17:00:00
Jun 10 17:40:17 host sdwdate[2330]: * replay_protection_time          : 2022-06-10 17:38:38
Jun 10 17:40:17 host sdwdate[2330]: * remote_time                     : 2022-06-10 17:50:42
Jun 10 17:40:17 host sdwdate[2330]: * consensus/valid-until           : 2022-06-10 20:00:00
Jun 10 17:40:17 host sdwdate[2330]: * time_diff_raw        : 636 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * time_diff_lag_cleaned: 607.98 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * Time Replay Protection         : sane
Jun 10 17:40:17 host sdwdate[2330]: * Tor Consensus Time Sanity Check: sane
Jun 10 17:40:17 host sdwdate[2330]: * remote_status: True
Jun 10 17:40:17 host sdwdate[2330]: remote 1: https://27m3p2uv7igmj6kvd4ql3cct5h3sdwrsajovkkndeufumzyfhlfev4qd.onion
Jun 10 17:40:17 host sdwdate[2330]: * comment: https://web.archive.org/web/20210604191739/https://securityheaders.com/?q=https%3A%2F%2Ftheintercept.com%2F&followRedirects=on
Jun 10 17:40:17 host sdwdate[2330]: * took_time     : 66.96 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * half_took_time: 33.48 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * replay_protection_unixtime: 1654882618
Jun 10 17:40:17 host sdwdate[2330]: * remote_unixtime           : 1654883452
Jun 10 17:40:17 host sdwdate[2330]: * consensus/valid-after           : 2022-06-10 17:00:00
Jun 10 17:40:17 host sdwdate[2330]: * replay_protection_time          : 2022-06-10 17:38:38
Jun 10 17:40:17 host sdwdate[2330]: * remote_time                     : 2022-06-10 17:50:52
Jun 10 17:40:17 host sdwdate[2330]: * consensus/valid-until           : 2022-06-10 20:00:00
Jun 10 17:40:17 host sdwdate[2330]: * time_diff_raw        : 635 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * time_diff_lag_cleaned: 601.52 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * Time Replay Protection         : sane
Jun 10 17:40:17 host sdwdate[2330]: * Tor Consensus Time Sanity Check: sane
Jun 10 17:40:17 host sdwdate[2330]: * remote_status: True
Jun 10 17:40:17 host sdwdate[2330]: remote 2: http://xpxduj55x2j27l2qytu2tcetykyfxbjbafin3x4i3ywddzphkbrd3jyd.onion
Jun 10 17:40:17 host sdwdate[2330]: * comment: https://web.archive.org/web/20201231233846/https://theintercept.com/source/ https://theintercept.com/source/ The Intercept(securedrop)
Jun 10 17:40:17 host sdwdate[2330]: * took_time     : 62.72 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * half_took_time: 31.36 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * replay_protection_unixtime: 1654882618
Jun 10 17:40:17 host sdwdate[2330]: * remote_unixtime           : 1654883450
Jun 10 17:40:17 host sdwdate[2330]: * consensus/valid-after           : 2022-06-10 17:00:00
Jun 10 17:40:17 host sdwdate[2330]: * replay_protection_time          : 2022-06-10 17:38:38
Jun 10 17:40:17 host sdwdate[2330]: * remote_time                     : 2022-06-10 17:50:50
Jun 10 17:40:17 host sdwdate[2330]: * consensus/valid-until           : 2022-06-10 20:00:00
Jun 10 17:40:17 host sdwdate[2330]: * time_diff_raw        : 637 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * time_diff_lag_cleaned: 605.64 second(s)
Jun 10 17:40:17 host sdwdate[2330]: * Time Replay Protection         : sane
Jun 10 17:40:17 host sdwdate[2330]: * Tor Consensus Time Sanity Check: sane
Jun 10 17:40:17 host sdwdate[2330]: * remote_status: True
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: urls_list:
Jun 10 17:40:17 host sdwdate[2330]: ['http://stormwayszuh4juycoy4kwoww5gvcu2c4tdtpkup667pdwe4qenzwayd.onion', 'https://27m3p2uv7igmj6kvd4ql3cct5h3sdwrsajovkkndeufumzyfhlfev4qd.onion', 'http://xpxduj55x2j27l2qytu2tcetykyfxbjbafin3x4i3ywddzphkbrd3jyd.onion']
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: status_list:
Jun 10 17:40:17 host sdwdate[2330]: ['ok', 'ok', 'ok']
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: took_time_list:
Jun 10 17:40:17 host sdwdate[2330]: [56.03, 66.96, 62.72]
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: half_took_time_list:
Jun 10 17:40:17 host sdwdate[2330]: [28.02, 33.48, 31.36]
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: remote_unixtime_list:
Jun 10 17:40:17 host sdwdate[2330]: [1654883442, 1654883452, 1654883450]
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: time_diff_raw_int_list:
Jun 10 17:40:17 host sdwdate[2330]: [636, 635, 637]
Jun 10 17:40:17 host sdwdate[2330]: remote_times.py: time_diff_lag_cleaned_float_list:
Jun 10 17:40:17 host sdwdate[2330]: [607.98, 601.52, 605.64]
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - returned urls "['http://stormwayszuh4juycoy4kwoww5gvcu2c4tdtpkup667pdwe4qenzwayd.onion', 'https://27m3p2uv7igmj6kvd4ql3cct5h3sdwrsajovkkndeufumzyfhlfev4qd.onion', 'http://xpxduj55x2j27l2qytu2tcetykyfxbjbafin3x4i3ywddzphkbrd3jyd.onion']"
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO -
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - failed_urls: 0 allowed_failures: 6
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - pool 0: http://stormwayszuh4juycoy4kwoww5gvcu2c4tdtpkup667pdwe4qenzwayd.onion, web_time: 2022-06-10 17:50:42, took_time: 56.03 seconds, time_diff_raw: 636 seconds, time_diff_lag_cleaned: 608 seconds
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - pool 1: https://27m3p2uv7igmj6kvd4ql3cct5h3sdwrsajovkkndeufumzyfhlfev4qd.onion, web_time: 2022-06-10 17:50:52, took_time: 66.96 seconds, time_diff_raw: 635 seconds, time_diff_lag_cleaned: 602 seconds
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - pool 2: http://xpxduj55x2j27l2qytu2tcetykyfxbjbafin3x4i3ywddzphkbrd3jyd.onion, web_time: 2022-06-10 17:50:50, took_time: 62.72 seconds, time_diff_raw: 637 seconds, time_diff_lag_cleaned: 606 seconds
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - End fetching remote times.
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO -
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - Success.
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO -      request_took_times, sorted: [56.03, 62.72, 66.96]
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - request_half_took_times, sorted: [28.02, 31.36, 33.48]
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO -           time_diff_raw, sorted: [635, 636, 637]
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO -       diffs_lag_cleaned, sorted: [602, 606, 608]
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - median          request_took_times: +62.72
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - median     half_request_took_times: +31.36
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - median         raw time difference: +636.00
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - median lag_cleaned time difference: +606.00
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - Randomizing nanoseconds.
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - randomize                         : -0.953909358
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - new time difference               : +635.046090642
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - replay_protection_unixtime: 1654882618
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - old_unixtime              : 1654882817.644364357
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - new_unixtime              : 1654883452.690454960
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - replay_protection_time          : 2022-06-10 17:38:38
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - old_unixtime_human_readable     : 2022-06-10 17:40:18
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - new_unixtime_human_readable     : 2022-06-10 17:50:53
Jun 10 17:40:17 host sdwdate[2330]: 2022-06-10 17:40:17 - sdwdate - INFO - Instantly setting the time by using command: /bin/date --utc "+%Y-%m-%d %H:%M:%S" --set "@1654883452.690454960"
Jun 10 17:50:52 host sdwdate[2330]: 2022-06-10 17:50:52 - sdwdate - INFO - /bin/date output: 2022-06-10 17:50:52
Jun 10 17:50:52 host sdwdate[2330]: 2022-06-10 17:50:52 - sdwdate - INFO - Time Replay Protection: write 1654883453 to file: /var/lib/sdwdate/time-replay-protection-utc-unixtime
Jun 10 17:50:52 host sdwdate[2330]: 2022-06-10 17:50:52 - sdwdate - INFO - Time Replay Protection: write 2022-06-10 17:50:53 to file: /var/lib/sdwdate/time-replay-protection-utc-humanreadable
Jun 10 17:50:52 host sdwdate[2330]: 2022-06-10 17:50:52 - sdwdate - INFO - Sleeping for 85 minutes, ok.

Where:

Jun 10 17:38:58 host kernel: clocksource: timekeeping watchdog on CPU0: Marking clocksource 'tsc' as unstable because the skew is too large:
Jun 10 17:38:58 host kernel: clocksource:                       'xen' wd_now: f9d4b2a3a9 wd_last: 69052de169 mask: ffffffffffffffff
Jun 10 17:38:58 host kernel: clocksource:                       'tsc' cs_now: 8ab2519683 cs_last: 8a5c9b64e0 mask: ffffffffffffffff
Jun 10 17:38:58 host kernel: tsc: Marking TSC unstable due to clocksource watchdog
Jun 10 17:38:58 host kernel: clocksource: Checking clocksource tsc synchronization from CPU 0.
Jun 10 17:38:58 host kernel: clocksource: Override clocksource tsc is unstable and not HRT compatible - cannot switch while in HRT/NOHZ mode
Jun 10 17:38:58 host kernel: clocksource: Switched to clocksource xen

The clock is changed to xen after resume because tsc unreliable.

1 Like
6

@marmarek said: started build of kernel 5.10.121, with clocksource change reverted; will be in testing repo in few hours.

1 Like
7

Still not sure I understand why the behavior shows only in latest version of whonix-gw-16 and not in original template deployed packages. So there seems to be something else that impacts this linked to packages of whonix-gw-16 itself and the kernel exposed by dom0 used by sys-whonix.

diff between packages deployed on template vs latest:

4,10c4,9
< anon-apps-config/now 3:6.2-1 all [installed,upgradable to: 3:6.4-1]
< anon-apt-sources-list/now 3:5.0-1 all [installed,upgradable to: 3:5.2-1]
< anon-connection-wizard/now 1:6.2-1 all [installed,upgradable to: 1:6.6-1]
< anon-gw-anonymizer-config/now 3:12.6-1 all [installed,upgradable to: 3:13.4-1]
< anon-gw-base-files/now 3:4.0-1 all [installed,upgradable to: 3:4.1-1]
< anon-icon-pack/now 3:3.3-1 all [installed,local]
< apparmor-profile-dist/now 3:7.7-1 all [installed,upgradable to: 3:7.8-1]
---
> anon-apps-config/unknown,now 3:6.4-1 all [installed,automatic]
> anon-apt-sources-list/unknown,now 3:5.2-1 all [installed,automatic]
> anon-connection-wizard/unknown,now 1:6.6-1 all [installed,automatic]
> anon-gw-anonymizer-config/unknown,now 3:13.4-1 all [installed,automatic]
> anon-gw-base-files/unknown,now 3:4.1-1 all [installed,automatic]
> apparmor-profile-dist/unknown,now 3:7.8-1 all [installed,automatic]
19c18
< base-files/now 11.1+deb11u2 amd64 [installed,upgradable to: 11.1+deb11u3]
---
> base-files/stable,now 11.1+deb11u3 amd64 [installed]
25,28c24,27
< bind9-dnsutils/now 1:9.16.22-1~deb11u1 amd64 [installed,upgradable to: 1:9.16.27-1~deb11u1]
< bind9-host/now 1:9.16.22-1~deb11u1 amd64 [installed,upgradable to: 1:9.16.27-1~deb11u1]
< bind9-libs/now 1:9.16.22-1~deb11u1 amd64 [installed,upgradable to: 1:9.16.27-1~deb11u1]
< bindp/now 3:2.6-1 all [installed,upgradable to: 3:2.7-1]
---
> bind9-dnsutils/stable,stable-security,now 1:9.16.27-1~deb11u1 amd64 [installed,automatic]
> bind9-host/stable,stable-security,now 1:9.16.27-1~deb11u1 amd64 [installed,automatic]
> bind9-libs/stable,stable-security,now 1:9.16.27-1~deb11u1 amd64 [installed,automatic]
> bindp/unknown,now 3:2.7-1 all [installed,automatic]
32,34c31,33
< bootclockrandomization/now 3:5.2-1 all [installed,upgradable to: 3:5.3-1]
< bsdextrautils/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
< bsdutils/now 1:2.36.1-8 amd64 [installed,upgradable to: 1:2.36.1-8+deb11u1]
---
> bootclockrandomization/unknown,now 3:5.3-1 all [installed,automatic]
> bsdextrautils/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed,automatic]
> bsdutils/stable,stable-security,now 1:2.36.1-8+deb11u1 amd64 [installed]
46c45
< damngpl/now 3:3.2-1 all [installed,upgradable to: 3:3.3-1]
---
> damngpl/unknown,now 3:3.3-1 all [installed,automatic]
63,64c62,63
< dirmngr/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< dist-base-files/now 3:8.1-1 all [installed,upgradable to: 3:8.2-1]
---
> dirmngr/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> dist-base-files/unknown,now 3:8.2-1 all [installed,automatic]
70,72c69,71
< dnsutils/now 1:9.16.22-1~deb11u1 all [installed,upgradable to: 1:9.16.27-1~deb11u1]
< dpkg-dev/stable,now 1.20.9 all [installed,upgradable to: 1.20.10]
< dpkg/stable,now 1.20.9 amd64 [installed,upgradable to: 1.20.10]
---
> dnsutils/stable,stable-security,now 1:9.16.27-1~deb11u1 all [installed,automatic]
> dpkg-dev/stable-security,now 1.20.10 all [installed,automatic]
> dpkg/stable-security,now 1.20.10 amd64 [installed]
81c80
< fdisk/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> fdisk/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
100c99
< gir1.2-gtk-3.0/now 3.24.24-4 amd64 [installed,upgradable to: 3.24.24-4+deb11u2]
---
> gir1.2-gtk-3.0/stable,now 3.24.24-4+deb11u2 amd64 [installed,automatic]
107,109c106,108
< gnupg-l10n/now 2.2.27-2 all [installed,upgradable to: 2.2.27-2+deb11u1]
< gnupg-utils/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gnupg/now 2.2.27-2 all [installed,upgradable to: 2.2.27-2+deb11u1]
---
> gnupg-l10n/stable,now 2.2.27-2+deb11u1 all [installed,automatic]
> gnupg-utils/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gnupg/stable,now 2.2.27-2+deb11u1 all [installed]
113,119c112,118
< gpg-agent/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gpg-wks-client/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gpg-wks-server/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gpg/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gpgconf/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gpgsm/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
< gpgv/now 2.2.27-2 amd64 [installed,upgradable to: 2.2.27-2+deb11u1]
---
> gpg-agent/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gpg-wks-client/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gpg-wks-server/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gpg/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gpgconf/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gpgsm/stable,now 2.2.27-2+deb11u1 amd64 [installed,automatic]
> gpgv/stable,now 2.2.27-2+deb11u1 amd64 [installed]
127c126
< gtk-update-icon-cache/now 3.24.24-4 amd64 [installed,upgradable to: 3.24.24-4+deb11u2]
---
> gtk-update-icon-cache/stable,now 3.24.24-4+deb11u2 amd64 [installed,automatic]
132,133c131,132
< gzip/stable,now 1.10-4 amd64 [installed,upgradable to: 1.10-4+deb11u1]
< hardened-malloc/now 10-3 amd64 [installed,upgradable to: 0:11.1-1]
---
> gzip/stable-security,now 1.10-4+deb11u1 amd64 [installed]
> hardened-malloc/unknown,now 0:11.1-1 amd64 [installed,automatic]
135c134
< helper-scripts/now 3:16.5-1 all [installed,upgradable to: 3:17.1-1]
---
> helper-scripts/unknown,now 3:17.1-1 all [installed,automatic]
138a138
> icon-pack-dist/unknown,now 3:3.5-1 all [installed,automatic]
144a145
> initializer-dist/unknown,now 3:6.0-1 all [installed,automatic]
147a149
> iotop/stable,now 0.6-24-g733f3f8-1.1 amd64 [installed,automatic]
156,157c158,160
< kicksecure-dependencies-cli/now 3:24.1-1 all [installed,upgradable to: 3:24.6-1]
< kicksecure-desktop-applications-xfce/now 3:24.1-1 all [installed,upgradable to: 3:24.6-1]
---
> kicksecure-dependencies-cli/unknown,now 3:24.6-1 all [installed,automatic]
> kicksecure-desktop-applications-xfce/unknown,now 3:24.6-1 all [installed,automatic]
> kicksecure-recommended-cli/unknown,now 3:24.6-1 all [installed,automatic]
159a163
> legacy-dist/unknown,now 3:13.2-1 all [installed,automatic]
166,167c170,171
< libarchive-tools/now 3.4.3-2+b1 amd64 [installed,upgradable to: 3.4.3-2+deb11u1]
< libarchive13/now 3.4.3-2+b1 amd64 [installed,upgradable to: 3.4.3-2+deb11u1]
---
> libarchive-tools/stable,now 3.4.3-2+deb11u1 amd64 [installed,automatic]
> libarchive13/stable,now 3.4.3-2+deb11u1 amd64 [installed,automatic]
189c193
< libblkid1/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> libblkid1/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
204,208c208,212
< libc-bin/now 2.31-13+deb11u2 amd64 [installed,upgradable to: 2.31-13+deb11u3]
< libc-dev-bin/now 2.31-13+deb11u2 amd64 [installed,upgradable to: 2.31-13+deb11u3]
< libc-l10n/now 2.31-13+deb11u2 all [installed,upgradable to: 2.31-13+deb11u3]
< libc6-dev/now 2.31-13+deb11u2 amd64 [installed,upgradable to: 2.31-13+deb11u3]
< libc6/now 2.31-13+deb11u2 amd64 [installed,upgradable to: 2.31-13+deb11u3]
---
> libc-bin/stable,now 2.31-13+deb11u3 amd64 [installed]
> libc-dev-bin/stable,now 2.31-13+deb11u3 amd64 [installed,automatic]
> libc-l10n/stable,now 2.31-13+deb11u3 all [installed]
> libc6-dev/stable,now 2.31-13+deb11u3 amd64 [installed,automatic]
> libc6/stable,now 2.31-13+deb11u3 amd64 [installed]
224c228
< libcryptsetup12/now 2:2.3.5-1 amd64 [installed,upgradable to: 2:2.3.7-1+deb11u1]
---
> libcryptsetup12/stable,stable-security,now 2:2.3.7-1+deb11u1 amd64 [installed]
227c231
< libcups2/stable,now 2.3.3op2-3+deb11u1 amd64 [installed,upgradable to: 2.3.3op2-3+deb11u2]
---
> libcups2/stable-security,now 2.3.3op2-3+deb11u2 amd64 [installed,automatic]
244c248
< libdpkg-perl/stable,now 1.20.9 all [installed,upgradable to: 1.20.10]
---
> libdpkg-perl/stable-security,now 1.20.10 all [installed,automatic]
269c273
< libexpat1/now 2.2.10-2 amd64 [installed,upgradable to: 2.2.10-2+deb11u3]
---
> libexpat1/stable,stable-security,now 2.2.10-2+deb11u3 amd64 [installed,automatic]
274c278
< libfdisk1/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> libfdisk1/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
281c285
< libflac8/now 1.3.3-2 amd64 [installed,upgradable to: 1.3.3-2+deb11u1]
---
> libflac8/stable,now 1.3.3-2+deb11u1 amd64 [installed,automatic]
323,324c327,328
< libgtk-3-0/now 3.24.24-4 amd64 [installed,upgradable to: 3.24.24-4+deb11u2]
< libgtk-3-common/now 3.24.24-4 all [installed,upgradable to: 3.24.24-4+deb11u2]
---
> libgtk-3-0/stable,now 3.24.24-4+deb11u2 amd64 [installed,automatic]
> libgtk-3-common/stable,now 3.24.24-4+deb11u2 all [installed,automatic]
351c355
< libjavascriptcoregtk-4.0-18/now 2.34.3-1~deb11u1 amd64 [installed,upgradable to: 2.36.3-1~deb11u1]
---
> libjavascriptcoregtk-4.0-18/stable-security,now 2.36.3-1~deb11u1 amd64 [installed,automatic]
370c374
< libldap-2.4-2/stable,now 2.4.57+dfsg-3 amd64 [installed,upgradable to: 2.4.57+dfsg-3+deb11u1]
---
> libldap-2.4-2/stable-security,now 2.4.57+dfsg-3+deb11u1 amd64 [installed,automatic]
381c385
< liblzma5/stable,now 5.2.5-2 amd64 [installed,upgradable to: 5.2.5-2.1~deb11u1]
---
> liblzma5/stable-security,now 5.2.5-2.1~deb11u1 amd64 [installed]
393c397
< libmount1/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> libmount1/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
420c424
< libnss3/now 2:3.61-1+deb11u1 amd64 [installed,upgradable to: 2:3.61-1+deb11u2]
---
> libnss3/stable,stable-security,now 2:3.61-1+deb11u2 amd64 [installed,automatic]
423a428
> libopengl0/stable,now 1.3.2-1 amd64 [installed,automatic]
431c436
< libpam-systemd/now 247.3-6 amd64 [installed,upgradable to: 247.3-7]
---
> libpam-systemd/stable,now 247.3-7 amd64 [installed,automatic]
451,452c456,457
< libpolkit-agent-1-0/now 0.105-31 amd64 [installed,upgradable to: 0.105-31+deb11u1]
< libpolkit-gobject-1-0/now 0.105-31 amd64 [installed,upgradable to: 0.105-31+deb11u1]
---
> libpolkit-agent-1-0/stable,stable-security,now 0.105-31+deb11u1 amd64 [installed,automatic]
> libpolkit-gobject-1-0/stable,stable-security,now 0.105-31+deb11u1 amd64 [installed,automatic]
463c468
< libqrexec-utils2/now 4.1.16-1+deb11u1 amd64 [installed,upgradable to: 4.1.18-1+deb11u1]
---
> libqrexec-utils2/unknown,now 4.1.18-1+deb11u1 amd64 [installed,automatic]
480c485
< libqubesdb/now 4.1.12-1+deb11u1 amd64 [installed,upgradable to: 4.1.13-1+deb11u1]
---
> libqubesdb/unknown,now 4.1.13-1+deb11u1 amd64 [installed,automatic]
493,494c498,499
< libsasl2-2/now 2.1.27+dfsg-2.1 amd64 [installed,upgradable to: 2.1.27+dfsg-2.1+deb11u1]
< libsasl2-modules-db/now 2.1.27+dfsg-2.1 amd64 [installed,upgradable to: 2.1.27+dfsg-2.1+deb11u1]
---
> libsasl2-2/stable,stable-security,now 2.1.27+dfsg-2.1+deb11u1 amd64 [installed,automatic]
> libsasl2-modules-db/stable,stable-security,now 2.1.27+dfsg-2.1+deb11u1 amd64 [installed,automatic]
509c514
< libsmartcols1/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> libsmartcols1/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
520c525
< libssl1.1/now 1.1.1k-1+deb11u1 amd64 [installed,upgradable to: 1.1.1n-0+deb11u2]
---
> libssl1.1/stable-security,now 1.1.1n-0+deb11u2 amd64 [installed]
524c529
< libsystemd0/now 247.3-6 amd64 [installed,upgradable to: 247.3-7]
---
> libsystemd0/stable,now 247.3-7 amd64 [installed]
536c541
< libtiff5/stable,now 4.2.0-1 amd64 [installed,upgradable to: 4.2.0-1+deb11u1]
---
> libtiff5/stable-security,now 4.2.0-1+deb11u1 amd64 [installed,automatic]
545c550
< libudev1/now 247.3-6 amd64 [installed,upgradable to: 247.3-7]
---
> libudev1/stable,now 247.3-7 amd64 [installed]
553c558
< libuuid1/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> libuuid1/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
574c579
< libwebkit2gtk-4.0-37/now 2.34.3-1~deb11u1 amd64 [installed,upgradable to: 2.36.3-1~deb11u1]
---
> libwebkit2gtk-4.0-37/stable-security,now 2.36.3-1~deb11u1 amd64 [installed,automatic]
615,624c620,629
< libxencall1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxendevicemodel1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxenevtchn1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxenforeignmemory1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxengnttab1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxenhypfs1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxenmisc4.14/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxenstore3.0/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxentoolcore1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< libxentoollog1/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
---
> libxencall1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxendevicemodel1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxenevtchn1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxenforeignmemory1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxengnttab1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxenhypfs1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxenmisc4.14/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxenstore3.0/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxentoolcore1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> libxentoollog1/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
639c644
< libxml2/now 2.9.10+dfsg-6.7 amd64 [installed,upgradable to: 2.9.10+dfsg-6.7+deb11u2]
---
> libxml2/stable-security,now 2.9.10+dfsg-6.7+deb11u2 amd64 [installed,automatic]
662,663c667,668
< linux-libc-dev/now 5.10.84-1 amd64 [installed,upgradable to: 5.10.113-1]
< locales/now 2.31-13+deb11u2 all [installed,upgradable to: 2.31-13+deb11u3]
---
> linux-libc-dev/stable-security,now 5.10.113-1 amd64 [installed,automatic]
> locales/stable,now 2.31-13+deb11u3 all [installed]
680c685
< mount/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
---
> mount/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
682,683c687,688
< msgcollector-gui/now 3:8.0-1 all [installed,upgradable to: 3:8.3-1]
< msgcollector/now 3:8.0-1 all [installed,upgradable to: 3:8.3-1]
---
> msgcollector-gui/unknown,now 3:8.3-1 all [installed,automatic]
> msgcollector/unknown,now 3:8.3-1 all [installed,automatic]
689a695
> netcat-openbsd/stable,now 1.217-3 amd64 [installed,automatic]
693c699
< onion-grater/now 3:10.2-1 all [installed,upgradable to: 3:10.4-1]
---
> onion-grater/unknown,now 3:10.4-1 all [installed,automatic]
695,696c701,702
< open-link-confirmation/now 3:4.8-1 all [installed,upgradable to: 3:4.9-1]
< openssl/now 1.1.1k-1+deb11u1 amd64 [installed,upgradable to: 1.1.1n-0+deb11u2]
---
> open-link-confirmation/unknown,now 3:4.9-1 all [installed,automatic]
> openssl/stable-security,now 1.1.1n-0+deb11u2 amd64 [installed]
710c716
< policykit-1/now 0.105-31 amd64 [installed,upgradable to: 0.105-31+deb11u1]
---
> policykit-1/stable,stable-security,now 0.105-31+deb11u1 amd64 [installed,automatic]
753c759
< python3-qubesdb/now 4.1.12-1+deb11u1 amd64 [installed,upgradable to: 4.1.13-1+deb11u1]
---
> python3-qubesdb/unknown,now 4.1.13-1+deb11u1 amd64 [installed,automatic]
769,776c775,782
< qubes-core-agent-dom0-updates/now 4.1.31-1+deb11u1 amd64 [installed,upgradable to: 4.1.35-1+deb11u1]
< qubes-core-agent-nautilus/now 4.1.31-1+deb11u1 amd64 [installed,upgradable to: 4.1.35-1+deb11u1]
< qubes-core-agent-networking/now 4.1.31-1+deb11u1 amd64 [installed,upgradable to: 4.1.35-1+deb11u1]
< qubes-core-agent-passwordless-root/now 4.1.31-1+deb11u1 amd64 [installed,upgradable to: 4.1.35-1+deb11u1]
< qubes-core-agent-thunar/now 4.1.31-1+deb11u1 amd64 [installed,upgradable to: 4.1.35-1+deb11u1]
< qubes-core-agent/now 4.1.31-1+deb11u1 amd64 [installed,upgradable to: 4.1.35-1+deb11u1]
< qubes-core-qrexec/now 4.1.16-1+deb11u1 amd64 [installed,upgradable to: 4.1.18-1+deb11u1]
< qubes-gui-agent/now 4.1.24-1+deb11u1 amd64 [installed,upgradable to: 4.1.25-1+deb11u1]
---
> qubes-core-agent-dom0-updates/unknown,now 4.1.35-1+deb11u1 amd64 [installed,automatic]
> qubes-core-agent-nautilus/unknown,now 4.1.35-1+deb11u1 amd64 [installed,automatic]
> qubes-core-agent-networking/unknown,now 4.1.35-1+deb11u1 amd64 [installed,automatic]
> qubes-core-agent-passwordless-root/unknown,now 4.1.35-1+deb11u1 amd64 [installed,automatic]
> qubes-core-agent-thunar/unknown,now 4.1.35-1+deb11u1 amd64 [installed,automatic]
> qubes-core-agent/unknown,now 4.1.35-1+deb11u1 amd64 [installed,automatic]
> qubes-core-qrexec/unknown,now 4.1.18-1+deb11u1 amd64 [installed,automatic]
> qubes-gui-agent/unknown,now 4.1.25-1+deb11u1 amd64 [installed,automatic]
783,788c789,794
< qubes-whonix-gateway-packages-recommended/now 1:17.1-1 all [installed,upgradable to: 1:17.3-1]
< qubes-whonix-gateway/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< qubes-whonix-shared-packages-recommended/now 1:17.1-1 all [installed,upgradable to: 1:17.3-1]
< qubes-whonix/now 1:17.1-1 all [installed,upgradable to: 1:17.3-1]
< qubesdb-vm/now 4.1.12-1+deb11u1 amd64 [installed,upgradable to: 4.1.13-1+deb11u1]
< qubesdb/now 4.1.12-1+deb11u1 amd64 [installed,upgradable to: 4.1.13-1+deb11u1]
---
> qubes-whonix-gateway-packages-recommended/unknown,now 1:17.3-1 all [installed,automatic]
> qubes-whonix-gateway/unknown,now 3:22.5-1 all [installed]
> qubes-whonix-shared-packages-recommended/unknown,now 1:17.3-1 all [installed,automatic]
> qubes-whonix/unknown,now 1:17.3-1 all [installed,automatic]
> qubesdb-vm/unknown,now 4.1.13-1+deb11u1 amd64 [installed,automatic]
> qubesdb/unknown,now 4.1.13-1+deb11u1 amd64 [installed,automatic]
790,791c796,797
< repository-dist-wizard/now 3:8.6-1 all [installed,upgradable to: 3:8.8-1]
< repository-dist/now 3:8.6-1 all [installed,upgradable to: 3:8.8-1]
---
> repository-dist-wizard/unknown,now 3:8.8-1 all [installed,automatic]
> repository-dist/unknown,now 3:8.8-1 all [installed,automatic]
796c802
< rsyslog/stable,now 8.2102.0-2 amd64 [installed,upgradable to: 8.2102.0-2+deb11u1]
---
> rsyslog/stable-security,now 8.2102.0-2+deb11u1 amd64 [installed]
800,801c806,807
< sdwdate-gui/now 1:8.0-1 all [installed,upgradable to: 1:8.2-1]
< sdwdate/now 3:19.1-1 all [installed,upgradable to: 3:19.7-1]
---
> sdwdate-gui/unknown,now 1:8.2-1 all [installed,automatic]
> sdwdate/unknown,now 3:19.7-1 all [installed,automatic]
803c809
< security-misc/now 3:22.9-1 all [installed,upgradable to: 3:23.1-1]
---
> security-misc/unknown,now 3:23.1-1 all [installed,automatic]
806,807c812,813
< setup-dist/now 3:6.8-1 all [installed,upgradable to: 3:7.1-1]
< setup-wizard-dist/now 3:7.4-1 all [installed,upgradable to: 3:7.8-1]
---
> setup-dist/unknown,now 3:7.1-1 all [installed,automatic]
> setup-wizard-dist/unknown,now 3:7.8-1 all [installed,automatic]
818,821c824,827
< systemcheck/now 3:24.2-1 all [installed,upgradable to: 3:24.7-1]
< systemd-sysv/now 247.3-6 amd64 [installed,upgradable to: 247.3-7]
< systemd/now 247.3-6 amd64 [installed,upgradable to: 247.3-7]
< sysvinit-utils/now 2.96-7 amd64 [installed,upgradable to: 2.96-7+deb11u1]
---
> systemcheck/unknown,now 3:24.7-1 all [installed,automatic]
> systemd-sysv/stable,now 247.3-7 amd64 [installed]
> systemd/stable,now 247.3-7 amd64 [installed]
> sysvinit-utils/stable,now 2.96-7+deb11u1 amd64 [installed]
823,824c829,830
< tasksel-data/now 3.68 all [installed,upgradable to: 3.68+deb11u1]
< tasksel/now 3.68 all [installed,upgradable to: 3.68+deb11u1]
---
> tasksel-data/stable,now 3.68+deb11u1 all [installed]
> tasksel/stable,now 3.68+deb11u1 all [installed]
829c835
< timesanitycheck/now 3:5.0-1 all [installed,upgradable to: 3:5.2-1]
---
> timesanitycheck/unknown,now 3:5.2-1 all [installed,automatic]
832,834c838,841
< tor-control-panel/now 1:3.5-1 all [installed,upgradable to: 1:4.1-1]
< tor-geoipdb/now 0.4.6.9-1~d11.bullseye+1 all [installed,upgradable to: 0.4.7.7-1~d11.bullseye+1]
< tor/now 0.4.6.9-1~d11.bullseye+1 amd64 [installed,upgradable to: 0.4.7.7-1~d11.bullseye+1]
---
> tor-control-panel/unknown,now 1:4.1-1 all [installed,automatic]
> tor-ctrl/unknown,now 3:4.7-1 all [installed,automatic]
> tor-geoipdb/unknown,now 0.4.7.7-1~d11.bullseye+1 all [installed,automatic]
> tor/unknown,now 0.4.7.7-1~d11.bullseye+1 amd64 [installed,automatic]
836c843
< tzdata/stable,now 2021a-1+deb11u2 all [installed,upgradable to: 2021a-1+deb11u3]
---
> tzdata/stable-updates,now 2021a-1+deb11u3 all [installed]
838c845
< udev/now 247.3-6 amd64 [installed,upgradable to: 247.3-7]
---
> udev/stable,now 247.3-7 amd64 [installed]
842c849
< usability-misc/now 3:10.1-1 all [installed,upgradable to: 3:10.4-1]
---
> usability-misc/unknown,now 3:10.4-1 all [installed,automatic]
844,845c851,852
< util-linux/now 2.36.1-8 amd64 [installed,upgradable to: 2.36.1-8+deb11u1]
< uwt/now 3:6.9-1 all [installed,upgradable to: 3:7.0-1]
---
> util-linux/stable,stable-security,now 2.36.1-8+deb11u1 amd64 [installed]
> uwt/unknown,now 3:7.0-1 all [installed,automatic]
849c856
< vm-config-dist/now 3:7.9-1 all [installed,upgradable to: 3:8.0-1]
---
> vm-config-dist/unknown,now 3:8.0-1 all [installed,automatic]
853,864c860,869
< whonix-base-files/now 3:8.4-1 all [installed,upgradable to: 3:8.5-1]
< whonix-firewall/now 3:10.9-1 all [installed,upgradable to: 3:11.1-1]
< whonix-gateway-default-applications-gui/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< whonix-gateway-packages-dependencies-cli/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< whonix-gateway-packages-dependencies-pre/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< whonix-gateway-shared-packages-shared-meta/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< whonix-gw-network-conf/now 3:4.4-1 all [installed,upgradable to: 3:4.5-1]
< whonix-initializer/now 3:5.9-1 all [installed,local]
< whonix-legacy/now 3:12.9-1 all [installed,local]
< whonix-shared-default-applications-gui/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< whonix-shared-packages-dependencies-cli/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
< whonix-shared-packages-recommended-cli/now 3:22.2-1 all [installed,upgradable to: 3:22.5-1]
---
> whonix-base-files/unknown,now 3:8.5-1 all [installed,automatic]
> whonix-firewall/unknown,now 3:11.1-1 all [installed,automatic]
> whonix-gateway-default-applications-gui/unknown,now 3:22.5-1 all [installed,automatic]
> whonix-gateway-packages-dependencies-cli/unknown,now 3:22.5-1 all [installed,automatic]
> whonix-gateway-packages-dependencies-pre/unknown,now 3:22.5-1 all [installed,automatic]
> whonix-gateway-shared-packages-shared-meta/unknown,now 3:22.5-1 all [installed,automatic]
> whonix-gw-network-conf/unknown,now 3:4.5-1 all [installed,automatic]
> whonix-shared-default-applications-gui/unknown,now 3:22.5-1 all [installed,automatic]
> whonix-shared-packages-dependencies-cli/unknown,now 3:22.5-1 all [installed,automatic]
> whonix-shared-packages-recommended-cli/unknown,now 3:22.5-1 all [installed,automatic]
878,881c883,886
< xen-utils-4.14/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< xen-utils-common/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
< xen-utils-guest/now 4.14.3-7+deb11u1 amd64 [installed,upgradable to: 4.14.4-4+deb11u1]
< xenstore-utils/stable,now 4.14.3+32-g9de3671772-1~deb11u1 amd64 [installed,upgradable to: 4.14.4+74-gd7b22226b5-1]
---
> xen-utils-4.14/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> xen-utils-common/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
> xen-utils-guest/unknown,now 4.14.4-4+deb11u1 amd64 [installed,automatic]
> xenstore-utils/stable-security,now 4.14.4+74-gd7b22226b5-1 amd64 [installed,automatic]
888,891c893,896
< xserver-xorg-input-qubes/now 4.1.24-1+deb11u1 amd64 [installed,upgradable to: 4.1.25-1+deb11u1]
< xserver-xorg-qubes-common/now 4.1.24-1+deb11u1 amd64 [installed,upgradable to: 4.1.25-1+deb11u1]
< xserver-xorg-video-dummyqbs/now 4.1.24-1+deb11u1 amd64 [installed,upgradable to: 4.1.25-1+deb11u1]
< xterm/now 366-1 amd64 [installed,upgradable to: 366-1+deb11u1]
---
> xserver-xorg-input-qubes/unknown,now 4.1.25-1+deb11u1 amd64 [installed,automatic]
> xserver-xorg-qubes-common/unknown,now 4.1.25-1+deb11u1 amd64 [installed,automatic]
> xserver-xorg-video-dummyqbs/unknown,now 4.1.25-1+deb11u1 amd64 [installed,automatic]
> xterm/stable,now 366-1+deb11u1 amd64 [installed]
893c898
< xz-utils/stable,now 5.2.5-2 amd64 [installed,upgradable to: 5.2.5-2.1~deb11u1]
---
> xz-utils/stable-security,now 5.2.5-2.1~deb11u1 amd64 [installed,automatic]
897,899c902,904
< zlib1g/stable,now 1:1.2.11.dfsg-2 amd64 [installed,upgradable to: 1:1.2.11.dfsg-2+deb11u1]
< zsh-common/now 5.8-6 all [installed,upgradable to: 5.8-6+deb11u1]
< zsh/now 5.8-6+b2 amd64 [installed,upgradable to: 5.8-6+deb11u1]
---
> zlib1g/stable-security,now 1:1.2.11.dfsg-2+deb11u1 amd64 [installed]
> zsh-common/stable,stable-security,now 5.8-6+deb11u1 all [installed,auto-removable]
> zsh/stable,stable-security,now 5.8-6+deb11u1 amd64 [installed,auto-removable]
1 Like
8

Related qubes issue:

1 Like
9

I confirm that

fixes the issue.

1 Like
10

might also be part of the long term solution

1 Like
11

Also discussed here: