Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

smart card error 6a80: incorrect parameter in command data field on linux #15

Open
alberts opened this issue May 10, 2020 · 6 comments
Open

smart card error 6a80: incorrect parameter in command data field on linux #15

alberts opened this issue May 10, 2020 · 6 comments
Labels
upstream This is an issue in piv-go waiting for info Needs more information from the reporter

Comments

@alberts
Copy link

alberts commented May 10, 2020

with commit 742bc61
and pcsc-lite-devel-1.8.26-1.fc31.x86_64 on fedora 31

$ ykman info
Device type: YubiKey 4
Firmware version: 4.3.7
Enabled USB interfaces: FIDO+CCID

Applications
OTP Disabled
FIDO U2F Enabled
OpenPGP Enabled
PIV Enabled
OATH Enabled
FIDO2 Not available

$ ykman piv reset
WARNING! This will delete all stored PIV data and restore factory settings. Proceed? [y/N]: y
Resetting PIV data...
Success! All PIV data have been cleared from your YubiKey.
Your YubiKey now has the default PIN, PUK and Management Key:
PIN: 123456
PUK: 12345678
Management Key: 010203040506070801020304050607080102030405060708

I get the following error:

The default PIN did not work

printed out the err, which says:

smart card error 6a80: incorrect parameter in command data field
@ericchiang
Copy link

What go-piv command is actually being executed?

@FiloSottile FiloSottile added the upstream This is an issue in piv-go label May 13, 2020
@alberts
Copy link
Author

alberts commented May 15, 2020 •
edited by FiloSottile
Loading

Here's the debug log from pscsd in case that's helpful.

Let me know if there's anything else I can provide.

May 14 18:41:49 localhost.localdomain systemd[1]: Started PC/SC Smart Card Daemon.
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000000 debuglog.c:299:DebugLogSetLevel() debug level=debug
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000044 pcscdaemon.c:451:main() Started by systemd
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000161 configfile.l:293:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000019 configfile.l:329:DBGetReaderListDir() Skipping non regular file: ..
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000004 configfile.l:369:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000058 configfile.l:329:DBGetReaderListDir() Skipping non regular file: .
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000010 pcscdaemon.c:663:main() pcsc-lite 1.8.26 daemon ready.
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00004775 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000124 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000122 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0406, path: /dev/bus/usb/001/002
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000009 hotplug_libudev.c:440:HPAddDevice() Adding USB device: Yubico YubiKey FIDO+CCID
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000037 readerfactory.c:1074:RFInitializeReader() Attempting startup of Yubico YubiKey FIDO+CCID 00 00 using /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000266 readerfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000025 ifdhandler.c:1961:init_driver() Driver version: 1.4.31
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000712 ifdhandler.c:1978:init_driver() LogLevel: 0x0003
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000008 ifdhandler.c:1989:init_driver() DriverOptions: 0x0000
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000214 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0406:libudev:0:/dev/bus/usb/001/002
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000011 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000710 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau ([email protected])
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000008 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
May 14 18:41:49 localhost.localdomain pcscd[336623]: 00000003 ccid_usb.c:322:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00112431 ccid_usb.c:636:OpenUSBByName() Found interface 1 but expecting 0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000020 ccid_usb.c:638:OpenUSBByName() Wrong interface for USB device 1/2. Checking next one.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000313 ccid_usb.c:189:close_libusb_if_needed() libusb_exit
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000187 ccid_usb.c:799:OpenUSBByName() Device not found?
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000017 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000006 readerfactory.c:1105:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0406:libudev:0:/dev/bus/usb/001/002)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:376:RFAddReader() Yubico YubiKey FIDO+CCID init failed.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:610:RFRemoveReader() UnrefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:1125:RFUnInitializeReader() Attempting shutdown of Yubico YubiKey FIDO+CCID 00 00.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:987:RFUnloadReader() Unloading reader driver.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000345 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0406, path: /dev/bus/usb/001/002
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000015 hotplug_libudev.c:440:HPAddDevice() Adding USB device: Yubico YubiKey FIDO+CCID
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000055 readerfactory.c:1074:RFInitializeReader() Attempting startup of Yubico YubiKey FIDO+CCID 00 00 using /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000337 readerfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000027 ifdhandler.c:1961:init_driver() Driver version: 1.4.31
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000832 ifdhandler.c:1978:init_driver() LogLevel: 0x0003
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000009 ifdhandler.c:1989:init_driver() DriverOptions: 0x0000
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000242 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0406:libudev:1:/dev/bus/usb/001/002
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000012 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000800 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau ([email protected])
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000009 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 ccid_usb.c:322:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00003632 ccid_usb.c:659:OpenUSBByName() Found Vendor/Product: 1050/0406 (Yubico YubiKey FIDO+CCID)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000009 ccid_usb.c:661:OpenUSBByName() Using USB bus/device: 1/2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 ccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00102491 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB3, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000017 readerfactory.c:396:RFAddReader() Using the reader polling thread
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000307 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAE, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000010 ifdhandler.c:476:IFDHGetCapabilities() Reader supports 1 slot(s)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000225 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000110 ifdhandler.c:1153:IFDHPowerICC() action: PowerUp, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000031 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0BDA, PID: 0x58FD, path: /dev/bus/usb/001/003
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000102 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0BDA, PID: 0x58FD, path: /dev/bus/usb/001/003
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000036 eventhandler.c:289:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 Card ATR: 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000052 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0BDA, PID: 0x58FD, path: /dev/bus/usb/001/003
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000090 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0BDA, PID: 0x58FD, path: /dev/bus/usb/001/003
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000086 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000095 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0A5C, PID: 0x5843, path: /dev/bus/usb/001/004
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 hotplug_libudev.c:440:HPAddDevice() Adding USB device: Broadcom Corp 58200
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000048 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAF, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:726:RFSetReaderName() Support 16 simultaneous readers
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAD, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:338:RFAddReader() Driver is thread safe
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:1074:RFInitializeReader() Attempting startup of Broadcom Corp 58200 [Broadcom ControlVault 3 w/FingerPrint] (0123456789ABCD) 01 00 using /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:863:RFLoadReader() Reusing already loaded driver for /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000008 readerfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000013 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 10000, device: usb:0a5c/5843:libudev:0:/dev/bus/usb/001/004
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000619 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau ([email protected])
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000008 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 ccid_usb.c:322:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00096071 ccid_usb.c:636:OpenUSBByName() Found interface 1 but expecting 0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000015 ccid_usb.c:638:OpenUSBByName() Wrong interface for USB device 1/4. Checking next one.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000389 ccid_usb.c:799:OpenUSBByName() Device not found?
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000010 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:1105:RFInitializeReader() Open Port 0x200001 Failed (usb:0a5c/5843:libudev:0:/dev/bus/usb/001/004)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:376:RFAddReader() Broadcom Corp 58200 [Broadcom ControlVault 3 w/FingerPrint] (0123456789ABCD) init failed.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:610:RFRemoveReader() UnrefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:1125:RFUnInitializeReader() Attempting shutdown of Broadcom Corp 58200 [Broadcom ControlVault 3 w/FingerPrint] (0123456789ABCD) 01 00.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000174 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0A5C, PID: 0x5843, path: /dev/bus/usb/001/004
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 hotplug_libudev.c:440:HPAddDevice() Adding USB device: Broadcom Corp 58200
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000053 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAF, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:726:RFSetReaderName() Support 16 simultaneous readers
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAD, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:338:RFAddReader() Driver is thread safe
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:1074:RFInitializeReader() Attempting startup of Broadcom Corp 58200 [Contacted SmartCard] (0123456789ABCD) 01 00 using /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:863:RFLoadReader() Reusing already loaded driver for /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000019 readerfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000014 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 10000, device: usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000008 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000523 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau ([email protected])
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000006 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 ccid_usb.c:322:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000122 ccid_usb.c:659:OpenUSBByName() Found Vendor/Product: 0A5C/5843 (Broadcom Corp 58200)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 ccid_usb.c:661:OpenUSBByName() Using USB bus/device: 1/4
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 ccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00114282 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB3, usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004 (lun: 10000)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000037 readerfactory.c:396:RFAddReader() Using the reader polling thread
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00003343 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAE, usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004 (lun: 10000)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000020 ifdhandler.c:476:IFDHGetCapabilities() Reader supports 1 slot(s)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000376 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000380 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000307 hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000167 readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Broadcom Corp 58200 [Contacted SmartCard] (0123456789ABCD) 01 00
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00010125 winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000020 winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00014602 winscard_svc.c:340:ContextThread() Authorized PC/SC client
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000008 winscard_svc.c:343:ContextThread() Thread is started: dwClientID=15, threadContext @0x563f7a172c60
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000005 winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 winscard_svc.c:373:ContextThread() Client is protocol version 4:4
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000023 winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000005 winscard.c:215:SCardEstablishContext() Establishing Context: 0x7C8DF365
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000026 winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000037 winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000024 winscard_svc.c:361:ContextThread() Received command: RELEASE_CONTEXT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:229:SCardReleaseContext() Releasing Context: 0x7C8DF365
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:476:ContextThread() RELEASE_CONTEXT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000010 winscard_svc.c:354:ContextThread() Client die: 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=15, threadContext @0x563f7a172c60
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x563f7a172c60
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:1077:MSGCleanupClient() Starting suicide alarm in 60 seconds
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000043 winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00005351 winscard_svc.c:340:ContextThread() Authorized PC/SC client
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 winscard_svc.c:343:ContextThread() Thread is started: dwClientID=15, threadContext @0x563f7a15de70
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000005 winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:373:ContextThread() Client is protocol version 4:4
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000015 winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 winscard.c:215:SCardEstablishContext() Establishing Context: 0x51CE304
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000020 winscard_svc.c:361:ContextThread() Received command: CONNECT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00010711 winscard_svc.c:499:ContextThread() Authorized client for 'Yubico YubiKey FIDO+CCID 00 00'
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000009 winscard.c:258:SCardConnect() Attempting Connect to Yubico YubiKey FIDO+CCID 00 00 using protocol: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:821:RFReaderInfo() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 prothandler.c:107:PHSetProtocol() Attempting PTS to T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:694:IFDHSetProtocolParameters() protocol T=1, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:430:SCardConnect() Active Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:456:SCardConnect() hCard Identity: 2a99981e
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:518:SCardConnect() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 winscard_svc.c:513:ContextThread() CONNECT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000035 winscard_svc.c:361:ContextThread() Received command: BEGIN_TRANSACTION from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:1082:SCardBeginTransaction() Status: 0x00000000
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:565:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000092 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000007 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00001012 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000009 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000027 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000878 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000060 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000001 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00004963 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000010 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000085 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000005 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00004978 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000011 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000044 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000006 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000002 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00004972 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000022 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000180 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000012 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00005000 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000018 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000168 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000013 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00004908 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000016 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000085 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000017 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000005 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00004951 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000014 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000061 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000008 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:50 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:52 localhost.localdomain pcscd[336623]: 01570543 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00000048 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00000240 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00000025 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00000005 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00000006 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00005070 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:52 localhost.localdomain pcscd[336623]: 00000028 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 01471795 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000035 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000005 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000005 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00001558 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000016 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000275 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000017 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00001495 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000016 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000131 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000013 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00005001 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000015 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000104 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000013 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00001466 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000016 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000138 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000018 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00001499 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000016 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000132 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000013 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00013096 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000025 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000164 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000015 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1595:SCardTransmit() Send Protocol: T=1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:1302:IFDHTransmitToICC() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00008884 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000018 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000665 winscard_svc.c:354:ContextThread() Client die: 15
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000022 winscard.c:229:SCardReleaseContext() Releasing Context: 0x51CE304
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000005 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 winscard.c:1300:SCardStatus() UnrefReader() count was: 3
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 winscard.c:881:SCardDisconnect() Active Contexts: -1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000002 winscard.c:882:SCardDisconnect() dwDisposition: 1
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000270 ifdhandler.c:1153:IFDHPowerICC() action: Reset, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000259 winscard.c:920:SCardDisconnect() Reset complete.
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000014 Card ATR: 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 winscard.c:1017:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB2, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000002 winscard.c:1030:SCardDisconnect() Stopping polling thread
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:345:IFDHStopPolling() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000043 winscard.c:1043:SCardDisconnect() UnrefReader() count was: 3
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 winscard_svc.c:931:MSGRemoveContext() UnrefReader() count was: 2
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000004 winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=15, threadContext @0x563f7a15de70
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000002 winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x563f7a15de70
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00000003 winscard_svc.c:1077:MSGCleanupClient() Starting suicide alarm in 60 seconds
May 14 18:41:53 localhost.localdomain pcscd[336623]: 00400413 eventhandler.c:494:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
May 14 18:41:58 localhost.localdomain pcscd[336623]: 05001142 ifdhandler.c:1153:IFDHPowerICC() action: PowerDown, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:41:58 localhost.localdomain pcscd[336623]: 00000303 eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
May 14 18:42:53 localhost.localdomain pcscd[336623]: 54598330 pcscdaemon.c:193:signal_thread() Received signal: 14
May 14 18:42:53 localhost.localdomain pcscd[336623]: 00000019 pcscdaemon.c:227:signal_thread() Preparing for suicide
May 14 18:42:53 localhost.localdomain pcscd[336623]: 00001194 hotplug_libudev.c:717:HPStopHotPluggables() Hotplug stopped
May 14 18:42:54 localhost.localdomain pcscd[336623]: 01000149 readerfactory.c:1353:RFCleanupReaders() entering cleaning function
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000010 readerfactory.c:1361:RFCleanupReaders() Stopping reader: Yubico YubiKey FIDO+CCID 00 00
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000002 readerfactory.c:610:RFRemoveReader() UnrefReader() count was: 1
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000002 eventhandler.c:175:EHDestroyEventHandler() Stomping thread.
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB1, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000001 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB2, usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000001 eventhandler.c:200:EHDestroyEventHandler() Request stopping of polling thread
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000001 ifdhandler.c:345:IFDHStopPolling() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00400358 eventhandler.c:504:EHStatusHandlerThread() Die
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000064 eventhandler.c:215:EHDestroyEventHandler() Thread stomped.
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000005 readerfactory.c:1125:RFUnInitializeReader() Attempting shutdown of Yubico YubiKey FIDO+CCID 00 00.
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000003 ifdhandler.c:283:IFDHCloseChannel() usb:1050/0406:libudev:1:/dev/bus/usb/001/002 (lun: 0)
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000225 readerfactory.c:1361:RFCleanupReaders() Stopping reader: Broadcom Corp 58200 [Contacted SmartCard] (0123456789ABCD) 01 00
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000010 readerfactory.c:610:RFRemoveReader() UnrefReader() count was: 1
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000002 eventhandler.c:175:EHDestroyEventHandler() Stomping thread.
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000002 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB1, usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004 (lun: 10000)
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000001 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB2, usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004 (lun: 10000)
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000001 eventhandler.c:200:EHDestroyEventHandler() Request stopping of polling thread
May 14 18:42:54 localhost.localdomain pcscd[336623]: 00000001 ifdhandler.c:345:IFDHStopPolling() usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004 (lun: 10000)
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00400206 eventhandler.c:504:EHStatusHandlerThread() Die
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000177 eventhandler.c:215:EHDestroyEventHandler() Thread stomped.
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000020 readerfactory.c:1125:RFUnInitializeReader() Attempting shutdown of Broadcom Corp 58200 [Contacted SmartCard] (0123456789ABCD) 01 00.
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000009 ifdhandler.c:283:IFDHCloseChannel() usb:0a5c/5843:libudev:1:/dev/bus/usb/001/004 (lun: 10000)
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000550 commands.c:1109:CmdPowerOff Card absent or mute
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000083 ccid_usb.c:189:close_libusb_if_needed() libusb_exit
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000281 readerfactory.c:987:RFUnloadReader() Unloading reader driver.
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000106 winscard_svc.c:155:ContextsDeinitialize() remaining threads: 0
May 14 18:42:55 localhost.localdomain pcscd[336623]: 00000008 pcscdaemon.c:787:at_exit() cleaning /var/run/pcscd
May 14 18:42:55 localhost.localdomain systemd[1]: pcscd.service: Succeeded.

@FiloSottile
Copy link
Owner

Can you test if v0.1.2 or later works? Thank you!

@FiloSottile FiloSottile added the waiting for info Needs more information from the reporter label Jun 20, 2020
@andrewd18
Copy link

I am encountering a similar issue on Debian 10 using Go 1.13 from backports. I'm using commit 4998d8c3 pulled from today's master.

andrew@Phobetor:~$ ykman info
Device type: YubiKey 5 NFC
Serial number: 11463649
Firmware version: 5.2.4
Form factor: Keychain (USB-A)
Enabled USB interfaces: OTP+FIDO+CCID
NFC interface is enabled.

Applications    USB     NFC    
OTP             Enabled Enabled
FIDO U2F        Enabled Enabled
OpenPGP         Enabled Enabled
PIV             Enabled Enabled
OATH            Enabled Enabled
FIDO2           Enabled Enabled
andrew@Phobetor:~$ ykman piv reset
WARNING! This will delete all stored PIV data and restore factory settings. Proceed? [y/N]: y
Resetting PIV data...
Success! All PIV data have been cleared from your YubiKey.
Your YubiKey now has the default PIN, PUK and Management Key:
        PIN:    123456
        PUK:    12345678
        Management Key: 010203040506070801020304050607080102030405060708
andrew@Phobetor:~$ ~/bin/yubikey-agent/yubikey-agent -setup
🔐 The PIN is up to 8 numbers, letters, or symbols. Not just numbers!
❌ The key will be lost if the PIN and PUK are locked after 3 incorrect tries.

Choose a new PIN/PUK: 
Repeat PIN/PUK: 

🧪 Reticulating splines...
‼️  The default PIN did not work

If you know what you're doing, reset PIN, PUK, and
Management Key to the defaults before retrying.

If you want to wipe all PIV keys and start fresh,
use --really-delete-all-piv-keys ⚠️

I don't know where the pcscd debug log lives but would be happy to provide more info or test more things if you can tell me where to look. 😄

@asychev
Copy link

asychev commented Feb 5, 2021

Same (The default PIN did not work) for me for a brand new YubiKey 5C NFC

@nidkil
Copy link

nidkil commented May 26, 2021

I'm running into the same error.

sign_and_send_pubkey: signing failed for ECDSA "YubiKey #14308549 PIV Slot 9a" from agent: agent refused operation

I'm running on Manjaro 21.05 and Go version 1.16.4.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
upstream This is an issue in piv-go waiting for info Needs more information from the reporter
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
6 participants
@alberts @andrewd18 @FiloSottile @ericchiang @nidkil @asychev