[pkg-gnupg-maint] Bug#854005: Bug#854005: ssh-agent no longer works

Antoine Beaupré anarcat at debian.org
Wed Feb 8 15:24:59 UTC 2017 

On 2017-02-07 09:28:33, Antoine Beaupré wrote:
>> does this line of thinking make sense?
>
> yes, it makes sense. i'll try to figure out if pcscd had crashed - but I
> don't understand how reinstalling the package could have possibly fixed
> this.

so here's what i could find. my first (traumatic) finding is that pcscd
runs as root... i was trying to find it in my process tree and failing
because of that, so that's part of the confusion.

this is the reason why removing the package fixes the issue - it
probably resets the systemd configuration for the daemon and
reinstalling restarts it properly.

now it is running - but who knows for how long?

● pcscd.service - PC/SC Smart Card Daemon
   Loaded: loaded (/lib/systemd/system/pcscd.service; indirect; vendor preset: enabled)
   Active: active (running) since Wed 2017-02-08 10:12:36 EST; 4min 1s ago
 Main PID: 14439 (pcscd)
   CGroup: /system.slice/pcscd.service
           └─14439 /usr/sbin/pcscd --foreground --auto-exit

It seems that I need to do this reset thing every morning now, so I
guess it's crashing at least every 24 hours. i have tried unplugging and
replugging the yubikey, it doesn't trigger the problem.

i have tried to figure out what may have happened by looking at the
journald logs, but i can't figure it out. it doesn't clearly mention a
crash. 

notice, in the log below, that i reinstalled the package at around Feb
08 10:12:36, which is when things went back to normal. yet before that,
it's unclear if there was a problem.

any ideas? should this be a separate bug report? it doesn't *look* like
it's the same issue because the workaround fails...

thanks,

a.

-- 
The steel horse fills a gap in modern life, it is an answer not only to
its needs, but also to its aspirations.  It's quite certainly here to
stay.
                         - Le Vélocipède Illustré, 1869

$ sudo LANG=C journalctl -x -u pcscd.service
-- Logs begin at Sat 2017-02-04 11:17:15 EST, end at Wed 2017-02-08 10:19:46 EST. --
Feb 04 12:33:58 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 04 12:33:58 curie pcscd[8947]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 04 12:33:58 curie pcscd[8947]: 00000012 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/007)
Feb 04 12:33:58 curie pcscd[8947]: 00000002 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 04 12:33:58 curie pcscd[8947]: 00341712 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 04 12:44:42 curie pcscd[8947]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 04 12:44:45 curie pcscd[8947]: 03512295 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 04 13:26:51 curie pcscd[8947]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/7): -4 LIBUSB_ERROR_NO_DEVICE
Feb 04 17:28:58 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 04 17:29:16 curie pcscd[31517]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 04 17:29:16 curie pcscd[31517]: 00000014 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/008)
Feb 04 17:29:16 curie pcscd[31517]: 00000003 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 04 17:53:08 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 04 17:53:08 curie pcscd[1915]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 04 17:53:08 curie pcscd[1915]: 00000018 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/008)
Feb 04 17:53:08 curie pcscd[1915]: 00000002 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 04 17:53:09 curie pcscd[1915]: 00860385 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 04 18:10:35 curie pcscd[1915]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 04 18:27:49 curie pcscd[1915]: 99999999 ccid_usb.c:1337:InterruptStop() libusb_cancel_transfer failed: -4
Feb 04 18:27:49 curie pcscd[1915]: 00400400 ccid_usb.c:797:WriteUSB() write failed (1/8): -4 LIBUSB_ERROR_NO_DEVICE
Feb 06 10:55:09 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 06 10:55:09 curie pcscd[20263]: 00000000 utils.c:82:GetDaemonPid() Can't open /var/run/pcscd/pcscd.pid: No such file or directory
Feb 06 10:55:09 curie pcscd[20263]: 00007301 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 06 10:55:09 curie pcscd[20263]: 00000009 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/010)
Feb 06 10:55:09 curie pcscd[20263]: 00000003 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 06 11:07:17 curie pcscd[20263]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 12:13:37 curie pcscd[20263]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 12:13:52 curie pcscd[20263]: 15281542 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 18:27:32 curie pcscd[20263]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/10): -4 LIBUSB_ERROR_NO_DEVICE
Feb 06 19:48:27 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 06 19:48:27 curie pcscd[3100]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 06 19:48:27 curie pcscd[3100]: 00000014 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/013)
Feb 06 19:48:27 curie pcscd[3100]: 00000003 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 06 19:48:49 curie pcscd[3100]: 22045135 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:49:15 curie pcscd[3100]: 26297901 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:49:56 curie pcscd[3100]: 41260039 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:50:06 curie pcscd[3100]: 09673127 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:51:25 curie pcscd[3100]: 78500770 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 20:22:55 curie pcscd[3100]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 20:23:00 curie pcscd[3100]: 04869977 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 20:30:37 curie pcscd[3100]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/13): -4 LIBUSB_ERROR_NO_DEVICE
Feb 07 20:55:56 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 07 20:55:56 curie pcscd[21376]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 07 20:55:56 curie pcscd[21376]: 00000012 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/015)
Feb 07 20:55:56 curie pcscd[21376]: 00000004 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 07 20:56:02 curie pcscd[21376]: 05648900 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 20:56:12 curie pcscd[21376]: 09655699 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 20:56:36 curie pcscd[21376]: 24217358 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 21:06:59 curie pcscd[21376]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 21:08:03 curie pcscd[21376]: 63425592 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 22:01:33 curie pcscd[21376]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 22:27:31 curie pcscd[21376]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/15): -4 LIBUSB_ERROR_NO_DEVICE
Feb 08 10:12:36 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 08 10:12:36 curie pcscd[14439]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 08 10:12:36 curie pcscd[14439]: 00000014 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/016)
Feb 08 10:12:36 curie pcscd[14439]: 00000003 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 08 10:13:10 curie pcscd[14439]: 33826818 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 08 10:17:53 curie pcscd[14439]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/16): -4 LIBUSB_ERROR_NO_DEVICE
Feb 08 10:17:58 curie pcscd[14439]: 04993454 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 08 10:17:58 curie pcscd[14439]: 00000012 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/017)
Feb 08 10:17:58 curie pcscd[14439]: 00000004 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 08 10:18:11 curie pcscd[14439]: 12507788 winscard.c:283:SCardConnect() Error Reader Exclusive
...skipping...
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 06 19:48:27 curie pcscd[3100]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 06 19:48:27 curie pcscd[3100]: 00000014 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/013)
Feb 06 19:48:27 curie pcscd[3100]: 00000003 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 06 19:48:49 curie pcscd[3100]: 22045135 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:49:15 curie pcscd[3100]: 26297901 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:49:56 curie pcscd[3100]: 41260039 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:50:06 curie pcscd[3100]: 09673127 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 19:51:25 curie pcscd[3100]: 78500770 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 20:22:55 curie pcscd[3100]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 20:23:00 curie pcscd[3100]: 04869977 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 06 20:30:37 curie pcscd[3100]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/13): -4 LIBUSB_ERROR_NO_DEVICE
Feb 07 20:55:56 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 07 20:55:56 curie pcscd[21376]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 07 20:55:56 curie pcscd[21376]: 00000012 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/015)
Feb 07 20:55:56 curie pcscd[21376]: 00000004 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 07 20:56:02 curie pcscd[21376]: 05648900 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 20:56:12 curie pcscd[21376]: 09655699 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 20:56:36 curie pcscd[21376]: 24217358 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 21:06:59 curie pcscd[21376]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 21:08:03 curie pcscd[21376]: 63425592 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 22:01:33 curie pcscd[21376]: 99999999 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 07 22:27:31 curie pcscd[21376]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/15): -4 LIBUSB_ERROR_NO_DEVICE
Feb 08 10:12:36 curie systemd[1]: Started PC/SC Smart Card Daemon.
-- Subject: Unit pcscd.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
-- 
-- Unit pcscd.service has finished starting up.
-- 
-- The start-up result is done.
Feb 08 10:12:36 curie pcscd[14439]: 00000000 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 08 10:12:36 curie pcscd[14439]: 00000014 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/016)
Feb 08 10:12:36 curie pcscd[14439]: 00000003 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 08 10:13:10 curie pcscd[14439]: 33826818 winscard.c:283:SCardConnect() Error Reader Exclusive
Feb 08 10:17:53 curie pcscd[14439]: 99999999 ccid_usb.c:797:WriteUSB() write failed (1/16): -4 LIBUSB_ERROR_NO_DEVICE
Feb 08 10:17:58 curie pcscd[14439]: 04993454 ifdhandler.c:151:CreateChannelByNameOrChannel() failed
Feb 08 10:17:58 curie pcscd[14439]: 00000012 readerfactory.c:1110:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0111:libudev:0:/dev/bus/usb/001/017)
Feb 08 10:17:58 curie pcscd[14439]: 00000004 readerfactory.c:375:RFAddReader() Yubico Yubikey NEO OTP+CCID init failed.
Feb 08 10:18:11 curie pcscd[14439]: 12507788 winscard.c:283:SCardConnect() Error Reader Exclusive

More information about the pkg-gnupg-maint mailing list