You are not logged in.

#1 2020-09-02 14:24:12

gtsatsis
Member
From: Athens, Greece
Registered: 2020-09-02
Posts: 4
Website

Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

Hey,

I recently got a refurbished Thinkpad T440, and have been trying to get sudo to work with fprint (as per https://wiki.archlinux.org/index.php/Fprint), and the sensor is being extremely slow.

For example, while enrolling, it took anywhere from 30 seconds to 1 minute to get to the next enrollment step, and sudo times out the verification.

The sensor itself is listed as supported at https://fprint.freedesktop.org/supported-devices.html.

journalctl doesn't show anything of interest, what would be the next steps in figuring out what's causing this slowness?

Thanks,
George

Last edited by gtsatsis (2020-09-04 16:12:54)

Offline

#2 2020-09-04 16:06:35

saint613
Member
Registered: 2020-06-25
Posts: 11

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

Are you using Fingerprint GUI?

Offline

#3 2020-09-04 16:10:48

gtsatsis
Member
From: Athens, Greece
Registered: 2020-09-02
Posts: 4
Website

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

saint613 wrote:

Are you using Fingerprint GUI?

Yes, and no; I do have it installed, and it is really slow as well, but I used

fprintd-enroll

to set the fingerprint up this time.

Offline

#4 2020-09-05 00:58:45

saint613
Member
Registered: 2020-06-25
Posts: 11

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

Odd, I'm on a T440S as well, i7, 12gb, 5.4.61 Kernel, Bus 001 Device 003: ID 138a:0017 Validity Sensors, Inc. VFS 5011 fingerprint sensor

lightdm-1:1.30.0-4, fprintd-1.90.1-1, fingerprint-gui 1.09-7

fingerprint-gui -d will allow you to debug

My pam settings for fingerprint-gui are as follows,  though it has nothing to do with the register scanning

/etc/pam.d/lightdm
auth        sufficient      pam_fingerprint-gui.so


/etc/pam.d/su
auth        sufficient      pam_fingerprint-gui.so

/etc/pam.d/sudo
auth        sufficient      pam_fingerprint-gui.so

Offline

#5 2020-09-05 01:28:05

gtsatsis
Member
From: Athens, Greece
Registered: 2020-09-02
Posts: 4
Website

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

Odd indeed, here's the output from journalctl while validating my fingerprint using fingerprint-gui -d:

Sep 05 04:16:57 ultithonk fingerprint-gui[16440]: A fingerprint for Right Index exists already.
Sep 05 04:16:59 ultithonk fingerprint-gui[16440]: Clicked "Verify".
Sep 05 04:16:59 ultithonk fingerprint-gui[16440]: Swipe finger at vfs5011.
Sep 05 04:16:59 ultithonk fingerprint-gui[16440]: Thread started.
Sep 05 04:16:59 ultithonk fingerprint-gui[16440]: Starting verify.
Sep 05 04:17:00 ultithonk kernel: usb 2-6: reset full-speed USB device number 3 using xhci_hcd
Sep 05 04:17:00 ultithonk fingerprint-gui[16440]: Need 1 stages.
Sep 05 04:17:00 ultithonk fingerprint-gui[16440]: Verify waiting...
Sep 05 04:17:08 ultithonk root[16495]: ACPI action undefined: PNP0C0A:01
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: Verify: have image.
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: FP_VERIFY_MATCH.
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: FP_DEV_CLOSE.
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: Verify OK
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: Verify result FP_VERIFY_MATCH.
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: Thread ended normally.
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: Verify complete.
Sep 05 04:17:36 ultithonk fingerprint-gui[16440]: Fingerprint was verified.

The only thing I can see out of the ordinary is the undefined ACPI action (which I was unable to reproduce).

At this point my only theory is that fprintd is waiting some amount of time by itself, but where would that even be configured.

Offline

#6 2020-09-06 14:27:11

saint613
Member
Registered: 2020-06-25
Posts: 11

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

So the pause is right after the ACPI action undefined: PNP0C0A:01 but your saying that it doesn't show in the log every time?
Do you have any weird ACPI actions set for the fingerprint scanner? /etc/acpi/actions/

Offline

#7 2020-09-06 16:03:27

gtsatsis
Member
From: Athens, Greece
Registered: 2020-09-02
Posts: 4
Website

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

saint613 wrote:

So the pause is right after the ACPI action undefined: PNP0C0A:01 but your saying that it doesn't show in the log every time?
Do you have any weird ACPI actions set for the fingerprint scanner? /etc/acpi/actions/

There are no ACPI actions set at all; This must've been something unrelated to the fingerprint scanner.

Offline

#8 2020-09-06 17:32:54

saint613
Member
Registered: 2020-06-25
Posts: 11

Re: Thinkpad T440 slow Fingerprint scan (Validity Sensors, Inc. 138a:0017)

Here is my journalctl log from a test I just did (sorry took a few unsuccessful swipes from the log, thats why there is a 6 second gap at 12:14:06). Key difference seems to be I'm using pam modules.

Sep 06 12:13:53 myarch fingerprint-gui[42133]: Started.
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Proprietary lib "libbsapi.so.4.3>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Libfprint initialized.
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Libbsapi initialized.
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Devices initialized.
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Intel Corp./un>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Linux Foundati>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Silicon Motion>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Linux Foundati>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Chicony Electr>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Intel Corp./un>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Validity Senso>
Sep 06 12:13:53 myarch fingerprint-gui[42133]: Found USB device: Linux Foundati>
Sep 06 12:13:54 myarch fingerprint-gui[42133]: No devices found by libbsapi.
Sep 06 12:13:54 myarch fingerprint-gui[42133]: initializing libfprint device (v>
Sep 06 12:13:54 myarch fingerprint-gui[42133]: Added vfs5011.
Sep 06 12:13:54 myarch fingerprint-gui[42133]: Current device set to 0 -- vfs50>
Sep 06 12:13:54 myarch fingerprint-gui[42133]: Current device set to 0 -- vfs50>
Sep 06 12:13:58 myarch kernel: usb 1-6: reset full-speed USB device number 3 us>
Sep 06 12:14:02 myarch fingerprint-gui[42133]: Testing service "sudo"
Sep 06 12:14:02 myarch fingerprint-gui[42133]: stopping device.
Sep 06 12:14:02 myarch fingerprint-gui[42133]: exit libfprint.
Sep 06 12:14:02 myarch fingerprint-gui[42133]: terminating libbsapi.
Sep 06 12:14:02 myarch fingerprint-gui[42133]: Devices released.
Sep 06 12:14:02 myarch fingerprint-gui[42133]: PAM_CALLBACK, waiting.
Sep 06 12:14:05 myarch kernel: usb 1-6: reset full-speed USB device number 3 us>
Sep 06 12:14:06 myarch fingerprint-helper[42148]: Not running as root (need pke>
Sep 06 12:14:06 myarch pkexec[42153]: pam_unix(polkit-1:session): session opene>
Sep 06 12:14:06 myarch audit[42153]: USER_START pid=42153 uid=1000 auid=1000 se>
Sep 06 12:14:06 myarch pkexec[42153]: dale: Executing command [USER=root] [TTY=>
Sep 06 12:14:06 myarch kernel: audit: type=1105 audit(1599408846.219:180): pid=>
Sep 06 12:14:06 myarch fingerprint-helper[42148]: Not running as root (need pke>
Sep 06 12:14:06 myarch pkexec[42156]: pam_unix(polkit-1:session): session opene>
Sep 06 12:14:06 myarch audit[42156]: USER_START pid=42156 uid=1000 auid=1000 se>
Sep 06 12:14:06 myarch pkexec[42156]: dale: Executing command [USER=root] [TTY=>
Sep 06 12:14:06 myarch kernel: audit: type=1105 audit(1599408846.239:181): pid=>
Sep 06 12:14:06 myarch fingerprint-gui[42133]: Fifo opened: 20
Sep 06 12:14:06 myarch fingerprint-gui[42133]: Read from fifo: _nOrMaL_Authenti>
Sep 06 12:14:12 myarch kernel: usb 1-6: reset full-speed USB device number 3 us>
Sep 06 12:14:12 myarch fingerprint-gui[42133]: Read from fifo: _aLiVe_
Sep 06 12:14:13 myarch fingerprint-gui[42133]: Read from fifo: _aLiVe_
Sep 06 12:14:13 myarch fingerprint-gui[42133]: Read from fifo: _aLiVe_
Sep 06 12:14:14 myarch fingerprint-gui[42133]: Read from fifo: _aLiVe_
Sep 06 12:14:14 myarch fingerprint-gui[42133]: Read from fifo: _aLiVe_
Sep 06 12:14:15 myarch fingerprint-gui[42133]: Read from fifo: _bOlD_OK
Sep 06 12:14:15 myarch fingerprint-gui[42133]: Read from fifo: _aUtH_
Sep 06 12:14:15 myarch fingerprint-gui[42133]: Received AUTH message
Sep 06 12:14:15 myarch fingerprint-gui[42133]: Caught line input.
Sep 06 12:14:15 myarch fingerprint-gui[42133]: PAM_CALLBACK return PAM_SUCCESS.

Offline

Board footer

Powered by FluxBB