Ubuntu logon fails but Azure AD says successful

AllDeesScripts commented 1 year ago

Hi, Getting the error "Sorry, password authentication didn't work. Please try again." when I logon with valid Azure AD credentials.

Screenshot: https://imgur.com/DxNAHEA

The Enterprise App sign-in log says the user was successfully authenticated as per this screenshot: https://imgur.com/3DmRBmz

Are you able to deduce anything from the log below?

Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_unix(gdm-password:auth): check pass; user unknown
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_unix(gdm-password:auth): authentication failure; logname= uid=0 euid=0 tty=/dev/tty1 ruser= rhost=
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): aad auth debug enabled
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): PAM AAD DEBUG enabled
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Loading configuration from /etc/aad.conf
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Connecting to "https://login.microsoftonline.com/c33f12c3-[xxxx]-[xxxx]-[xxxxxxxxxxx]__;!!HUqgN_M!t1cV7xJo6StWoMrqM2v0Cxt7AakkGylgPpOjh4NQTDWCtAKhwiR14stt7glsaEdU8xzRTNd5KIIJWQOn9Fb3ovo174syK8Vrc2nnsL-E$ ", with clientID "4613665f-[xxxx]-[xxxx]-[xxxxxxxxxxx]" for user "adelev@[redacted].onmicrosoft.com"
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Authentication successful with user/password
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Cache initialization
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Opening cache in /var/lib/aad/cache
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): check file permissions on /var/lib/aad/cache/passwd.db
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): check file permissions on /var/lib/aad/cache/shadow.db
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Shadow db mode: 2
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Cleaning up db. Removing entries that last authenticated online more than 180 days ago
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): getting user information from cache for "adelev@[redacted].onmicrosoft.com"
Apr 26 16:22:01 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): encrypt password for user "adelev@[redacted].onmicrosoft.com"
Apr 26 16:22:02 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): updating from last online login information for user "adelev@[redacted].onmicrosoft.com"
Apr 26 16:22:02 Ubuntu gdm-password][6306]: pam_aad(gdm-password:auth): Close database request
Apr 26 16:22:02 Ubuntu gdm-password][6306]: gkr-pam: error looking up user information
Apr 26 16:22:02 Ubuntu gdm-password][6306]: pam_unix(gdm-password:account): could not identify user (from getpwnam(adelev@[redacted].onmicrosoft.com))
Apr 26 16:22:02 Ubuntu gdm-password][6325]: accountsservice: ActUserManager: user (null) has no username (uid: -1)
Apr 26 16:22:05 Ubuntu geoclue[4387]: Service not used for 60 seconds. Shutting down..
Apr 26 16:22:05 Ubuntu systemd[1]: geoclue.service: Deactivated successfully.

denisonbarbosa commented 1 year ago

Hey @AllDeesScripts, thanks for reporting the issue! First, I just want to ask something before asking you for more logs: did you also install the libnss-aad package?

AllDeesScripts commented 1 year ago

Hi @denisonbarbosa,

It is showing as installed:

xxxxxx@Ubuntu:~$ dpkg -s libnss-aad Package: libnss-aad Status: install ok installed Priority: optional Section: admin Installed-Size: 620 Maintainer: Ubuntu Developers ubuntu-devel-discuss@lists.ubuntu.com Architecture: amd64 Multi-Arch: same Source: aad-auth Version: 0.4 Depends: aad-common, libc6 (>= 2.34), libgcc-s1 (>= 4.2), libsqlite3-0 (>= 3.6.0) Description: Azure Active Directory Authentication module for NSS Azure Active Directory Authentication enables authentication of Azure Active Directory accounts on your local system. It dynamically creates local accounts, supporting additional features like offline connectivity. . This package contains the NSS module. Built-Using: golang-1.20 (= 1.20.1-1) Homepage: https://github.com/ubuntu/aad-auth

denisonbarbosa commented 1 year ago

Alright, thanks for checking! The line gkr-pam: error looking up user information indicates that pam is not being able to query the database for the user info. Can you check the output of: getent passwd {your_user_name}?

If it is on the cache and the NSS module is linked properly (you can check by looking in /etc/nsswitch.conf, you should see something like: passwd: files systemd sss aad), the command should return the database entry, otherwise it will return nothing.

Also, can you set the environment variable NSS_AAD_DEBUG to 1 if you didn't already, try authenticating again and then provide the logs? This will help us debug the NSS module as well, where seems like the problem is.

AllDeesScripts commented 1 year ago

Thank you for the detailed steps. I have the below outputs for you:

getent I did this with the local user first as a control test and then the Azure AD user. The Azure AD user did not return anything.

xxxxx@Ubuntu:~$ getent passwd xxxxx
xxxxx:x:1000:1000:Authorised User,,,:/home/xxxxx:/bin/bash

xxxxx@Ubuntu:~$ getent passwd adelev@XXXX.onmicrosoft.com
xxxxx@Ubuntu:~$

nsswitch.conf

xxxxx@Ubuntu:~$ cat /etc/nsswitch.conf
# /etc/nsswitch.conf
#
# Example configuration of GNU Name Service Switch functionality.
# If you have the `glibc-doc-reference' and `info' packages installed, try:
# `info libc "Name Service Switch"' for information about this file.

passwd:         files systemd sss aad
group:          files systemd sss aad
shadow:         files sss aad
gshadow:        files

hosts:          files mdns4_minimal [NOTFOUND=return] dns
networks:       files

protocols:      db files
services:       db files sss
ethers:         db files
rpc:            db files

netgroup:       nis sss
automount:      sss

journalctl Authentication attempt is at 14:27


Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: State 'stop-sigterm' timed out. Killing.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1424 (gnome-shell) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1440 (pool-spawner) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1441 (gmain) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1443 (gdbus) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1447 (llvmpipe-0) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1448 (llvmpipe-1) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1449 (llvmpipe-2) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1450 (llvmpipe-3) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1451 (gnome-shell) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1452 (gnome-shell) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1453 (gnome-shell) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1454 (gnome-shell) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1458 (gnome-s:disk$0) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1461 (dconf worker) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1462 (gnome-shell) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1545 (JS Helper) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1546 (JS Helper) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1547 (JS Helper) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 1548 (JS Helper) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 12836 (threaded-ml) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Killing process 13101 (pool-gnome-shel) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu update-notifier[2128]: Error reading events from display: Broken pipe
Apr 28 14:26:32 Ubuntu snapd-desktop-i[4302]: Error reading events from display: Broken pipe
Apr 28 14:26:32 Ubuntu systemd[1256]: app-gnome-update\x2dnotifier-2128.scope: Consumed 2.671s CPU time.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Main process exited, code=killed, status=9/KILL
Apr 28 14:26:32 Ubuntu polkitd(authority=local)[478]: Unregistered Authentication Agent for unix-session:2 (system bus name :1.72, object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_GB.UTF-8) (disconnected from bus)
Apr 28 14:26:32 Ubuntu xdg-desktop-por[1905]: Error reading events from display: Broken pipe
Apr 28 14:26:32 Ubuntu systemd[1256]: xdg-desktop-portal-gnome.service: Main process exited, code=exited, status=1/FAILURE
Apr 28 14:26:32 Ubuntu systemd[1256]: xdg-desktop-portal-gnome.service: Failed with result 'exit-code'.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Failed with result 'timeout'.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped org.gnome.Shell@wayland.service - GNOME Shell on Wayland.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Triggering OnFailure= dependencies.
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell@wayland.service: Consumed 1min 48.914s CPU time.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped target gnome-session-manager.target - GNOME Session Manager is ready.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped target gnome-session-pre.target - Tasks to be run before GNOME Session starts.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped target graphical-session-pre.target - Session services which should run early before the graphical session is brought up.
Apr 28 14:26:32 Ubuntu systemd[1256]: Reached target gnome-session-shutdown.target - Shutdown running GNOME Session.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopping gnome-session-monitor.service - Monitor Session leader for GNOME Session...
Apr 28 14:26:32 Ubuntu systemd[1256]: Starting gnome-session-restart-dbus.service - Restart DBus after GNOME Session shutdown...
Apr 28 14:26:32 Ubuntu systemd[1256]: org.gnome.Shell-disable-extensions.service - Disable GNOME Shell extensions after failure was skipped because of an unmet condition check (ConditionPathExists=/run/user/1000/gnome-shell-disable-extensions).
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped target gnome-session-shutdown.target - Shutdown running GNOME Session.
Apr 28 14:26:32 Ubuntu systemd[1256]: Reached target gnome-session-shutdown.target - Shutdown running GNOME Session.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped target gnome-session-shutdown.target - Shutdown running GNOME Session.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped gnome-session-monitor.service - Monitor Session leader for GNOME Session.
Apr 28 14:26:32 Ubuntu systemd[1256]: Started gnome-session-restart-dbus.service - Restart DBus after GNOME Session shutdown.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopping dbus.service - D-Bus User Message Bus...
Apr 28 14:26:32 Ubuntu io.snapcraft.Settings[2453]: Exiting on terminated.
Apr 28 14:26:32 Ubuntu evolution-addre[1595]: Error setting property 'ConnectionStatus' on interface org.gnome.evolution.dataserver.Source: The connection is closed (g-io-error-quark, 18)
Apr 28 14:26:32 Ubuntu gdm-password][1245]: pam_unix(gdm-password:session): session closed for user XXXX
Apr 28 14:26:32 Ubuntu systemd[1256]: dbus.service: Killing process 1496 (pool-spawner) with signal SIGKILL.
Apr 28 14:26:32 Ubuntu systemd[1256]: Stopped dbus.service - D-Bus User Message Bus.
Apr 28 14:26:32 Ubuntu systemd[1256]: dbus.service: Consumed 2.807s CPU time.
Apr 28 14:26:32 Ubuntu systemd[1256]: Starting dbus.service - D-Bus User Message Bus...
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: Log output set to syslog
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: Log level set to Debug
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: get_all_entries for group
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: opening database connection from /var/lib/aad/cache
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions
Apr 28 14:26:32 Ubuntu aad_auth[13389]: nss_aad: found record: Group { name: "adelev@XXXX.onmicrosoft.com", passwd: "x", gid: 100000, members: ["adelev@XXXX.onmicrosoft.com"] }
Apr 28 14:26:32 Ubuntu systemd[1]: session-2.scope: Deactivated successfully.
Apr 28 14:26:32 Ubuntu dbus-daemon[13389]: [session uid=1000 pid=13389] AppArmor D-Bus mediation is enabled
Apr 28 14:26:32 Ubuntu systemd[1256]: Started dbus.service - D-Bus User Message Bus.
Apr 28 14:26:32 Ubuntu systemd-logind[495]: Session 2 logged out. Waiting for processes to exit.
Apr 28 14:26:32 Ubuntu systemd-logind[495]: Removed session 2.
Apr 28 14:26:32 Ubuntu tracker-miner-fs-3[1352]: OK
Apr 28 14:26:32 Ubuntu gdm-launch-environment][13392]: pam_env(gdm-launch-environment:session): deprecated reading of user environment enabled
Apr 28 14:26:32 Ubuntu gdm-launch-environment][13392]: pam_unix(gdm-launch-environment:session): session opened for user gdm(uid=127) by (uid=0)
Apr 28 14:26:32 Ubuntu systemd-logind[495]: New session c2 of user gdm.
Apr 28 14:26:32 Ubuntu systemd[1]: Created slice user-127.slice - User Slice of UID 127.
Apr 28 14:26:32 Ubuntu systemd[1]: Starting user-runtime-dir@127.service - User Runtime Directory /run/user/127...
Apr 28 14:26:32 Ubuntu systemd[1]: Finished user-runtime-dir@127.service - User Runtime Directory /run/user/127.
Apr 28 14:26:32 Ubuntu systemd[1]: Starting user@127.service - User Manager for UID 127...
Apr 28 14:26:32 Ubuntu systemd[13452]: pam_unix(systemd-user:session): session opened for user gdm(uid=127) by (uid=0)
Apr 28 14:26:32 Ubuntu systemd-xdg-autostart-generator[13490]: Exec binary 'start-pulseaudio-x11' does not exist: No such file or directory
Apr 28 14:26:32 Ubuntu systemd-xdg-autostart-generator[13490]: /etc/xdg/autostart/pulseaudio.desktop: not generating unit, error parsing Exec= line: No such file or directory
Apr 28 14:26:32 Ubuntu systemd-xdg-autostart-generator[13490]: Exec binary '/usr/libexec/gnome-shell-overrides-migration.sh' does not exist: No such file or directory
Apr 28 14:26:32 Ubuntu systemd-xdg-autostart-generator[13490]: /etc/xdg/autostart/gnome-shell-overrides-migration.desktop: not generating unit, error parsing Exec= line: No such file or directory
Apr 28 14:26:32 Ubuntu systemd[13452]: Queued start job for default target default.target.
Apr 28 14:26:32 Ubuntu systemd[13452]: Created slice app.slice - User Application Slice.
Apr 28 14:26:32 Ubuntu systemd[13452]: Created slice session.slice - User Core Session Slice.
Apr 28 14:26:32 Ubuntu systemd[13452]: Created slice background.slice - User Background Tasks Slice.
Apr 28 14:26:32 Ubuntu systemd[13452]: Started ubuntu-report.path - Pending report trigger for Ubuntu Report.
Apr 28 14:26:32 Ubuntu systemd[13452]: Reached target paths.target - Paths.
Apr 28 14:26:32 Ubuntu systemd[13452]: Reached target timers.target - Timers.
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting dbus.socket - D-Bus User Message Bus Socket...
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on dirmngr.socket - GnuPG network certificate management daemon.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on gcr-ssh-agent.socket - GCR ssh-agent wrapper.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on gnome-keyring-daemon.socket - GNOME Keyring daemon.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers).
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted).
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation).
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on pipewire-pulse.socket - PipeWire PulseAudio.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on pipewire.socket - PipeWire Multimedia System Socket.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on pk-debconf-helper.socket - debconf communication socket.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on snapd.session-agent.socket - REST API socket for snapd user session agent.
Apr 28 14:26:32 Ubuntu systemd[13452]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Apr 28 14:26:32 Ubuntu systemd[13452]: Reached target sockets.target - Sockets.
Apr 28 14:26:32 Ubuntu systemd[13452]: Reached target basic.target - Basic System.
Apr 28 14:26:32 Ubuntu systemd[1]: Started user@127.service - User Manager for UID 127.
Apr 28 14:26:32 Ubuntu systemd[1]: Started session-c2.scope - Session c2 of User gdm.
Apr 28 14:26:32 Ubuntu systemd[13452]: Started pipewire.service - PipeWire Multimedia Service.
Apr 28 14:26:32 Ubuntu systemd[13452]: Started snap.snapd-desktop-integration.snapd-desktop-integration.service - Service for snap application snapd-desktop-integration.snapd-desktop-integration.
Apr 28 14:26:32 Ubuntu systemd[13452]: Started snapd.aa-prompt-ui.service - snapd UI prompt.
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting tracker-extract-3.service - Tracker metadata extractor...
Apr 28 14:26:32 Ubuntu systemd[13452]: Started wireplumber.service - Multimedia Service Session Manager.
Apr 28 14:26:32 Ubuntu systemd[13452]: Started pipewire-pulse.service - PipeWire PulseAudio.
Apr 28 14:26:32 Ubuntu snapd-aa-prompt-ui[13526]: AA Prompt UI not implemented
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting dbus.service - D-Bus User Message Bus...
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: Log output set to syslog
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: Log level set to Debug
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: get_all_entries for group
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: opening database connection from /var/lib/aad/cache
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions
Apr 28 14:26:32 Ubuntu aad_auth[13544]: nss_aad: found record: Group { name: "adelev@XXXX.onmicrosoft.com", passwd: "x", gid: 100000, members: ["adelev@XXXX.onmicrosoft.com"] }
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] AppArmor D-Bus mediation is enabled
Apr 28 14:26:32 Ubuntu systemd[13452]: Started dbus.service - D-Bus User Message Bus.
Apr 28 14:26:32 Ubuntu wireplumber[13530]: Can't find org.freedesktop.portal.Desktop. Is xdg-desktop-portal running?
Apr 28 14:26:32 Ubuntu wireplumber[13530]: found session bus but no portal
Apr 28 14:26:32 Ubuntu pipewire[13522]: mod.rt: Can't find org.freedesktop.portal.Desktop. Is xdg-desktop-portal running?
Apr 28 14:26:32 Ubuntu pipewire[13522]: mod.rt: found session bus but no portal
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.freedesktop.portal.Documents' unit='xdg-document-portal.service' requested by ':1.4' (uid=127 pid=13523 comm="/usr/bin/snap run snapd-desktop-integration" label="unconfined")
Apr 28 14:26:32 Ubuntu pipewire-pulse[13532]: mod.rt: Can't find org.freedesktop.portal.Desktop. Is xdg-desktop-portal running?
Apr 28 14:26:32 Ubuntu pipewire-pulse[13532]: mod.rt: found session bus but no portal
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.6' (uid=127 pid=13529 comm="/usr/libexec/tracker-extract-3" label="unconfined")
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting gvfs-daemon.service - Virtual filesystem service...
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting xdg-document-portal.service - flatpak document portal service...
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: Log output set to syslog
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: Log level set to Debug
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: get_all_entries for group
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: opening database connection from /var/lib/aad/cache
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gtk.vfs.Daemon'
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service' requested by ':1.8' (uid=127 pid=13560 comm="/usr/libexec/xdg-document-portal" label="unconfined")
Apr 28 14:26:32 Ubuntu systemd[13452]: Started gvfs-daemon.service - Virtual filesystem service.
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting xdg-permission-store.service - sandboxed app permission store...
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.freedesktop.portal.Documents'
Apr 28 14:26:32 Ubuntu xdg-document-portal[13560]: Ignoring invalid max threads value 4294967295 > max (100000).
Apr 28 14:26:32 Ubuntu systemd[13452]: Started xdg-permission-store.service - sandboxed app permission store.
Apr 28 14:26:32 Ubuntu systemd[13452]: Started xdg-document-portal.service - flatpak document portal service.
Apr 28 14:26:32 Ubuntu audit[13523]: AVC apparmor="DENIED" operation="capable" class="cap" profile="/usr/lib/snapd/snap-confine" pid=13523 comm="snap-confine" capability=12  capname="net_admin"
Apr 28 14:26:32 Ubuntu audit[13523]: AVC apparmor="DENIED" operation="capable" class="cap" profile="/usr/lib/snapd/snap-confine" pid=13523 comm="snap-confine" capability=38  capname="perfmon"
Apr 28 14:26:32 Ubuntu kernel: audit: type=1400 audit(1682688392.886:90): apparmor="DENIED" operation="capable" class="cap" profile="/usr/lib/snapd/snap-confine" pid=13523 comm="snap-confine" capability=12  capname="net_admin"
Apr 28 14:26:32 Ubuntu kernel: audit: type=1400 audit(1682688392.886:91): apparmor="DENIED" operation="capable" class="cap" profile="/usr/lib/snapd/snap-confine" pid=13523 comm="snap-confine" capability=38  capname="perfmon"
Apr 28 14:26:32 Ubuntu snapd-desktop-integration.snapd-desktop-integration[13523]: Sorry, home directories outside of /home needs configuration.
Apr 28 14:26:32 Ubuntu snapd-desktop-integration.snapd-desktop-integration[13523]: See https://forum.snapcraft.io/t/11209__;!!HUqgN_M!pDroxWBTNo43I82cdJhVvX6AHFz4C3x87et3f6i8fyvOxe3uw1-ePOHO5gsJpfCciVE2v2DYyoHt5oW27EoU423uZmnL1hmyFbizM_S5$  for details.
Apr 28 14:26:32 Ubuntu systemd[13452]: snap.snapd-desktop-integration.snapd-desktop-integration.service: Main process exited, code=exited, status=1/FAILURE
Apr 28 14:26:32 Ubuntu systemd[13452]: snap.snapd-desktop-integration.snapd-desktop-integration.service: Failed with result 'exit-code'.
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.freedesktop.Tracker3.Miner.Files' unit='tracker-miner-fs-3.service' requested by ':1.6' (uid=127 pid=13529 comm="/usr/libexec/tracker-extract-3" label="unconfined")
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting tracker-miner-fs-3.service - Tracker file system data miner...
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Successfully made thread 13530 of process 13530 owned by '127' high priority at nice level -11.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 7 threads of 4 processes of 2 users.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &DOCUMENTS. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &MUSIC. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &PICTURES. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &VIDEOS. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &DOWNLOAD. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &DOCUMENTS. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &MUSIC. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &PICTURES. Ignoring this location.
Apr 28 14:26:32 Ubuntu tracker-miner-f[13604]: Unable to get XDG user directory path for special directory &VIDEOS. Ignoring this location.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Successfully made thread 13522 of process 13522 owned by '127' high priority at nice level -11.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 8 threads of 5 processes of 2 users.
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.11' (uid=127 pid=13604 comm="/usr/libexec/tracker-miner-fs-3" label="unconfined")
Apr 28 14:26:32 Ubuntu systemd[13452]: Starting gvfs-udisks2-volume-monitor.service - Virtual filesystem service - disk device monitor...
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Successfully made thread 13532 of process 13532 owned by '127' high priority at nice level -11.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 9 threads of 6 processes of 2 users.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 9 threads of 6 processes of 2 users.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 9 threads of 6 processes of 2 users.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 9 threads of 6 processes of 2 users.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 9 threads of 6 processes of 2 users.
Apr 28 14:26:32 Ubuntu rtkit-daemon[788]: Supervising 9 threads of 6 processes of 2 users.
Apr 28 14:26:32 Ubuntu aad_auth[13561]: nss_aad: found record: Group { name: "adelev@XXXX.onmicrosoft.com", passwd: "x", gid: 100000, members: ["adelev@XXXX.onmicrosoft.com"] }
Apr 28 14:26:32 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
Apr 28 14:26:32 Ubuntu systemd[13452]: Started gvfs-udisks2-volume-monitor.service - Virtual filesystem service - disk device monitor.
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.11' (uid=127 pid=13604 comm="/usr/libexec/tracker-miner-fs-3" label="unconfined")
Apr 28 14:26:33 Ubuntu systemd[13452]: Starting gvfs-afc-volume-monitor.service - Virtual filesystem service - Apple File Conduit monitor...
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
Apr 28 14:26:33 Ubuntu systemd[13452]: Started gvfs-afc-volume-monitor.service - Virtual filesystem service - Apple File Conduit monitor.
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.11' (uid=127 pid=13604 comm="/usr/libexec/tracker-miner-fs-3" label="unconfined")
Apr 28 14:26:33 Ubuntu systemd[13452]: Starting gvfs-gphoto2-volume-monitor.service - Virtual filesystem service - digital camera monitor...
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
Apr 28 14:26:33 Ubuntu systemd[13452]: Started gvfs-gphoto2-volume-monitor.service - Virtual filesystem service - digital camera monitor.
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.11' (uid=127 pid=13604 comm="/usr/libexec/tracker-miner-fs-3" label="unconfined")
Apr 28 14:26:33 Ubuntu systemd[13452]: Starting gvfs-goa-volume-monitor.service - Virtual filesystem service - GNOME Online Accounts monitor...
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating service name='org.gnome.OnlineAccounts' requested by ':1.17' (uid=127 pid=13643 comm="/usr/libexec/gvfs-goa-volume-monitor" label="unconfined")
Apr 28 14:26:33 Ubuntu goa-daemon[13649]: goa-daemon version 3.48.0 starting
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating service name='org.gnome.Identity' requested by ':1.18' (uid=127 pid=13649 comm="/usr/libexec/goa-daemon" label="unconfined")
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gnome.OnlineAccounts'
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
Apr 28 14:26:33 Ubuntu systemd[13452]: Started gvfs-goa-volume-monitor.service - Virtual filesystem service - GNOME Online Accounts monitor.
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Running GNOME Shell (using mutter 44.0) as a Wayland display server
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gnome.Identity'
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.11' (uid=127 pid=13604 comm="/usr/libexec/tracker-miner-fs-3" label="unconfined")
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Successfully made thread 13624 of process 13522 owned by '127' RT at priority 20.
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Supervising 10 threads of 6 processes of 2 users.
Apr 28 14:26:33 Ubuntu systemd[13452]: Starting gvfs-mtp-volume-monitor.service - Virtual filesystem service - Media Transfer Protocol monitor...
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
Apr 28 14:26:33 Ubuntu systemd[13452]: Started gvfs-mtp-volume-monitor.service - Virtual filesystem service - Media Transfer Protocol monitor.
Apr 28 14:26:33 Ubuntu org.gnome.Shell.desktop[13651]: pci id for fd 10: 1414:0006, driver (null)
Apr 28 14:26:33 Ubuntu org.gnome.Shell.desktop[13651]: MESA-LOADER: failed to open hyperv_drm: /usr/lib/dri/hyperv_drm_dri.so: cannot open shared object file: No such file or directory (search paths /usr/lib/x86_64-linux-gnu/dri:\$${ORIGIN}/dri:/usr/lib/dri, suffix _dri)
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Successfully made thread 13623 of process 13530 owned by '127' RT at priority 20.
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Supervising 11 threads of 6 processes of 2 users.
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Supervising 11 threads of 6 processes of 2 users.
Apr 28 14:26:33 Ubuntu org.gnome.Shell.desktop[13651]: pci id for fd 11: 1414:0006, driver (null)
Apr 28 14:26:33 Ubuntu org.gnome.Shell.desktop[13651]: kmsro: driver missing
Apr 28 14:26:33 Ubuntu wireplumber[13530]: SPA handle 'api.libcamera.enum.manager' could not be loaded; is it installed?
Apr 28 14:26:33 Ubuntu wireplumber[13530]: PipeWire's libcamera SPA missing or broken. libcamera not supported.
Apr 28 14:26:33 Ubuntu wireplumber[13530]: GetManagedObjects() failed: org.freedesktop.DBus.Error.NameHasNoOwner
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Added device '/dev/dri/card0' (hyperv_drm) using atomic mode setting.
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Failed to initialize accelerated iGPU/dGPU framebuffer sharing: Not hardware accelerated
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Created gbm renderer for '/dev/dri/card0'
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: GPU /dev/dri/card0 selected as primary
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Successfully made thread 13625 of process 13532 owned by '127' RT at priority 20.
Apr 28 14:26:33 Ubuntu rtkit-daemon[788]: Supervising 12 threads of 6 processes of 2 users.
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: meta_viewport_info_get_extents: assertion 'viewport_info != NULL' failed
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: meta_viewport_info_get_extents: assertion 'viewport_info != NULL' failed
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Disabling DMA buffer screen sharing (not hardware accelerated)
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.a11y.Bus' requested by ':1.4' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.a11y.Bus'
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: Log output set to syslog
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: Log level set to Debug
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: get_all_entries for group
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: opening database connection from /var/lib/aad/cache
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: found record: Group { name: "adelev@XXXX.onmicrosoft.com", passwd: "x", gid: 100000, members: ["adelev@XXXX.onmicrosoft.com"] }
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: get_all_entries for group
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: opening database connection from /var/lib/aad/cache
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions
Apr 28 14:26:33 Ubuntu aad_auth[13700]: nss_aad: found record: Group { name: "adelev@XXXX.onmicrosoft.com", passwd: "x", gid: 100000, members: ["adelev@XXXX.onmicrosoft.com"] }
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Using public X11 display :1024, (using :1025 for managed services)
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Using Wayland display name 'wayland-0'
Apr 28 14:26:33 Ubuntu dbus-daemon[13544]: [session uid=127 pid=13544] Successfully activated service 'org.freedesktop.Tracker3.Miner.Files'
Apr 28 14:26:33 Ubuntu systemd[13452]: Started tracker-miner-fs-3.service - Tracker file system data miner.
Apr 28 14:26:33 Ubuntu systemd[13452]: Started tracker-extract-3.service - Tracker metadata extractor.
Apr 28 14:26:33 Ubuntu systemd[13452]: Reached target default.target - Main User Target.
Apr 28 14:26:33 Ubuntu systemd[13452]: Startup finished in 666ms.
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Unset XDG_SESSION_ID, getCurrentSessionProxy() called outside a user session. Asking logind directly.
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Will monitor session c2
Apr 28 14:26:33 Ubuntu dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.locale1' unit='dbus-org.freedesktop.locale1.service' requested by ':1.183' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu systemd[1]: Starting systemd-localed.service - Locale Service...
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.gnome.Shell.Screencast' requested by ':1.3' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.freedesktop.impl.portal.PermissionStore' requested by ':1.3' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
Apr 28 14:26:33 Ubuntu dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.locale1'
Apr 28 14:26:33 Ubuntu systemd[1]: Started systemd-localed.service - Locale Service.
Apr 28 14:26:33 Ubuntu dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.GeoClue2' unit='geoclue.service' requested by ':1.183' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu systemd[1]: Starting geoclue.service - Location Lookup Service...
Apr 28 14:26:33 Ubuntu polkitd(authority=local)[478]: Registered Authentication Agent for unix-session:c2 (system bus name :1.183 [/usr/bin/gnome-shell], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_GB.UTF-8)
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.gnome.Shell.Notifications' requested by ':1.3' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Extension ubuntu-appindicators@ubuntu.com already installed in /usr/share/gnome-shell/extensions/ubuntu-appindicators@ubuntu.com. /usr/share/gnome-shell/extensions/ubuntu-appindicators@ubuntu.com will not be loaded
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Extension ding@rastersoft.com already installed in /usr/share/gnome-shell/extensions/ding@rastersoft.com. /usr/share/gnome-shell/extensions/ding@rastersoft.com will not be loaded
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Extension ubuntu-dock@ubuntu.com already installed in /usr/share/gnome-shell/extensions/ubuntu-dock@ubuntu.com. /usr/share/gnome-shell/extensions/ubuntu-dock@ubuntu.com will not be loaded
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13700]: dbus-daemon[13700]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13700]: dbus-daemon[13700]: Successfully activated service 'org.a11y.atspi.Registry'
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13812]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Apr 28 14:26:33 Ubuntu org.gnome.Shell.desktop[13651]: Window manager warning: Failed to parse saved session file: Failed to open file “/var/lib/gdm3/.config/mutter/sessions/1028434c831e84995516826883933581000000136270000.ms”: No such file or directory
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.gtk.vfs.Daemon' requested by ':1.15' (uid=127 pid=13827 comm="ibus-daemon --panel disable" label="unconfined")
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.gnome.Shell.Notifications'
Apr 28 14:26:33 Ubuntu geoclue[13779]: No feature manager for feature of type 'GProxyResolver'
Apr 28 14:26:33 Ubuntu geoclue[13779]: No feature manager for feature of type 'GProxyResolver'
Apr 28 14:26:33 Ubuntu gnome-shell[13651]: Error looking up permission: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for geolocation
Apr 28 14:26:33 Ubuntu dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.GeoClue2'
Apr 28 14:26:33 Ubuntu systemd[1]: Started geoclue.service - Location Lookup Service.
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.gtk.vfs.Daemon'
Apr 28 14:26:33 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.freedesktop.portal.IBus' requested by ':1.15' (uid=127 pid=13827 comm="ibus-daemon --panel disable" label="unconfined")
Apr 28 14:26:33 Ubuntu org.gnome.Shell.desktop[13706]: Failed to initialize glamor, falling back to sw
Apr 28 14:26:34 Ubuntu dbus-daemon[450]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.190' (uid=127 pid=13850 comm="/usr/libexec/gsd-rfkill" label="unconfined")
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.freedesktop.systemd1' requested by ':1.10' (uid=127 pid=13818 comm="/usr/libexec/gsd-sharing" label="unconfined")
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activated service 'org.freedesktop.systemd1' failed: Process org.freedesktop.systemd1 exited with status 1
Apr 28 14:26:34 Ubuntu gsd-sharing[13818]: Failed to StopUnit service: GDBus.Error:org.freedesktop.DBus.Error.Spawn.ChildExited: Process org.freedesktop.systemd1 exited with status 1
Apr 28 14:26:34 Ubuntu gsd-sharing[13818]: Failed to StopUnit service: GDBus.Error:org.freedesktop.DBus.Error.Spawn.ChildExited: Process org.freedesktop.systemd1 exited with status 1
Apr 28 14:26:34 Ubuntu systemd[1]: Starting systemd-hostnamed.service - Hostname Service...
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.gnome.Shell.Screencast'
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.freedesktop.portal.IBus'
Apr 28 14:26:34 Ubuntu dbus-daemon[450]: [system] Successfully activated service 'org.freedesktop.hostname1'
Apr 28 14:26:34 Ubuntu systemd[1]: Started systemd-hostnamed.service - Hostname Service.
Apr 28 14:26:34 Ubuntu kernel: rfkill: input handler disabled
Apr 28 14:26:34 Ubuntu org.gnome.Shell.desktop[14013]: The XKEYBOARD keymap compiler (xkbcomp) reports:
Apr 28 14:26:34 Ubuntu org.gnome.Shell.desktop[14013]: > Warning:          Unsupported maximum keycode 708, clipping.
Apr 28 14:26:34 Ubuntu org.gnome.Shell.desktop[14013]: >                   X11 cannot support keycodes above 255.
Apr 28 14:26:34 Ubuntu org.gnome.Shell.desktop[14013]: Errors from xkbcomp are not fatal to the X server
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.freedesktop.portal.IBus' requested by ':1.33' (uid=127 pid=13999 comm="ibus-daemon --panel disable -r --xim" label="unconfined")
Apr 28 14:26:34 Ubuntu spice-vdagent[14059]: vdagent virtio channel /dev/virtio-ports/com.redhat.spice.0 does not exist, exiting
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.freedesktop.portal.IBus'
Apr 28 14:26:34 Ubuntu gnome-session-binary[13627]: Entering running state
Apr 28 14:26:34 Ubuntu xbrlapi.desktop[14068]: openConnection: connect: No such file or directory
Apr 28 14:26:34 Ubuntu xbrlapi.desktop[14068]: cannot connect to braille devices daemon brltty at :0
Apr 28 14:26:34 Ubuntu dbus-daemon[450]: [system] Activating via systemd: service name='net.reactivated.Fprint' unit='fprintd.service' requested by ':1.183' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:26:34 Ubuntu systemd[1256]: snap.snapd-desktop-integration.snapd-desktop-integration.service: Scheduled restart job, restart counter is at 1.
Apr 28 14:26:34 Ubuntu systemd[1256]: Stopped snap.snapd-desktop-integration.snapd-desktop-integration.service - Service for snap application snapd-desktop-integration.snapd-desktop-integration.
Apr 28 14:26:34 Ubuntu gnome-shell[13651]: Registering session with GDM
Apr 28 14:26:34 Ubuntu systemd[1]: Starting fprintd.service - Fingerprint Authentication Daemon...
Apr 28 14:26:34 Ubuntu systemd[1256]: Started snap.snapd-desktop-integration.snapd-desktop-integration.service - Service for snap application snapd-desktop-integration.snapd-desktop-integration.
Apr 28 14:26:34 Ubuntu dbus-daemon[13389]: [session uid=1000 pid=13389] Activating via systemd: service name='org.freedesktop.portal.Documents' unit='xdg-document-portal.service' requested by ':1.2' (uid=1000 pid=14080 comm="/usr/bin/snap run snapd-desktop-integration" label="unconfined")
Apr 28 14:26:34 Ubuntu systemd[1256]: Starting xdg-document-portal.service - flatpak document portal service...
Apr 28 14:26:34 Ubuntu dbus-daemon[13389]: [session uid=1000 pid=13389] Activating via systemd: service name='org.freedesktop.impl.portal.PermissionStore' unit='xdg-permission-store.service' requested by ':1.3' (uid=1000 pid=14098 comm="/usr/libexec/xdg-document-portal" label="unconfined")
Apr 28 14:26:34 Ubuntu systemd[1256]: Starting xdg-permission-store.service - sandboxed app permission store...
Apr 28 14:26:34 Ubuntu dbus-daemon[13389]: [session uid=1000 pid=13389] Successfully activated service 'org.freedesktop.impl.portal.PermissionStore'
Apr 28 14:26:34 Ubuntu systemd[1256]: Started xdg-permission-store.service - sandboxed app permission store.
Apr 28 14:26:34 Ubuntu dbus-daemon[13389]: [session uid=1000 pid=13389] Successfully activated service 'org.freedesktop.portal.Documents'
Apr 28 14:26:34 Ubuntu systemd[1256]: Started xdg-document-portal.service - flatpak document portal service.
Apr 28 14:26:34 Ubuntu xdg-document-portal[14098]: Ignoring invalid max threads value 4294967295 > max (100000).
Apr 28 14:26:34 Ubuntu gsd-media-keys[13870]: Failed to grab accelerator for keybinding settings:playback-repeat
Apr 28 14:26:34 Ubuntu gsd-media-keys[13870]: Failed to grab accelerator for keybinding settings:hibernate
Apr 28 14:26:34 Ubuntu dbus-daemon[450]: [system] Successfully activated service 'net.reactivated.Fprint'
Apr 28 14:26:34 Ubuntu systemd[1]: Started fprintd.service - Fingerprint Authentication Daemon.
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Activating service name='org.gnome.ScreenSaver' requested by ':1.27' (uid=127 pid=13900 comm="/usr/libexec/gsd-power" label="unconfined")
Apr 28 14:26:34 Ubuntu gnome-shell[13651]: JS ERROR: Gio.IOErrorEnum: GDBus.Error:net.reactivated.Fprint.Error.NoSuchDevice: No devices available
                                           asyncCallback@resource:///org/gnome/gjs/modules/core/overrides/Gio.js:114:23
Apr 28 14:26:34 Ubuntu /usr/libexec/gdm-wayland-session[13561]: dbus-daemon[13561]: [session uid=127 pid=13561] Successfully activated service 'org.gnome.ScreenSaver'
Apr 28 14:26:34 Ubuntu snapd-desktop-i[14216]: Failed to do gtk init. Waiting for a new session with desktop capabilities.
Apr 28 14:26:34 Ubuntu audit[14216]: SECCOMP auid=1000 uid=1000 gid=1000 ses=3 subj=snap.snapd-desktop-integration.snapd-desktop-integration pid=14216 comm="snapd-desktop-i" exe="/snap/snapd-desktop-integration/83/usr/bin/snapd-desktop-integration" sig=0 arch=c000003e syscall=314 compat=0 ip=0x7ff962393a3d code=0x50000
Apr 28 14:26:34 Ubuntu kernel: audit: type=1326 audit(1682688394.630:92): auid=1000 uid=1000 gid=1000 ses=3 subj=snap.snapd-desktop-integration.snapd-desktop-integration pid=14216 comm="snapd-desktop-i" exe="/snap/snapd-desktop-integration/83/usr/bin/snapd-desktop-integration" sig=0 arch=c000003e syscall=314 compat=0 ip=0x7ff962393a3d code=0x50000
Apr 28 14:26:34 Ubuntu snapd-desktop-i[14216]: Checking session /org/freedesktop/login1/session/c2...
Apr 28 14:26:34 Ubuntu NetworkManager[576]: <info>  [1682688394.7724] agent-manager: agent[f620843cf5ffc0ad,:1.183/org.gnome.Shell.NetworkAgent/127]: agent registered
Apr 28 14:26:41 Ubuntu systemd[1]: Started whoopsie.service - crash report submission.
Apr 28 14:26:41 Ubuntu whoopsie[15559]: [14:26:41] Using lock path: /var/lock/whoopsie/lock
Apr 28 14:26:41 Ubuntu systemd[1]: whoopsie.service: Deactivated successfully.
Apr 28 14:26:42 Ubuntu systemd[1]: Stopping user@1000.service - User Manager for UID 1000...
Apr 28 14:26:42 Ubuntu systemd[1256]: Activating special unit exit.target...
Apr 28 14:26:42 Ubuntu systemd[1256]: Removed slice app-gnome\x2dsession\x2dmanager.slice - Slice /app/gnome-session-manager.
Apr 28 14:26:42 Ubuntu systemd[1256]: Removed slice app-org.gnome.Terminal.slice - Slice /app/org.gnome.Terminal.
Apr 28 14:26:42 Ubuntu systemd[1256]: app-org.gnome.Terminal.slice: Consumed 2.300s CPU time.
Apr 28 14:26:42 Ubuntu systemd[1256]: Removed slice background.slice - User Background Tasks Slice.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped target default.target - Main User Target.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping dbus.service - D-Bus User Message Bus...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping gcr-ssh-agent.service - GCR ssh-agent wrapper...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping pipewire-pulse.service - PipeWire PulseAudio...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping snap.snapd-desktop-integration.snapd-desktop-integration.service - Service for snap application snapd-desktop-integration.snapd-desktop-integration...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping ssh-agent.service - OpenSSH Agent...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping xdg-document-portal.service - flatpak document portal service...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping xdg-permission-store.service - sandboxed app permission store...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped xdg-permission-store.service - sandboxed app permission store.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped pipewire-pulse.service - PipeWire PulseAudio.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping wireplumber.service - Multimedia Service Session Manager...
Apr 28 14:26:42 Ubuntu wireplumber[1279]: stopped by signal: Terminated
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped gcr-ssh-agent.service - GCR ssh-agent wrapper.
Apr 28 14:26:42 Ubuntu wireplumber[1279]: disconnected from pipewire
Apr 28 14:26:42 Ubuntu systemd[1256]: ssh-agent.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Apr 28 14:26:42 Ubuntu systemd[1]: run-user-1000-doc.mount: Deactivated successfully.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped dbus.service - D-Bus User Message Bus.
Apr 28 14:26:42 Ubuntu systemd[1256]: snap.snapd-desktop-integration.snapd-desktop-integration.service: Main process exited, code=exited, status=143/n/a
Apr 28 14:26:42 Ubuntu systemd[1256]: snap.snapd-desktop-integration.snapd-desktop-integration.service: Failed with result 'exit-code'.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped snap.snapd-desktop-integration.snapd-desktop-integration.service - Service for snap application snapd-desktop-integration.snapd-desktop-integration.
Apr 28 14:26:42 Ubuntu systemd[1256]: xdg-document-portal.service: Main process exited, code=exited, status=20/n/a
Apr 28 14:26:42 Ubuntu systemd[1256]: xdg-document-portal.service: Failed with result 'exit-code'.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped xdg-document-portal.service - flatpak document portal service.
Apr 28 14:26:42 Ubuntu agent-launch[15570]: dbus-update-activation-environment: error: unable to connect to D-Bus: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
Apr 28 14:26:42 Ubuntu systemd[1256]: ssh-agent.service: Control process exited, code=exited, status=71/OSERR
Apr 28 14:26:42 Ubuntu systemd[1256]: ssh-agent.service: Failed with result 'exit-code'.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped ssh-agent.service - OpenSSH Agent.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped wireplumber.service - Multimedia Service Session Manager.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopping pipewire.service - PipeWire Multimedia Service...
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped pipewire.service - PipeWire Multimedia Service.
Apr 28 14:26:42 Ubuntu systemd[1256]: Removed slice session.slice - User Core Session Slice.
Apr 28 14:26:42 Ubuntu systemd[1256]: session.slice: Consumed 1min 57.619s CPU time.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped target basic.target - Basic System.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped target paths.target - Paths.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped ubuntu-report.path - Pending report trigger for Ubuntu Report.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped target sockets.target - Sockets.
Apr 28 14:26:42 Ubuntu systemd[1256]: Stopped target timers.target - Timers.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed dbus.socket - D-Bus User Message Bus Socket.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed dirmngr.socket - GnuPG network certificate management daemon.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed gcr-ssh-agent.socket - GCR ssh-agent wrapper.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed gnome-keyring-daemon.socket - GNOME Keyring daemon.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers).
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted).
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation).
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed pipewire-pulse.socket - PipeWire PulseAudio.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed pipewire.socket - PipeWire Multimedia System Socket.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed pk-debconf-helper.socket - debconf communication socket.
Apr 28 14:26:42 Ubuntu systemd[1256]: Closed snapd.session-agent.socket - REST API socket for snapd user session agent.
Apr 28 14:26:42 Ubuntu systemd[1256]: Removed slice app.slice - User Application Slice.
Apr 28 14:26:42 Ubuntu systemd[1256]: app.slice: Consumed 5min 10.159s CPU time.
Apr 28 14:26:42 Ubuntu systemd[1256]: Reached target shutdown.target - Shutdown.
Apr 28 14:26:42 Ubuntu systemd[1256]: Finished systemd-exit.service - Exit the Session.
Apr 28 14:26:42 Ubuntu systemd[1256]: Reached target exit.target - Exit the Session.
Apr 28 14:26:42 Ubuntu systemd[1263]: pam_unix(systemd-user:session): session closed for user XXXX
Apr 28 14:26:42 Ubuntu systemd[1]: user@1000.service: Deactivated successfully.
Apr 28 14:26:42 Ubuntu systemd[1]: Stopped user@1000.service - User Manager for UID 1000.
Apr 28 14:26:42 Ubuntu systemd[1]: user@1000.service: Consumed 7min 9.761s CPU time.
Apr 28 14:26:42 Ubuntu systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000...
Apr 28 14:26:42 Ubuntu systemd[1]: run-user-1000.mount: Deactivated successfully.
Apr 28 14:26:42 Ubuntu systemd[1]: user-runtime-dir@1000.service: Deactivated successfully.
Apr 28 14:26:42 Ubuntu systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000.
Apr 28 14:26:42 Ubuntu systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000.
Apr 28 14:26:42 Ubuntu systemd[1]: user-1000.slice: Consumed 7min 10.103s CPU time.
Apr 28 14:27:04 Ubuntu systemd[1]: systemd-localed.service: Deactivated successfully.
Apr 28 14:27:04 Ubuntu systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Apr 28 14:27:05 Ubuntu systemd[1]: fprintd.service: Deactivated successfully.
Apr 28 14:27:09 Ubuntu dbus-daemon[450]: [system] Activating via systemd: service name='net.reactivated.Fprint' unit='fprintd.service' requested by ':1.183' (uid=127 pid=13651 comm="/usr/bin/gnome-shell" label="unconfined")
Apr 28 14:27:09 Ubuntu gnome-shell[13651]: ActUserManager: user (null) has no username (uid: -1)
Apr 28 14:27:09 Ubuntu systemd[1]: Starting fprintd.service - Fingerprint Authentication Daemon...
Apr 28 14:27:09 Ubuntu gdm-password][15580]: accountsservice: ActUserManager: user (null) has no username (uid: -1)
Apr 28 14:27:09 Ubuntu dbus-daemon[450]: [system] Successfully activated service 'net.reactivated.Fprint'
Apr 28 14:27:09 Ubuntu systemd[1]: Started fprintd.service - Fingerprint Authentication Daemon.
Apr 28 14:27:09 Ubuntu gnome-shell[13651]: JS ERROR: Gio.IOErrorEnum: GDBus.Error:net.reactivated.Fprint.Error.NoSuchDevice: No devices available
                                           asyncCallback@resource:///org/gnome/gjs/modules/core/overrides/Gio.js:114:23
Apr 28 14:27:19 Ubuntu gdm-password][15580]: pam_unix(gdm-password:auth): check pass; user unknown
Apr 28 14:27:19 Ubuntu gdm-password][15580]: pam_unix(gdm-password:auth): authentication failure; logname= uid=0 euid=0 tty=/dev/tty1 ruser= rhost=
Apr 28 14:27:19 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): aad auth debug enabled
Apr 28 14:27:19 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): PAM AAD DEBUG enabled
Apr 28 14:27:19 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Loading configuration from /etc/aad.conf
Apr 28 14:27:19 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Connecting to "https://login.microsoftonline.com/TenantID_Redacted__;!!HUqgN_M!pDroxWBTNo43I82cdJhVvX6AHFz4C3x87et3f6i8fyvOxe3uw1-ePOHO5gsJpfCciVE2v2DYyoHt5oW27EoU423uZmnL1hmyFTjZUwk5$ ", with clientID "4613665f-f52c-4657-8aa9-9210ccf3a6c5" for user "adelev@XXXX.onmicrosoft.com"
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Authentication successful with user/password
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Cache initialization
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Opening cache in /var/lib/aad/cache
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): check file permissions on /var/lib/aad/cache/passwd.db
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): check file permissions on /var/lib/aad/cache/shadow.db
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Shadow db mode: 2
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Cleaning up db. Removing entries that last authenticated online more than 180 days ago
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): getting user information from cache for "adelev@XXXX.onmicrosoft.com"
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): encrypt password for user "adelev@XXXX.onmicrosoft.com"
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): updating from last online login information for user "adelev@XXXX.onmicrosoft.com"
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_aad(gdm-password:auth): Close database request
Apr 28 14:27:20 Ubuntu gdm-password][15580]: gkr-pam: error looking up user information
Apr 28 14:27:20 Ubuntu gdm-password][15580]: pam_unix(gdm-password:account): could not identify user (from getpwnam(adelev@XXXX.onmicrosoft.com))
Apr 28 14:27:20 Ubuntu gdm-password][15599]: accountsservice: ActUserManager: user (null) has no username (uid: -1)
Apr 28 14:27:34 Ubuntu geoclue[13779]: Service not used for 60 seconds. Shutting down..
Apr 28 14:27:34 Ubuntu systemd[1]: geoclue.service: Deactivated successfully.
Apr 28 14:27:39 Ubuntu systemd[1]: fprintd.service: Deactivated successfully.

denisonbarbosa commented 1 year ago

Hey @AllDeesScripts! Sorry for the delay. Did you change anything on your /etc/nsswitch.conf file? The shadow: section seems to be missing the systemd module. Would you mind adding it so it would look like this:

passwd:         files systemd sss aad
group:          files systemd sss aad
shadow:         files systemd sss aad
gshadow:        files systemd

After changing the nsswitch.conf file:

remove the cache files located on /var/lib/aad/cache/ (just a sudo rm /var/lib/aad/cache/* will suffice);
remove the home directory created for your aad user (if you enabled the mkhomedir pam module);
reboot the machine;
try logging in again;
share the logs (redacting sensitive information);

I know it's some tasking, but it would help a lot to see exactly how everything was set up from the start so I can have a better idea of what could be the issue.

Ps.: Don't forget to double-check the previous debug enablers that I told you about.

kuhlmanncommasoft commented 1 year ago

@denisonbarbosa

Im facing the exact same error. I have followed all your mitigation steps but could not get it running.

Here are my logs :

Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Log output set to syslog Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Log level set to Debug Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: get_all_entries for group Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: opening database connection from /var/lib/aad/cache Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: found record: Group { name: "nadenau_test_enduserXXX", passwd: "x", gid: 100000, members: ["nadenau_test_enduserXXX"] } Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: get_entry_by_name for passwd for name: pam_unix_non_existent: Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: opening database connection from /var/lib/aad/cache Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions Jul 26 16:20:50 johannes-Virtual-Machine aad_auth[2469]: nss_aad: no record found Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: get_all_entries for group Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: opening database connection from /var/lib/aad/cache Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: found record: Group { name: "nadenau_test_enduserXXX", passwd: "x", gid: 100000, members: ["nadenau_test_enduserXXX"] } Jul 26 16:20:53 johannes-Virtual-Machine sudo[2469]: johannes : TTY=pts/2 ; PWD=/root ; USER=root ; COMMAND=/bin/bash Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: get_all_entries for group Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: opening database connection from /var/lib/aad/cache Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/passwd.db" permissions Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: Checking file "/var/lib/aad/cache/shadow.db" permissions Jul 26 16:20:53 johannes-Virtual-Machine aad_auth[2469]: nss_aad: found record: Group { name: "nadenau_test_enduserXXX", passwd: "x", gid: 100000, members: ["nadenau_test_enduserXXX"] } Jul 26 16:20:53 johannes-Virtual-Machine sudo[2469]: pam_unix(sudo-i:session): session opened for user root(uid=0) by (uid=1000) Jul 26 16:21:19 johannes-Virtual-Machine login[2489]: pam_unix(login:auth): check pass; user unknown Jul 26 16:21:19 johannes-Virtual-Machine login[2489]: pam_unix(login:auth): authentication failure; logname=johannes uid=0 euid=0 tty=/dev/pts/3 ruser= rhost= Jul 26 16:21:19 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): aad auth debug enabled Jul 26 16:21:19 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): PAM AAD DEBUG enabled Jul 26 16:21:19 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Loading configuration from /etc/aad.conf Jul 26 16:21:19 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Connecting to "https://login.microsoftonline.com/XXX", with clientID "XXX" for user "nadenau_test_enduserXXX" Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Authentication successful with user/password Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Cache initialization Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Opening cache in /var/lib/aad/cache Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): check file permissions on /var/lib/aad/cache/passwd.db Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): check file permissions on /var/lib/aad/cache/shadow.db Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Shadow db mode: 2 Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Cleaning up db. Removing entries that last authenticated online more than 180 days ago Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): getting user information from cache for "nadenau_test_enduser@XXX" Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): encrypt password for user "nadenau_test_enduser@XXX" Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): updating from last online login information for user "nadenau_test_enduser@XXX" Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_aad(login:auth): Close database request Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: pam_unix(login:account): could not identify user (from getpwnam(nadenau_test_enduser@XXX)) Jul 26 16:21:20 johannes-Virtual-Machine login[2489]: Authentication failure

AllDeesScripts commented 1 year ago

@denisonbarbosa Thanks for the reply.

I will be redeploying my VM and reattempting this process. Redeploying it all previously yielded the same result, but picking this up again I would still like to start afresh and will feedback to you on your points once I've done this.

denisonbarbosa commented 1 year ago

Hey @kuhlmanncommasoft, thanks for also collaborating with the issue. In your case, the domain CommatrixOnline is capitalized. In the aad-auth version that's available in the archive, issue #290 wasn't fixed yet, but it's fixed now after #295.

@AllDeesScripts, if your domain also has capitalized letters, the issue could be the same as well. If not, then I'll wait for your feedback on the redeploy. Thanks again!

AllDeesScripts commented 1 year ago

Wow!, Thank you @kuhlmanncommasoft for posting your log.

And thank you @denisonbarbosa for the solution. This has worked. It logged in straight away when I entered all of the username in lower case.

denisonbarbosa commented 1 year ago

Great news! Since most of Microsoft's tools are not case-sensitive, it's a good rule of thumb to always try to use lowercase first when interacting with "hybrid" tools like aad-auth.

kuhlmanncommasoft commented 1 year ago

@AllDeesScripts Just to make sure: Did you use the module from the Ubuntu repo, or did you compile it yourself?

AllDeesScripts commented 1 year ago

@kuhlmanncommasoft from the repo

AllDeesScripts commented 1 year ago

.... This is an install from April. So probs isn't the latest version

kuhlmanncommasoft commented 1 year ago

@AllDeesScripts Thanks!

denisonbarbosa commented 1 year ago

@AllDeesScripts Just to make sure: Did you use the module from the Ubuntu repo, or did you compile it yourself?

@kuhlmanncommasoft, first clean the cache with the commands that I've described earlier in the issue and then try authenticating using the fully lowercased username.

ubuntu / aad-auth

Ubuntu logon fails but Azure AD says successful #209