cesalazar
commented
3 years ago Similar delay on a Thinkpad T460s. This is the log in journalctl (problem at 01:50:59):
Jan 27 00:00:00 foo dbus-daemon[360]: [system] Activating via systemd: service name='net.reactivated.Fprint' unit='fprintd.service' requested by ':1.162' (uid=0 pid=83418 comm="sudo su ")
Jan 27 00:00:00 foo systemd[1]: Starting Fingerprint Authentication Daemon...
░░ Subject: A start job for unit fprintd.service has begun execution
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░
░░ A start job for unit fprintd.service has begun execution.
░░
░░ The job identifier is 2755.
Jan 27 01:50:55 foo kernel: usb 1-9: reset full-speed USB device number 8 using xhci_hcd
Jan 27 01:50:55 foo dbus-daemon[360]: [system] Successfully activated service 'net.reactivated.Fprint'
Jan 27 01:50:55 foo systemd[1]: Started Fingerprint Authentication Daemon.
░░ Subject: A start job for unit fprintd.service has finished successfully
░░ Defined-By: systemd
░░ Support: https://forum.manjaro.org/c/support
░░
░░ A start job for unit fprintd.service has finished successfully.
░░
░░ The job identifier is 2755.
Jan 27 01:50:55 foo audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=fprintd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 27 01:50:55 foo kernel: audit: type=1130 audit(1611708655.171:751): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=fprintd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 27 01:50:55 foo kernel: usb 1-9: reset full-speed USB device number 8 using xhci_hcd
Jan 27 01:50:59 foo fprintd[83419]: LED blinking failed with error Operation was cancelled <=
Jan 27 01:51:02 foo fprintd[83419]: USB write transfer error: transfer timed out <=
Jan 27 01:51:02 foo fprintd[83419]: Data exchange failed at state 1, usb error: transfer timed out <=
Jan 27 01:51:02 foo fprintd[83419]: Deactivation failed at state 1, unexpected device reply during deactivation <=
Jan 27 01:51:02 foo audit[83418]: USER_AUTH pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_fprintd acct="baz" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo audit[83418]: USER_ACCT pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="baz" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo sudo[83418]: baz : TTY=pts/3 ; PWD=/bar ; USER=root ; COMMAND=/usr/bin/su
Jan 27 01:51:02 foo audit[83418]: CRED_REFR pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_fprintd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1100 audit(1611708662.408:752): pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_fprintd acct="baz" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1101 audit(1611708662.408:753): pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="baz" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1110 audit(1611708662.408:754): pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_fprintd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo sudo[83418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Jan 27 01:51:02 foo audit[83418]: USER_START pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1105 audit(1611708662.414:755): pid=83418 uid=1000 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/3 res=success'
Jan 27 01:51:02 foo audit[83788]: USER_AUTH pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_rootok acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo audit[83788]: USER_ACCT pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_unix acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo su[83788]: (to root) baz on pts/3
Jan 27 01:51:02 foo kernel: audit: type=1100 audit(1611708662.421:756): pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_rootok acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1101 audit(1611708662.421:757): pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_unix acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo audit[83788]: CRED_ACQ pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_rootok acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo su[83788]: pam_unix(su:session): session opened for user root(uid=0) by (uid=0)
Jan 27 01:51:02 foo audit[83788]: USER_START pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_unix acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1103 audit(1611708662.424:758): pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_rootok acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
Jan 27 01:51:02 foo kernel: audit: type=1105 audit(1611708662.424:759): pid=83788 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_unix acct="root" exe="/usr/bin/su" hostname=foo addr=? terminal=pts/3 res=success'
The fprintd project redirected me to this project. https://gitlab.freedesktop.org/libfprint/fprintd/-/issues/102
With fprintd 1.90.1 everything is fine.
Now every time I need use my finger print for root stuff, I need to wait
2 seconds in the terminal
5 seconds in the GUI (pkexec)
5 seconds in the login screen GUI