Bus n/a: changing state UNSET → OPENING
sd-bus: starting bus by connecting to /run/dbus/system_bus_socket...
Bus n/a: changing state OPENING → AUTHENTICATING
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.DynamicUser: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.DynamicUser"}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Machine: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Machine"}}
/run/systemd/userdb/io.systemd.Machine: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Home: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Home"}}
/run/systemd/userdb/io.systemd.Home: Changing state idle-client → awaiting-reply
/run/systemd/userdb/io.systemd.DynamicUser: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.DynamicUser: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Machine: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.Machine: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.Machine: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Home: New incoming message: {"parameters":{"record":{"binding":{"redacted":{"fileSystemType":"ext4","fileSystemUuid":"527b9b64-a8df-43cf-891a-07454b531bfe","gid":60021,"homeDirectory":"/home/leonard","imagePath":"/home/leonard.home","luksCipher":"aes","luksCipherMode":"xts-plain64","luksUuid":"691e7970-651e-4fbb-829d-773960169cdc","luksVolumeKeySize":32,"partitionUuid":"df56d4ec-aa8d-4e46-a51b-15f114620754","storage":"luks","uid":60021}},"disposition":"regular","lastChangeUSec":1607597800175597,"lastPasswordChangeUSec":1593709471259825,"memberOf":["adm","ftp","games","http","leonard","libvirt","log","power","realtime","rfkill","storage","sys","systemd-journal","tty","users","uucp","video","wheel","wireshark"],"privileged":{"hashedPassword":["$6$redactedsha512hash"]},"realName":"Leonard Janis Robert König","shell":"/usr/bin/zsh","signature":[{"data":"redacted","key":"redacted}],"userName":"leonard","status":{"redacted":{"goodAuthenticationCounter":992,"lastGoodAuthenticationUSec":1642011013194896,"rateLimitBeginUSec":1642014493576513,"rateLimitCount":1,"badAuthenticationCounter":157,"lastBadAuthenticationUSec":1642014493586821,"state":"inactive","service":"io.systemd.Home","diskSize":269054656512,"diskCeiling":307510693888,"diskFloor":5242880,"signedLocally":true}}},"incomplete":false}}
/run/systemd/userdb/io.systemd.Home: Changing state awaiting-reply → processing-reply
/run/systemd/userdb/io.systemd.Home: Changing state processing-reply → idle-client
No passwords acquired.
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.DynamicUser: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.DynamicUser"}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Machine: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Machine"}}
/run/systemd/userdb/io.systemd.Machine: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Home: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Home"}}
/run/systemd/userdb/io.systemd.Home: Changing state idle-client → awaiting-reply
/run/systemd/userdb/io.systemd.DynamicUser: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.DynamicUser: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Machine: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.Machine: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.Machine: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Home: New incoming message: {"parameters":{"record":{"binding":{"redacted":{"fileSystemType":"ext4","fileSystemUuid":"527b9b64-a8df-43cf-891a-07454b531bfe","gid":60021,"homeDirectory":"/home/leonard","imagePath":"/home/leonard.home","luksCipher":"aes","luksCipherMode":"xts-plain64","luksUuid":"691e7970-651e-4fbb-829d-773960169cdc","luksVolumeKeySize":32,"partitionUuid":"df56d4ec-aa8d-4e46-a51b-15f114620754","storage":"luks","uid":60021}},"disposition":"regular","lastChangeUSec":1607597800175597,"lastPasswordChangeUSec":1593709471259825,"memberOf":["adm","ftp","games","http","leonard","libvirt","log","power","realtime","rfkill","storage","sys","systemd-journal","tty","users","uucp","video","wheel","wireshark"],"privileged":{"hashedPassword":["$6$redactedsha512hash"]},"realName":"Leonard Janis Robert König","shell":"/usr/bin/zsh","signature":[{"data":"redacted","key":"redacted}],"userName":"leonard","status":{"redacted":{"goodAuthenticationCounter":992,"lastGoodAuthenticationUSec":1642011013194896,"rateLimitBeginUSec":1642014493576513,"rateLimitCount":1,"badAuthenticationCounter":157,"lastBadAuthenticationUSec":1642014493586821,"state":"inactive","service":"io.systemd.Home","diskSize":269054656512,"diskCeiling":307510693888,"diskFloor":5242880,"signedLocally":true}}},"incomplete":false}}
/run/systemd/userdb/io.systemd.Home: Changing state awaiting-reply → processing-reply
/run/systemd/userdb/io.systemd.Home: Changing state processing-reply → idle-client
No security token PINs acquired.
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.DynamicUser: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.DynamicUser"}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Machine: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Machine"}}
/run/systemd/userdb/io.systemd.Machine: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Home: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Home"}}
/run/systemd/userdb/io.systemd.Home: Changing state idle-client → awaiting-reply
/run/systemd/userdb/io.systemd.DynamicUser: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.DynamicUser: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Machine: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.Machine: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.Machine: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Home: New incoming message: {"parameters":{"record":{"binding":{"redacted":{"fileSystemType":"ext4","fileSystemUuid":"527b9b64-a8df-43cf-891a-07454b531bfe","gid":60021,"homeDirectory":"/home/leonard","imagePath":"/home/leonard.home","luksCipher":"aes","luksCipherMode":"xts-plain64","luksUuid":"691e7970-651e-4fbb-829d-773960169cdc","luksVolumeKeySize":32,"partitionUuid":"df56d4ec-aa8d-4e46-a51b-15f114620754","storage":"luks","uid":60021}},"disposition":"regular","lastChangeUSec":1607597800175597,"lastPasswordChangeUSec":1593709471259825,"memberOf":["adm","ftp","games","http","leonard","libvirt","log","power","realtime","rfkill","storage","sys","systemd-journal","tty","users","uucp","video","wheel","wireshark"],"privileged":{"hashedPassword":["$6$redactedsha512hash"]},"realName":"Leonard Janis Robert König","shell":"/usr/bin/zsh","signature":[{"data":"redacted","key":"redacted}],"userName":"leonard","status":{"redacted":{"goodAuthenticationCounter":992,"lastGoodAuthenticationUSec":1642011013194896,"rateLimitBeginUSec":1642014493576513,"rateLimitCount":1,"badAuthenticationCounter":157,"lastBadAuthenticationUSec":1642014493586821,"state":"inactive","service":"io.systemd.Home","diskSize":269054656512,"diskCeiling":307510693888,"diskFloor":5242880,"signedLocally":true}}},"incomplete":false}}
/run/systemd/userdb/io.systemd.Home: Changing state awaiting-reply → processing-reply
/run/systemd/userdb/io.systemd.Home: Changing state processing-reply → idle-client
No recovery keys acquired.
Bus n/a: changing state AUTHENTICATING → HELLO
Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 signature=n/a error-name=n/a error-message=n/a
Got message type=method_return sender=org.freedesktop.DBus destination=:1.247 path=n/a interface=n/a member=n/a cookie=4294967295 reply_cookie=1 signature=s error-name=n/a error-message=n/a
Bus n/a: changing state HELLO → RUNNING
Sent message type=method_call sender=n/a destination=org.freedesktop.home1 path=/org/freedesktop/home1 interface=org.freedesktop.home1.Manager member=ActivateHome cookie=2 reply_cookie=0 signature=ss error-name=n/a error-message=n/a
Got message type=error sender=:1.5 destination=:1.247 path=n/a interface=n/a member=n/a cookie=59 reply_cookie=2 signature=s error-name=org.freedesktop.home1.BadPassword error-message=Password for home leonard is incorrect or not sufficient for authentication.
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.DynamicUser: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.DynamicUser"}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Machine: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Machine"}}
/run/systemd/userdb/io.systemd.Machine: Changing state idle-client → awaiting-reply
varlink: Setting state idle-client
/run/systemd/userdb/io.systemd.Home: Sending message: {"method":"io.systemd.UserDatabase.GetUserRecord","parameters":{"userName":"leonard","service":"io.systemd.Home"}}
/run/systemd/userdb/io.systemd.Home: Changing state idle-client → awaiting-reply
/run/systemd/userdb/io.systemd.DynamicUser: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.DynamicUser: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.DynamicUser: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Machine: New incoming message: {"error":"io.systemd.UserDatabase.NoRecordFound","parameters":{}}
/run/systemd/userdb/io.systemd.Machine: Changing state awaiting-reply → processing-reply
Got lookup error: io.systemd.UserDatabase.NoRecordFound
/run/systemd/userdb/io.systemd.Machine: Changing state processing-reply → idle-client
/run/systemd/userdb/io.systemd.Home: New incoming message: {"parameters":{"record":{"binding":{"redacted":{"fileSystemType":"ext4","fileSystemUuid":"527b9b64-a8df-43cf-891a-07454b531bfe","gid":60021,"homeDirectory":"/home/leonard","imagePath":"/home/leonard.home","luksCipher":"aes","luksCipherMode":"xts-plain64","luksUuid":"691e7970-651e-4fbb-829d-773960169cdc","luksVolumeKeySize":32,"partitionUuid":"df56d4ec-aa8d-4e46-a51b-15f114620754","storage":"luks","uid":60021}},"disposition":"regular","lastChangeUSec":1607597800175597,"lastPasswordChangeUSec":1593709471259825,"memberOf":["adm","ftp","games","http","leonard","libvirt","log","power","realtime","rfkill","storage","sys","systemd-journal","tty","users","uucp","video","wheel","wireshark"],"privileged":{"hashedPassword":["$6$redactedsha512hash"]},"realName":"Leonard Janis Robert König","shell":"/usr/bin/zsh","signature":[{"data":"redacted","key":"redacted}],"userName":"leonard","status":{"redacted":{"goodAuthenticationCounter":992,"lastGoodAuthenticationUSec":1642011013194896,"rateLimitBeginUSec":1642014493576513,"rateLimitCount":1,"badAuthenticationCounter":157,"lastBadAuthenticationUSec":1642014493586821,"state":"inactive","service":"io.systemd.Home","diskSize":269054656512,"diskCeiling":307510693888,"diskFloor":5242880,"signedLocally":true}}},"incomplete":false}}
/run/systemd/userdb/io.systemd.Home: Changing state awaiting-reply → processing-reply
/run/systemd/userdb/io.systemd.Home: Changing state processing-reply → idle-client
Sent message type=method_call sender=n/a destination=org.freedesktop.home1 path=/org/freedesktop/home1 interface=org.freedesktop.home1.Manager member=ActivateHome cookie=3 reply_cookie=0 signature=ss error-name=n/a error-message=n/a
Got message type=error sender=:1.5 destination=:1.247 path=n/a interface=n/a member=n/a cookie=61 reply_cookie=3 signature=s error-name=org.freedesktop.home1.HomeBusy error-message=Home leonard is currently being used, or an operation on home leonard is currently being executed.
Operation on home leonard failed: Home leonard is currently being used, or an operation on home leonard is currently being executed.
Bus n/a: changing state RUNNING → CLOSED