Slow shutdown of version 15 workstation and gateway

[rk1]

Ok, I did as HulaHoop asked. I cannot upload txt files, so I will paste the flurry of messages right here. But before I do so let me make an observation. I noticed that midway the shutdown process (of both Gateway and Workstation) the process halts, mentioning that a stop job is underway and a timer is set for 1 m 29 sec. When that period of time has passed the shutdown continues…

So maybe we already pinpointed the problem. But here is the shutdown process for the Gateway:

#[14;0]user@host:~$ sudo shutdown
[sudo] password for user:
Shutdown scheduled for Fri 2020-01-24 05:46:28 UTC, use ‘shutdown -c’ to cancel.
user@host:~$ Stopping Session 1 of user user.
Stopping Daemon for power management…
Stopping live-tools - System Support Scripts…
Stopping Agent daemon for Spice guests…
[ OK ] Stopped target Timers.
[ OK ] Stopped Daily apt upgrade and clean activities.
[ OK ] Stopped Daily Cleanup of Temporary Directories.
[ OK ] Stopped Daily rotation of log files.
[ OK ] Stopped Daily man-db regeneration.
[ OK ] Stopped Daily apt download activities.
[ OK ] Stopped target Graphical Interface.
Stopping Disk Manager…
[ OK ] Stopped target Multi-User System.
[ OK ] Stopped /home/user from /e…ulation at First Boot Service.
Stopping Secure Distributed Web Date…
Stopping System Logging Service…
Stopping LSB: Set sysfs variables from /etc/sysfs.conf…
Stopping Notify sdwdate-gui on gateway about shutdown…
Stopping LSB: Start some power management scripts…
[ OK ] Stopped Mounts KVM shared folder during start.
[ OK ] Stopped OpenVPN service.
[ OK ] Stopped target Login Prompts.
Stopping Serial Getty on ttyS0…
Stopping Getty on tty1…
Stopping whonixcheck…
Stopping Regular background program processing daemon…
[ OK ] Stopped msgcollector.
[ OK ] Stopped Session 3 of user user.
Stopping Authorization Manager…
[ OK ] Stopped Regular background program processing daemon.
[ OK ] Stopped Disk Manager.
[ OK ] Stopped System Logging Service.
[ OK ] Stopped Authorization Manager.
[ OK ] Stopped Serial Getty on ttyS0.
[ OK ] Stopped Getty on tty1.
[ OK ] Stopped Secure Distributed Web Date.
[ OK ] Stopped whonixcheck.
[ OK ] Stopped Daemon for power management.
[ OK ] Stopped Agent daemon for Spice guests.
[ OK ] Stopped live-tools - System Support Scripts.
[ OK ] Stopped Notify sdwdate-gui on gateway about shutdown…
[ OK ] Stopped LSB: Set sysfs variables from /etc/sysfs.conf.
Stopping Tor control port filter proxy…
[ OK ] Stopped Anonymizing overla…r TCP (multi-instance-master).
[ OK ] Stopped Make Sure Torrc Fi…s Exist For Tor Control Panel.
Stopping Whonix firewall watcher…
Stopping Anonymizing overlay network for TCP…
[ OK ] Stopped Ram Adjusted Desktop Starter.
Stopping Light Display Manager…
[ OK ] Removed slice system-getty.slice.
[ OK ] Removed slice system-serial\x2dgetty.slice.
[ OK ] Stopped Whonix firewall watcher.
[ OK ] Stopped Tor control port filter proxy.
[ OK ] Stopped Anonymizing overlay network for TCP.
[ OK ] Stopped LSB: Start some power management scripts.
[ OK ] Stopped Light Display Manager.
[ OK ] Stopped Ram Adjusted Desktop Starter Early Boot.
Stopping Boot Clock Randomization…
[ OK ] Stopped Make Sure Torrc Files Exist.
[ OK ] Stopped Whonix legacy versions fixes.
[ OK ] Removed slice system-tor.slice.
[ OK ] Stopped Whonix First Run Initializer (text mode).
Stopping Encrypted Swap File Creator…
[ OK ] Stopped Boot Clock Randomization.
[ OK ] Stopped Time Sanity Check.
[ OK ] Deactivated swap /dev/disk/by-id/dm-name-swapfile.
[ OK ] Deactivated swap /dev/disk…2-85b0-4183-8d54-300bb0d2e491.
[ OK ] Deactivated swap /dev/mapper/swapfile.
[ OK ] Deactivated swap /dev/disk…4ed09c787cda14027ddc-swapfile.
[ OK ] Deactivated swap /dev/dm-0.
[ OK ] Stopped Encrypted Swap File Creator.
[ OK ] Stopped Session 1 of user user.
Stopping User Manager for UID 1000…
Stopping Login Service…
[ OK ] Stopped Login Service.
[ OK ] Stopped User Manager for UID 1000.
Stopping User Runtime Directory /run/user/1000…
[ OK ] Unmounted /run/user/1000.
[ OK ] Stopped User Runtime Directory /run/user/1000.
[ OK ] Removed slice User Slice of UID 1000.
Stopping Permit User Sessions…
Stopping D-Bus System Message Bus…
[ OK ] Stopped D-Bus System Message Bus.
[ OK ] Stopped Permit User Sessions.
[ OK ] Stopped target Remote File Systems.
[ OK ] Stopped target Basic System.
[ OK ] Stopped target Paths.
[ OK ] Stopped target Sockets.
[ OK ] Closed Activation socket for spice guest agent daemon.
[ OK ] Closed D-Bus System Message Bus Socket.
[ OK ] Closed Syslog Socket.
[ OK ] Stopped target Slices.
[ OK ] Removed slice User and Session Slice.
[ OK ] Stopped target System Initialization.
Stopping Update UTMP about System Boot/Shutdown…
[ OK ] Stopped target Local Encrypted Volumes.
[ OK ] Stopped Forward Password R…uests to Wall Directory Watch.
[ OK ] Stopped Dispatch Password …ts to Console Directory Watch.
Stopping Load/Save Random Seed…
[ OK ] Stopped target Network.
Stopping Raise network interfaces…
[ OK ] Stopped Load/Save Random Seed.
[ OK ] Stopped Update UTMP about System Boot/Shutdown.
Stopping Security Auditing Service…
[ 222.091220] kauditd_printk_skb: 8 callbacks suppressed
[ 222.091221] audit: type=1305 audit(1579844878.876:257): audit_pid=0 old=357 auid=4294967295 ses=4294967295 subj==unconfined res=1
[ OK ] Stopped Security Auditing Service.
[ OK ] Stopped Create Volatile Files and Directories.
[ 222.097424] audit: type=1131 audit(1579844878.880:258): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=auditd comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ 222.100839] audit: type=1131 audit(1579844878.884:259): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=systemd-tmpfiles-setup comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped Raise network interfaces.
[ 222.117178] audit: type=1131 audit(1579844878.904:260): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=networking comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped target Network (Pre).
[ OK ] Stopped Whonix firewall loader.
[ 222.122100] audit: type=1131 audit(1579844878.908:261): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=whonix-firewall comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped Apply Kernel Variables.
[ 222.126781] audit: type=1131 audit(1579844878.912:262): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=systemd-sysctl comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped Load Kernel Modules.
[ OK ] Stopped target Local File Systems.
[ 222.132022] audit: type=1131 audit(1579844878.916:263): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=systemd-modules-load comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
Unmounting /run/msgcollector…
[ OK ] Unmounted /run/msgcollector.
[ OK ] Reached target Unmount All Filesystems.
[ OK ] Stopped target Local File Systems (Pre).
[ OK ] Stopped Create Static Device Nodes in /dev.
[ 222.146947] audit: type=1131 audit(1579844878.932:264): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=systemd-tmpfiles-setup-dev comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped Create System Users.
[ 222.150844] audit: type=1131 audit(1579844878.936:265): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=systemd-sysusers comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped Remount Root and Kernel File Systems.
[ 222.154758] audit: type=1131 audit(1579844878.940:266): pid=1 uid=0 auid=4294967295 ses=4294967295 subj==unconfined msg=‘unit=systemd-remount-fs comm=“systemd” exe="/lib/systemd/systemd" hostname=? addr=? terminal=? res=success’
[ OK ] Stopped target Swap.
[ OK ] Reached target Shutdown.
[ OK ] Reached target Final Step.
[ OK ] Started Power-Off.
[ OK ] Reached target Power-Off.
[ 222.214341] systemd-shutdow: 59 output lines suppressed due to ratelimiting
[ 222.227554] systemd-shutdown[1]: Syncing filesystems and block devices.
[ 222.237846] systemd-shutdown[1]: Sending SIGTERM to remaining processes…
[ 222.240223] systemd-journald[216]: Received SIGTERM from PID 1 (systemd-shutdow).
[ 222.248743] systemd-shutdown[1]: Sending SIGKILL to remaining processes…
[ 222.251286] systemd-shutdown[1]: Unmounting file systems.
[ 222.254094] [2292]: Remounting ‘/’ read-only in with options ‘errors=remount-ro’.
[ 222.281344] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
[ 222.290329] systemd-shutdown[1]: All filesystems unmounted.
[ 222.291149] systemd-shutdown[1]: Deactivating swaps.
[ 222.291796] systemd-shutdown[1]: All swaps deactivated.
[ 222.292804] systemd-shutdown[1]: Detaching loop devices.
[ 222.294372] systemd-shutdown[1]: All loop devices detached.
[ 222.295088] systemd-shutdown[1]: Detaching DM devices.
[ 222.299832] kvm: exiting hardware virtualization
[ 222.300800] ACPI: Preparing to enter system sleep state S5
[ 222.301550] reboot: Power down

r@c:~$

And here for the Workstation:

#[14;0]user@host:~$ sudo shutdown now
[sudo] password for user:
Stopping redirect /run/tor…to Whonix-Gateway port 9051…
[ OK ] Stopped target Sound Card.
Stopping Authorization Manager…
[ OK ] Stopped target Graphical Interface.
Stopping Session 3 of user user.
[ OK ] Stopped target Timers.
[ OK ] Stopped Daily rotation of log files.
Stopping Daemon for power management…
[ OK ] Stopped Daily apt upgrade and clean activities.
Stopping Save/Restore Sound Card State…
[ OK ] Stopped target Multi-User System.
Stopping LSB: Set sysfs variables from /etc/sysfs.conf…
Stopping Notify sdwdate-gui on gateway about shutdown…
Stopping Secure Distributed Web Date…
Stopping Regular background program processing daemon…
[ OK ] Stopped Helper Service for…ser home by Whonix developers.
[ OK ] Stopped /home/user from /e…ulation at First Boot Service.
Stopping whonixcheck…
[ OK ] Stopped Helper Service for… DispVMs by Whonix developers.
Stopping LSB: Start some power management scripts…
[ OK ] Stopped Daily apt download activities.
Stopping live-tools - System Support Scripts…
[ OK ] Stopped Daily Cleanup of Temporary Directories.
Stopping Disk Manager…
Stopping System Logging Service…
[ OK ] Stopped OpenVPN service.
[ 91.529834] show_signal_msg: 7 callbacks suppressed
[ 91.529837] xfce4-panel[1231]: segfault at 8cf5a0c19 ip 00007d46c948cdb0 sp 00007ffeb342a4e0 error 4 in libc-2.28.so[7d46c942f000+148000]
[ 91.532063] Code: f0 48 01 f2 4c 8b 68 08 4d 89 ec 49 83 e4 f8 49 39 f4 0f 85 da 00 00 00 4e 3b 24 20 0f 85 c0 00 00 00 48 8b 70 10 4c 8b 60 18 <48> 39 46 18 0f 85 9e 00 00 00 49 39 44 24 10 0f 85 93 00 00 00 4c
Stopping Session 1 of user user.
Stopping Agent daemon for Spice guests…
[ OK ] Stopped Mounts KVM shared folder during start.
[ OK ] Stopped Daily man-db regeneration.
[ OK ] Stopped target Login Prompts.
Stopping Getty on tty1…
Stopping Serial Getty on ttyS0…
[ OK ] Stopped msgcollector.
Stopping kloak anti keystroke deanonymization tool…
[ OK ] Stopped System Logging Service.
[ OK ] Stopped kloak anti keystroke deanonymization tool.
[ OK ] Stopped Disk Manager.
[ OK ] Stopped Regular background program processing daemon.
[ OK ] Stopped Authorization Manager.
[ OK ] Stopped whonixcheck.
[ OK ] Stopped Secure Distributed Web Date.
[ OK ] Stopped Serial Getty on ttyS0.
[ OK ] Stopped Getty on tty1.
[ OK ] Stopped redirect /run/tor/…l to Whonix-Gateway port 9051.
[ OK ] Stopped Daemon for power management.
[ OK ] Stopped Agent daemon for Spice guests.
[ OK ] Stopped Save/Restore Sound Card State.
[ OK ] Stopped Notify sdwdate-gui on gateway about shutdown…
[ OK ] Stopped live-tools - System Support Scripts.
[ OK ] Stopped Session 3 of user user.
[ OK ] Stopped LSB: Set sysfs variables from /etc/sysfs.conf.
Stopping Manage Sound Card State (restore and store)…
[ OK ] Removed slice system-getty.slice.
[ OK ] Stopped Ram Adjusted Desktop Starter.
Stopping Light Display Manager…
[ OK ] Removed slice system-serial\x2dgetty.slice.
Stopping Whonix firewall watcher…
[ OK ] Stopped Whonix legacy versions fixes.
Stopping anon-ws-disable-stacked-tor…
[ OK ] Stopped Anonymizing overla…r TCP (multi-instance-master).
[ OK ] Stopped Whonix firewall watcher.
[ OK ] Stopped anon-ws-disable-stacked-tor.
[ OK ] Stopped LSB: Start some power management scripts.
[ OK ] Stopped Light Display Manager.
[ OK ] Stopped Ram Adjusted Desktop Starter Early Boot.
[ OK ] Removed slice system-tor.slice.
[ OK ] Stopped Whonix First Run Initializer (text mode).
Stopping Encrypted Swap File Creator…
Stopping Boot Clock Randomization…
[ OK ] Stopped Boot Clock Randomization.
[ OK ] Stopped Time Sanity Check.
[ 91.807358] device-mapper: uevent: version 1.0.3
[ 91.809641] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com
[ OK ] Stopped Encrypted Swap File Creator.
[ OK ] Stopped Manage Sound Card State (restore and store).
[ OK ] Stopped Session 1 of user user.
Stopping User Manager for UID 1000…
Stopping Login Service…
[ OK ] Stopped Login Service.
[ OK ] Stopped User Manager for UID 1000.
Stopping User Runtime Directory /run/user/1000…
[ OK ] Unmounted /run/user/1000.
[ OK ] Stopped User Runtime Directory /run/user/1000.
Stopping D-Bus System Message Bus…
[ OK ] Removed slice User Slice of UID 1000.
Stopping Permit User Sessions…
[ OK ] Stopped D-Bus System Message Bus.
[ OK ] Stopped Permit User Sessions.
[ OK ] Stopped target Remote File Systems.
[ OK ] Stopped target Basic System.
[ OK ] Stopped target Sockets.
[ OK ] Closed redirect /run/tor/c…l to Whonix-Gateway port 9051.
[ OK ] Closed redirect 127.0.0.1:…3 to Whonix-Gateway port 9051.
[ OK ] Closed redirect /run/anon-…k to Whonix-Gateway port 9051.
[ OK ] Closed redirect 127.0.0.1:…0 to Whonix-Gateway port 9050.
[ OK ] Closed redirect 127.0.0.1:…2 to Whonix-Gateway port 9152.
[ OK ] Closed redirect 127.0.0.1:…0 to Whonix-Gateway port 9150.
[ OK ] Closed redirect 127.0.0.1:…9 to Whonix-Gateway port 9119.
[ OK ] Closed redirect /run/anon-…k to Whonix-Gateway port 9050.
[ OK ] Closed redirect /run/anon-…k to Whonix-Gateway port 9150.
[ OK ] Closed redirect /run/anon-…k to Whonix-Gateway port 9051.
[ OK ] Closed Syslog Socket.
[ OK ] Closed redirect 127.0.0.1:…1 to Whonix-Gateway port 9051.
[ OK ] Closed Activation socket for spice guest agent daemon.
[ OK ] Closed redirect 127.0.0.1:…1 to Whonix-Gateway port 9051.
[ OK ] Closed D-Bus System Message Bus Socket.
[ OK ] Closed redirect /run/anon-…k to Whonix-Gateway port 9051.
[ OK ] Closed redirect /run/tor/s…s to Whonix-Gateway port 9050.
[ OK ] Closed redirect /run/anon-…k to Whonix-Gateway port 9152.
[ OK ] Stopped target Slices.
[ OK ] Removed slice User and Session Slice.
[ OK ] Stopped target Paths.
[ OK ] Stopped target System Initialization.
Stopping Load/Save Random Seed…
[ OK ] Stopped target Local Encrypted Volumes.
[ OK ] Stopped Forward Password R…uests to Wall Directory Watch.
[ OK ] Stopped Dispatch Password …ts to Console Directory Watch.
Stopping Update UTMP about System Boot/Shutdown…
[ OK ] Stopped target Network.
Stopping Raise network interfaces…
[ OK ] Stopped Update UTMP about System Boot/Shutdown.
[ OK ] Stopped Create Volatile Files and Directories.
[ OK ] Stopped Load/Save Random Seed.
[ OK ] Stopped Raise network interfaces.
[ OK ] Stopped Apply Kernel Variables.
[ OK ] Stopped Load Kernel Modules.
[ OK ] Stopped target Network (Pre).
[ OK ] Stopped Whonix firewall loader.
[ OK ] Stopped target Local File Systems.
Unmounting /run/msgcollector…
Unmounting /mnt/shared…
[ OK ] Unmounted /run/msgcollector.
[ OK ] Stopped target Swap.
[ OK ] Unmounted /mnt/shared.
[ OK ] Reached target Unmount All Filesystems.
[ OK ] Stopped target Local File Systems (Pre).
[ OK ] Stopped Create Static Device Nodes in /dev.
[ OK ] Stopped Create System Users.
[ OK ] Stopped Remount Root and Kernel File Systems.
[ OK ] Reached target Shutdown.
[ OK ] Reached target Final Step.
[ OK ] Started Power-Off.
[ OK ] Reached target Power-Off.
[ 182.027879] systemd-shutdow: 56 output lines suppressed due to ratelimiting
[ 182.040537] systemd-shutdown[1]: Syncing filesystems and block devices.
[ 182.050605] systemd-shutdown[1]: Sending SIGTERM to remaining processes…
[ 182.053387] systemd-journald[225]: Received SIGTERM from PID 1 (systemd-shutdow).
[ 182.063320] systemd-shutdown[1]: Sending SIGKILL to remaining processes…
[ 182.065809] systemd-shutdown[1]: Unmounting file systems.
[ 182.068718] [2022]: Remounting ‘/’ read-only in with options ‘errors=remount-ro’.
[ 182.096126] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
[ 182.107165] systemd-shutdown[1]: All filesystems unmounted.
[ 182.108595] systemd-shutdown[1]: Deactivating swaps.
[ 182.109340] systemd-shutdown[1]: All swaps deactivated.
[ 182.110144] systemd-shutdown[1]: Detaching loop devices.
[ 182.110947] systemd-shutdown[1]: All loop devices detached.
[ 182.111690] systemd-shutdown[1]: Detaching DM devices.
[ 182.116445] kvm: exiting hardware virtualization
[ 182.227300] ACPI: Preparing to enter system sleep state S5
[ 182.228210] reboot: Power down

r@c:~$

[Patrick]

[ 91.529837] xfce4-panel[1231]: segfault at 8cf5a0c19 ip 00007d46c948cdb0 sp 00007ffeb342a4e0 error 4 in libc-2.28.so[7d46c942f000+148000]
[ 91.532063] Code: f0 48 01 f2 4c 8b 68 08 4d 89 ec 49 83 e4 f8 49 39 f4 0f 85 da 00 00 00 4e 3b 24 20 0f 85 c0 00 00 00 48 8b 70 10 4c 8b 60 18 <48> 39 46 18 0f 85 9e 00 00 00 49 39 44 24 10 0f 85 93 00 00 00 4c

This needs to be reported upstream against XFCE.

https://bugzilla.xfce.org/

They might ask for a backtrace.

https://docs.xfce.org/contribute/bugs/start#backtraces

Debian specific instructions:
https://wiki.debian.org/HowToGetABacktrace

For simplify, you’d need this package too:
debug-misc opt-in package to easily enable debugging

Let me know if anyone wants to proceed debugging this.

[rk1]

@Patrick:
The segfault, right, should be reported, sure, when I get my Whonix system hardened I might do this (will let you know by that time). But the original aim of reporting the console messages was to tackle the slow shutdown. So was the “stop job” with the timer the culprit here?

[Patrick]

Possibly.

Stopping Session 1 of user user.

Taking a long time on the screenshot. And just before that, the segfault.

I might be able to say more when systemd journal logs are provided as per Slow shutdown of version 15 workstation and gateway

[rk1]

@Patrick
I got the journals, but I cannot post them here: “Body is limited to 50000 characters; you entered 453602.”

[Patrick_mobile]

Use https://paste.debian.net or similar.

[Pingaeir]

@rk1 Thank you for also speaking up about this issue and picking up where I left off re giving the journal logs.

[rk1]

@Patrick
The files were too big for paste.debian also. So you can download them here:

Transfer Big Files

with password: 6WkznRLkYhs3bXQ

[Patrick]

Please don’t share otd (libre office or so) files. Plain text much better. However the workstation log was plain text and it gave another lead.

Last OK message:

Jan 24 11:12:08 host systemd[1]: Stopped Encrypted Swap File Creator.

Then takes a long time. Then:

Jan 24 11:13:38 host systemd[1]: session-1.scope: Stopping timed out. Killing.
Jan 24 11:13:38 host systemd[1]: session-1.scope: Killing process 1956 (lightdm) with signal SIGKILL.
Jan 24 11:13:38 host systemd[1]: session-1.scope: Failed with result ‘timeout’.
Jan 24 11:13:38 host systemd[1]: Stopped Session 1 of user user.

Would require debugging lightdm.

Quote https://wiki.debian.org/LightDM

lightdm is developed at Canonical by Robert Ancell

https://launchpad.net/~robert-ancell

Added some instruction on how to get a lightdm debug log just now:

https://www.whonix.org/wiki/Troubleshooting#lightdm

[rk1]

@Patrick
The light-dm Gateway log:

– Logs begin at Fri 2020-01-24 10:49:21 UTC, end at Sat 2020-01-25 07:59:34 UTC. –
Jan 25 07:56:45 host systemd[1]: Condition check resulted in Light Display Manager being skipped.
Jan 25 07:56:51 host systemd[1]: Starting Light Display Manager…
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Logging to /var/log/lightdm/lightdm.log
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Starting Light Display Manager 1.26.0, UID=0 PID=769
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration dirs from /usr/share/lightdm/lightdm.conf.d
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration from /usr/share/lightdm/lightdm.conf.d/01_debian.conf
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration dirs from /usr/local/share/lightdm/lightdm.conf.d
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration dirs from /etc/xdg/lightdm/lightdm.conf.d
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf.d/autologin.conf
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf.d/whonix-autologin.conf
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: [SeatDefaults] is now called [Seat:], please update this configuration
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Registered seat module local
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Registered seat module xremote
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Registered seat module unity
Jan 25 07:56:51 host lightdm[769]: [+0.00s] DEBUG: Using D-Bus name org.freedesktop.DisplayManager
Jan 25 07:56:51 host lightdm[769]: [+0.01s] DEBUG: Monitoring logind for seats
Jan 25 07:56:51 host lightdm[769]: [+0.01s] DEBUG: New seat added from logind: seat0
Jan 25 07:56:51 host lightdm[769]: [+0.01s] DEBUG: Seat seat0: Loading properties from config section Seat:
Jan 25 07:56:51 host lightdm[769]: [+0.01s] DEBUG: Seat seat0: Starting
Jan 25 07:56:51 host lightdm[769]: [+0.01s] DEBUG: Seat seat0: Creating user session
Jan 25 07:56:51 host lightdm[769]: [+0.02s] WARNING: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jan 25 07:56:51 host lightdm[769]: [+0.02s] DEBUG: Loading user config from /etc/lightdm/users.conf
Jan 25 07:56:51 host lightdm[769]: [+0.02s] DEBUG: User user added
Jan 25 07:56:51 host lightdm[769]: [+0.02s] DEBUG: Seat seat0: Creating display server of type x
Jan 25 07:56:51 host lightdm[769]: [+0.02s] DEBUG: posix_spawn avoided (fd close requested)
Jan 25 07:56:51 host systemd[1]: Started Light Display Manager.
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: Could not run plymouth --ping: Failed to execute child process ?plymouth? (No such file or directory)
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: Using VT 7
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: Seat seat0: Starting local X display on VT 7
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: XServer 0: Logging to /var/log/lightdm/x-0.log
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: XServer 0: Writing X server authority to /var/run/lightdm/root/:0
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: XServer 0: Launching X Server
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: Launching process 776: /usr/bin/X :0 -seat seat0 -auth /var/run/lightdm/root/:0 -nolisten tcp vt7 -novtswitch
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: XServer 0: Waiting for ready signal from X server :0
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: Acquired bus name org.freedesktop.DisplayManager
Jan 25 07:56:51 host lightdm[769]: [+0.03s] DEBUG: Registering seat with bus path /org/freedesktop/DisplayManager/Seat0
Jan 25 07:56:52 host lightdm[769]: [+0.58s] DEBUG: Got signal 10 from process 776
Jan 25 07:56:52 host lightdm[769]: [+0.58s] DEBUG: XServer 0: Got signal from X server :0
Jan 25 07:56:52 host lightdm[769]: [+0.58s] DEBUG: XServer 0: Connecting to XServer :0
Jan 25 07:56:52 host lightdm[769]: [+0.59s] DEBUG: posix_spawn avoided (fd close requested) (child_setup specified)
Jan 25 07:56:52 host lightdm[769]: [+0.59s] DEBUG: Seat seat0: Display server ready, starting session authentication
Jan 25 07:56:52 host lightdm[769]: [+0.59s] DEBUG: Session pid=788: Started with service ‘lightdm-autologin’, username ‘user’
Jan 25 07:56:52 host lightdm[788]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: Session pid=788: Authentication complete with return value 0: Success
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: Seat seat0: Session authenticated, running command
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: Registering session with bus path /org/freedesktop/DisplayManager/Session0
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: posix_spawn avoided (fd close requested) (child_setup specified)
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: Session pid=788: Running command /etc/X11/Xsession startxfce4
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: Creating shared data directory /var/lib/lightdm/data/user
Jan 25 07:56:52 host lightdm[769]: [+0.61s] DEBUG: Session pid=788: Logging to .xsession-errors
Jan 25 07:56:52 host lightdm[788]: pam_unix(lightdm-autologin:session): session opened for user user by (uid=0)
Jan 25 07:56:52 host lightdm[792]: pam_exec(lightdm-autologin:session): Calling /usr/lib/security-misc/permission-lockdown …
Jan 25 07:56:52 host lightdm[769]: [+0.94s] DEBUG: Activating VT 7
Jan 25 07:56:52 host lightdm[769]: [+0.94s] DEBUG: Activating login1 session 1
Jan 25 07:56:52 host lightdm[769]: [+0.95s] DEBUG: Seat seat0 changes active session to 1
Jan 25 07:56:52 host lightdm[769]: [+0.95s] DEBUG: Session 1 is already active
Jan 25 07:58:53 host systemd[1]: Stopping Light Display Manager…
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Got signal 15 from process 1
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Caught Terminated signal, shutting down
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Stopping display manager
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Seat seat0: Stopping
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Seat seat0: Stopping display server
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Sending signal 15 to process 776
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Seat seat0: Stopping session
Jan 25 07:58:53 host lightdm[769]: [+74.86s] DEBUG: Terminating login1 session 1
Jan 25 07:58:53 host lightdm[769]: [+74.87s] DEBUG: Session pid=788: Sending SIGTERM
Jan 25 07:58:53 host lightdm[769]: [+74.87s] DEBUG: Session pid=788: Exited with return value 0
Jan 25 07:58:53 host lightdm[769]: [+74.87s] DEBUG: Seat seat0: Session stopped
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Process 776 exited with return value 0
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: XServer 0: X server stopped
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Releasing VT 7
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: XServer 0: Removing X server authority /var/run/lightdm/root/:0
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Seat seat0: Display server stopped
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Seat seat0: Stopped
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Display manager stopped
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Stopping daemon
Jan 25 07:58:53 host lightdm[769]: [+74.96s] DEBUG: Exiting with return value 0
Jan 25 07:58:53 host systemd[1]: lightdm.service: Succeeded.
Jan 25 07:58:53 host systemd[1]: Stopped Light Display Manager.
Jan 25 07:59:10 host systemd[1]: Starting Light Display Manager…
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Logging to /var/log/lightdm/lightdm.log
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Starting Light Display Manager 1.26.0, UID=0 PID=2238
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration dirs from /usr/share/lightdm/lightdm.conf.d
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration from /usr/share/lightdm/lightdm.conf.d/01_debian.conf
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration dirs from /usr/local/share/lightdm/lightdm.conf.d
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration dirs from /etc/xdg/lightdm/lightdm.conf.d
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf.d/autologin.conf
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf.d/whonix-autologin.conf
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: [SeatDefaults] is now called [Seat:], please update this configuration
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Registered seat module local
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Registered seat module xremote
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Registered seat module unity
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Using D-Bus name org.freedesktop.DisplayManager
Jan 25 07:59:10 host lightdm[2238]: [+0.00s] DEBUG: Monitoring logind for seats
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: New seat added from logind: seat0
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Seat seat0: Loading properties from config section Seat:
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Seat seat0: Starting
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Seat seat0: Creating user session
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] WARNING: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Loading user config from /etc/lightdm/users.conf
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: User user added
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Seat seat0: Creating display server of type x
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: posix_spawn avoided (fd close requested)
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Could not run plymouth --ping: Failed to execute child process ?plymouth? (No such file or directory)
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Using VT 7
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Seat seat0: Starting local X display on VT 7
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: XServer 0: Logging to /var/log/lightdm/x-0.log
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: XServer 0: Writing X server authority to /var/run/lightdm/root/:0
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: XServer 0: Launching X Server
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Launching process 2244: /usr/bin/X :0 -seat seat0 -auth /var/run/lightdm/root/:0 -nolisten tcp vt7 -novtswitch
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: XServer 0: Waiting for ready signal from X server :0
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Acquired bus name org.freedesktop.DisplayManager
Jan 25 07:59:10 host lightdm[2238]: [+0.01s] DEBUG: Registering seat with bus path /org/freedesktop/DisplayManager/Seat0
Jan 25 07:59:10 host systemd[1]: Started Light Display Manager.
Jan 25 07:59:10 host lightdm[2238]: [+0.16s] DEBUG: Got signal 10 from process 2244
Jan 25 07:59:10 host lightdm[2238]: [+0.16s] DEBUG: XServer 0: Got signal from X server :0
Jan 25 07:59:10 host lightdm[2238]: [+0.16s] DEBUG: XServer 0: Connecting to XServer :0
Jan 25 07:59:10 host lightdm[2238]: [+0.17s] DEBUG: posix_spawn avoided (fd close requested) (child_setup specified)
Jan 25 07:59:10 host lightdm[2238]: [+0.17s] DEBUG: Seat seat0: Display server ready, starting session authentication
Jan 25 07:59:10 host lightdm[2238]: [+0.17s] DEBUG: Session pid=2251: Started with service ‘lightdm-autologin’, username ‘user’
Jan 25 07:59:10 host lightdm[2251]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: Session pid=2251: Authentication complete with return value 0: Success
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: Seat seat0: Session authenticated, running command
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: Registering session with bus path /org/freedesktop/DisplayManager/Session0
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: posix_spawn avoided (fd close requested) (child_setup specified)
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: Session pid=2251: Running command /etc/X11/Xsession startxfce4
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: Creating shared data directory /var/lib/lightdm/data/user
Jan 25 07:59:10 host lightdm[2238]: [+0.18s] DEBUG: Session pid=2251: Logging to .xsession-errors
Jan 25 07:59:10 host lightdm[2251]: pam_unix(lightdm-autologin:session): session opened for user user by (uid=0)
Jan 25 07:59:10 host lightdm[2255]: pam_exec(lightdm-autologin:session): Calling /usr/lib/security-misc/permission-lockdown …
Jan 25 07:59:10 host lightdm[2238]: [+0.23s] DEBUG: Activating VT 7
Jan 25 07:59:10 host lightdm[2238]: [+0.23s] DEBUG: Activating login1 session 4
Jan 25 07:59:10 host lightdm[2238]: [+0.23s] DEBUG: Seat seat0 changes active session to 4
Jan 25 07:59:10 host lightdm[2238]: [+0.23s] DEBUG: Session 4 is already active

---

and the Workstation log:

– Logs begin at Fri 2020-01-24 11:09:41 UTC, end at Sat 2020-01-25 08:15:23 UTC. –
Jan 25 08:14:09 host systemd[1]: Condition check resulted in Light Display Manager being skipped.
Jan 25 08:14:09 host systemd[1]: Starting Light Display Manager…
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Logging to /var/log/lightdm/lightdm.log
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Starting Light Display Manager 1.26.0, UID=0 PID=673
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration dirs from /usr/share/lightdm/lightdm.conf.d
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration from /usr/share/lightdm/lightdm.conf.d/01_debian.conf
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration dirs from /usr/local/share/lightdm/lightdm.conf.d
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration dirs from /etc/xdg/lightdm/lightdm.conf.d
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf.d/autologin.conf
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf.d/whonix-autologin.conf
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: [SeatDefaults] is now called [Seat:], please update this configuration
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Loading configuration from /etc/lightdm/lightdm.conf
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Registered seat module local
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Registered seat module xremote
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Registered seat module unity
Jan 25 08:14:09 host lightdm[673]: [+0.00s] DEBUG: Using D-Bus name org.freedesktop.DisplayManager
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: Monitoring logind for seats
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: New seat added from logind: seat0
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: Seat seat0: Loading properties from config section Seat:
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: Seat seat0: Starting
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: Seat seat0: Creating user session
Jan 25 08:14:09 host lightdm[673]: [+0.01s] WARNING: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: Loading user config from /etc/lightdm/users.conf
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: User user added
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: Seat seat0: Creating display server of type x
Jan 25 08:14:09 host lightdm[673]: [+0.01s] DEBUG: posix_spawn avoided (fd close requested)
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: Could not run plymouth --ping: Failed to execute child process ?plymouth? (No such file or directory)
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: Using VT 7
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: Seat seat0: Starting local X display on VT 7
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: XServer 0: Logging to /var/log/lightdm/x-0.log
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: XServer 0: Writing X server authority to /var/run/lightdm/root/:0
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: XServer 0: Launching X Server
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: Launching process 684: /usr/bin/X :0 -seat seat0 -auth /var/run/lightdm/root/:0 -nolisten tcp vt7 -novtswitch
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: XServer 0: Waiting for ready signal from X server :0
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: Acquired bus name org.freedesktop.DisplayManager
Jan 25 08:14:09 host lightdm[673]: [+0.02s] DEBUG: Registering seat with bus path /org/freedesktop/DisplayManager/Seat0
Jan 25 08:14:09 host systemd[1]: Started Light Display Manager.
Jan 25 08:14:10 host lightdm[673]: [+0.66s] DEBUG: Got signal 10 from process 684
Jan 25 08:14:10 host lightdm[673]: [+0.66s] DEBUG: XServer 0: Got signal from X server :0
Jan 25 08:14:10 host lightdm[673]: [+0.66s] DEBUG: XServer 0: Connecting to XServer :0
Jan 25 08:14:10 host lightdm[673]: [+0.66s] DEBUG: posix_spawn avoided (fd close requested) (child_setup specified)
Jan 25 08:14:10 host lightdm[673]: [+0.67s] DEBUG: Seat seat0: Display server ready, starting session authentication
Jan 25 08:14:10 host lightdm[673]: [+0.67s] DEBUG: Session pid=762: Started with service ‘lightdm-autologin’, username ‘user’
Jan 25 08:14:10 host lightdm[762]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: Session pid=762: Authentication complete with return value 0: Success
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: Seat seat0: Session authenticated, running command
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: Registering session with bus path /org/freedesktop/DisplayManager/Session0
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: posix_spawn avoided (fd close requested) (child_setup specified)
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: Session pid=762: Running command /etc/X11/Xsession startxfce4
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: Creating shared data directory /var/lib/lightdm/data/user
Jan 25 08:14:10 host lightdm[673]: [+0.73s] DEBUG: Session pid=762: Logging to .xsession-errors
Jan 25 08:14:10 host lightdm[762]: pam_unix(lightdm-autologin:session): session opened for user user by (uid=0)
Jan 25 08:14:10 host lightdm[769]: pam_exec(lightdm-autologin:session): Calling /usr/lib/security-misc/permission-lockdown …
Jan 25 08:14:10 host lightdm[673]: [+1.01s] DEBUG: Activating VT 7
Jan 25 08:14:10 host lightdm[673]: [+1.01s] DEBUG: Activating login1 session 1
Jan 25 08:14:10 host lightdm[673]: [+1.01s] DEBUG: Seat seat0 changes active session to 1
Jan 25 08:14:10 host lightdm[673]: [+1.01s] DEBUG: Session 1 is already active

[Patrick]

That log looks very similar to mine and I don’t see any errors there. Looks like proper termination.

I have no idea.

I suggest to report a bug against Debian.

And/or upstream original developers.

Quote https://wiki.debian.org/LightDM

lightdm is developed at Canonical by Robert Ancell

https://launchpad.net/~robert-ancell

They might ask for a backtrace.

(similar this post Slow shutdown of version 15 workstation and gateway)

[rk1]

@Patrick
I want to report the bug like you suggested, but how to do so is not crystal clear. First problem I have is you say it is a lightdm bug. How do you know it is? Second when I go to launchpad -> robert-ancell, I see lightdm is one of his packages. But everywhere it says lightdm in Ubuntu. And the problem of slow shutdown arises in Whonix. Or you mean to say that lightdm as used in Whonix is the same lightdm used in Ubuntu?

[Patrick]

First problem I have is you say it is a lightdm bug. How do you know it is?

Because the log contains:

Jan 24 11:13:38 host systemd[1]: session-1.scope: Killing process 1956 (lightdm) with signal SIGKILL.

This means systemd sends the usual sigterm signal which lightdm ignores and then when that didn’t work it sends the sigkill command. I.e. lightdm does not react to sigterm for some reason.

Or you mean to say that lightdm as used in Whonix is the same lightdm used in Ubuntu?

No. Lightdm from Debian https://packages.debian.org/buster/lightdm

You could report to Debian too and the Debian maintainer should in theory forward the bug upstream. But my experience is this takes a long time. It is useful to report bugs to Debian. Often the maintainer or someone knows something. But contacting the upstream developer directly makes sense too.

I want to report the bug like you suggested, but how to do so is not crystal clear.

Don’t assume it’s more clear to me.
Just contact upstream somehow. Then see how it goes. Preferably in public to have a reference and give others to share their thoughts well. I’d open a bug on launchpad even if’s not 100% correct that’s not a crime. If it is the wrong place, someone will point out a better place.

[rk1]

Ok, filed a bug-report on launchpad with Robert Ancell.

[Patrick]

Link?

[rk1]

link bug (slow shutdown Whonix) on launchpad:
https://bugs.launchpad.net/ubuntu/+source/lightdm/+bug/1860915

[Pingaeir]

I am currently avoiding the problem by just forcing shutdown for WS/GW. It is ugly but I only use read-only version so dataloss is not an issue.

[HulaHoop]

Me too bro.

[Pingaeir]

Hey guys, heads up, I have found the solution.

Just up your ram to 2gigs. It has solved the issue for me.

I wanted to increase specs on the workstation for other reasons and noticed this problem was not occuring any more and so upped it to 2gb on the gateway as well and it seems to have fixed it there too.

EDIT: Just switching to 2 gig didn’t seem to do it, but increasing cpu too seems to have worked, if not that then keep upping them til it does as the workstation works consistently and I have that at 4 cpu/4 gig I think.

Though it depends how much you wanna use just to solve that issue :). My local machine rarely is needed for much so I can afford to use it for the short instances I want to run whonix.

[HulaHoop]

@Pingaeir

EDIT: Just switching to 2 gig didn’t seem to do it, but increasing cpu too seems to have worked, if not that then keep upping them til it does as the workstation works consistently and I have that at 4 cpu/4 gig I think.

If you are increasing cpu count while pinned then you won;t be really getting any increased perf benefit.