[Pcsclite-muscle] pcscd errors break https for chromium-based browsers

Arthur Clément arthur.clement at protonmail.com
Tue Feb 26 10:32:47 PST 2019


I managed to reproduce, please find the log attached.


The final logs "winscard.c:296:SCardConnect() Waiting for release of lock" are related to chromium stops/starts (when https was not working anymore).
I also tried to run yubioath when I encountered the https issue, with no luck
I hope it can help.




‐‐‐‐‐‐‐ Original Message ‐‐‐‐‐‐‐
On Tuesday 26 February 2019 09:17, Arthur Clément <arthur.clement at protonmail.com> wrote:

> Hi,
>
> I launched the debug mode but it can take a while before reproducing the issue.
>
> But I just experienced and interesting thing: pcscd was waiting for a lock, and the lock has been released when I closed chromium browser.
> The log (not closed yet) attached, I'm still waiting for the ssl issue.
>
> ## Unlocked when I closed chromium :
>
> 00000003 [139633404929792] winscard.c:296:SCardConnect() Waiting for release of lock
> 24628039 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000011 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000135 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000005 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000087 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000005 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000089 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000005 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000087 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000005 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000091 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000005 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000084 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000006 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000019 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000004 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000033 [139633379751680] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14
> 00000009 [139633379751680] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2
> 00000003 [139633379751680] winscard.c:1601:SCardTransmit() Send Protocol: T=1
> 00000003 [139633379751680] APDU: 00 20 00 80
> 00000004 [139633379751680] ifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000002 [139633379751680] commands.c:1672:CmdXfrBlockAPDU_extended() T=0 (extended): 4 bytes
> 00000004 [139633379751680] -> 000000 6F 04 00 00 00 00 E8 00 00 00 00 20 00 80
> 00000121 [139633379751680] <- 000000 81 00 00 00 00 00 E8 40 FC 00
> 00000005 [139633379751680] commands.c:1564:CCID_Receive Overrun error
> 00000002 [139633379751680] SW:
> 00000002 [139633379751680] ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> 00000002 [139633379751680] winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> 00000001 [139633379751680] winscard.c:1646:SCardTransmit() UnrefReader() count was: 3
> 00000002 [139633379751680] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x80100016 for client 14
> 00000020 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000003 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000021 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000003 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000014 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000002 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000014 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000015 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000016 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000012 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000002 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000013 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000029 [139633379751680] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14
> 00000006 [139633379751680] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2
> 00000002 [139633379751680] winscard.c:1601:SCardTransmit() Send Protocol: T=1
> 00000002 [139633379751680] APDU: 00 20 00 80
> 00000002 [139633379751680] ifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000001 [139633379751680] commands.c:1672:CmdXfrBlockAPDU_extended() T=0 (extended): 4 bytes
> 00000003 [139633379751680] -> 000000 6F 04 00 00 00 00 E9 00 00 00 00 20 00 80
> 00000132 [139633379751680] <- 000000 81 00 00 00 00 00 E9 40 FC 00
> 00000002 [139633379751680] commands.c:1564:CCID_Receive Overrun error
> 00000001 [139633379751680] SW:
> 00000001 [139633379751680] ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> 00000002 [139633379751680] winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> 00000001 [139633379751680] winscard.c:1646:SCardTransmit() UnrefReader() count was: 3
> 00000001 [139633379751680] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x80100016 for client 14
> 00000014 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000016 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000013 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000013 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000013 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000002 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000015 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 00000013 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
> 00000007 [139633379751680] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14
> 00000013 [139633379751680] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
> 00000001 [139633379751680] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
> 01095199 [139633379751680] winscard_svc.c:354:ContextThread() Client die: 14
> 00000025 [139633379751680] winscard.c:229:SCardReleaseContext() Releasing Context: 0x453300CB
> 00000009 [139633379751680] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2
> 00000003 [139633379751680] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 3
> 00000002 [139633379751680] winscard.c:1306:SCardStatus() UnrefReader() count was: 4
> 00000003 [139633379751680] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 3
> 00000002 [139633379751680] winscard.c:884:SCardDisconnect() Active Contexts: 1
> 00000001 [139633379751680] winscard.c:885:SCardDisconnect() dwDisposition: 1
> 00000004 [139633379751680] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000005 [139633379751680] -> 000000 65 00 00 00 00 00 EA 00 00 00
> 00000232 [139633379751680] <- 000000 81 00 00 00 00 00 EA 00 00 00
> 00000008 [139633379751680] ifdhandler.c:1942:IFDHICCPresence() Card present
> 00000004 [139633379751680] ifdhandler.c:1154:IFDHPowerICC() action: Reset, usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000002 [139633379751680] -> 000000 62 00 00 00 00 00 EB 00 00 00
> 00000239 [139633379751680] <- 000000 80 12 00 00 00 00 EB 00 00 00 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4
> 00000009 [139633379751680] winscard.c:923:SCardDisconnect() Reset complete.
> 00000006 [139633379751680] Card ATR: 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4
> 00000003 [139633379751680] winscard.c:1020:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
> 00000003 [139633379751680] ifdhandler.c:381:IFDHGetCapabilities() tag: 0xFB2, usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000003 [139633379751680] winscard.c:1033:SCardDisconnect() Stopping polling thread
> 00000002 [139633379751680] ifdhandler.c:346:IFDHStopPolling() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000064 [139633379751680] winscard.c:1046:SCardDisconnect() UnrefReader() count was: 4
> 00000005 [139633490528000] ccid_usb.c:1352:InterruptRead() after (0) (3)
> 00000002 [139633379751680] winscard_svc.c:931:MSGRemoveContext() UnrefReader() count was: 3
> 00000008 [139633490528000] ccid_usb.c:1367:InterruptRead() InterruptRead (1/2): 3
> 00000008 [139633379751680] winscard_svc.c:1057:MSGCleanupClient() Thread is stopping: dwClientID=14, threadContext @0x55a33e312980
> 00000002 [139633379751680] winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x55a33e312980
> 00099190 [139633404929792] winscard.c:299:SCardConnect() Lock released
> 00000007 [139633404929792] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE
> 00000002 [139633404929792] prothandler.c:108:PHSetProtocol() Attempting PTS to T=1
> 00000003 [139633404929792] ifdhandler.c:695:IFDHSetProtocolParameters() protocol T=1, usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000001 [139633404929792] ifdhandler.c:709:IFDHSetProtocolParameters() Timeout: 3000 ms
> 00000001 [139633404929792] winscard.c:431:SCardConnect() Active Protocol: T=1
> 00000002 [139633404929792] winscard.c:456:SCardConnect() hCard Identity: 19a994fb
> 00000002 [139633404929792] winscard.c:518:SCardConnect() UnrefReader() count was: 2
> 00000003 [139633404929792] winscard_svc.c:511:ContextThread() CONNECT rv=0x0 for client 19
> 00000253 [139633404929792] winscard_svc.c:362:ContextThread() Received command: RELEASE_CONTEXT from client 19
> 00000006 [139633404929792] winscard.c:229:SCardReleaseContext() Releasing Context: 0x42D61F53
> 00000002 [139633404929792] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
> 00000002 [139633404929792] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2
> 00000001 [139633404929792] winscard.c:884:SCardDisconnect() Active Contexts: 1
> 00000002 [139633404929792] winscard.c:885:SCardDisconnect() dwDisposition: 0
> 00000003 [139633404929792] winscard.c:1020:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
> 00000004 [139633404929792] ifdhandler.c:381:IFDHGetCapabilities() tag: 0xFB2, usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000002 [139633404929792] winscard.c:1033:SCardDisconnect() Stopping polling thread
> 00000003 [139633404929792] ifdhandler.c:346:IFDHStopPolling() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000002 [139633404929792] winscard.c:1046:SCardDisconnect() UnrefReader() count was: 3
> 00000002 [139633404929792] winscard_svc.c:931:MSGRemoveContext() UnrefReader() count was: 2
> 00000001 [139633404929792] winscard_svc.c:474:ContextThread() RELEASE_CONTEXT rv=0x0 for client 19
> 00001168 [139633404929792] winscard_svc.c:354:ContextThread() Client die: 19
> 00000012 [139633404929792] winscard_svc.c:1057:MSGCleanupClient() Thread is stopping: dwClientID=19, threadContext @0x55a33e2f3e80
> 00000001 [139633404929792] winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x55a33e2f3e80
> 00019396 [139633371358976] winscard_svc.c:354:ContextThread() Client die: 17
> 00000064 [139633371358976] winscard.c:229:SCardReleaseContext() Releasing Context: 0xC9518A8
> 00000007 [139633371358976] winscard_svc.c:1057:MSGCleanupClient() Thread is stopping: dwClientID=17, threadContext @0x55a33e330cc0
> 00000002 [139633371358976] winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x55a33e330cc0
> 00279938 [139633490528000] eventhandler.c:494:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
> 00000009 [139633490528000] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000003 [139633490528000] -> 000000 65 00 00 00 00 00 EC 00 00 00
> 00000156 [139633490528000] <- 000000 81 00 00 00 00 00 EC 00 00 00
> 00000003 [139633490528000] ifdhandler.c:1942:IFDHICCPresence() Card present
> 00000002 [139633490528000] ifdhandler.c:310:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0) 5000 ms
> 00000002 [139633490528000] ccid_usb.c:1306:InterruptRead() before (0)
> 05000224 [139633490528000] ccid_usb.c:1352:InterruptRead() after (0) (2)
> 00000028 [139633490528000] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000011 [139633490528000] -> 000000 65 00 00 00 00 00 ED 00 00 00
> 00000370 [139633490528000] <- 000000 81 00 00 00 00 00 ED 00 00 00
> 00000028 [139633490528000] ifdhandler.c:1942:IFDHICCPresence() Card present
> 00000012 [139633490528000] ifdhandler.c:1154:IFDHPowerICC() action: PowerDown, usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000013 [139633490528000] -> 000000 63 00 00 00 00 00 EE 00 00 00
> 00000175 [139633490528000] <- 000000 81 00 00 00 00 00 EE 02 00 00
> 00000011 [139633490528000] eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
> 00000010 [139633490528000] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0)
> 00000013 [139633490528000] -> 000000 65 00 00 00 00 00 EF 00 00 00
> 00000139 [139633490528000] <- 000000 81 00 00 00 00 00 EF 00 00 00
> 00000010 [139633490528000] ifdhandler.c:1942:IFDHICCPresence() Card present
> 00000007 [139633490528000] ifdhandler.c:310:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/001/002 (lun: 0) 600000 ms
> 00000006 [139633490528000] ccid_usb.c:1306:InterruptRead() before (0)
>
>
>
> ‐‐‐‐‐‐‐ Original Message ‐‐‐‐‐‐‐
> On Monday 25 February 2019 10:09, Ludovic Rousseau <ludovic.rousseau at gmail.com> wrote:
>
> > hello
> >
> > I see errors in your pcscd log.
> > Can you generate a full log as described in https://pcsclite.apdu.fr/#support
> >
> > Bye
> >
> > Le lun 25 févr. 2019 10:05, Arthur Clément <arthur.clement at protonmail.com> a écrit :
> >
> > > Hi,
> > >
> > > I would like to report a weird problem. Since I upgraded Fedora 28 to 29 few months ago, I have an issue with pcscd and my yubikey : yubioath only works on the CLI and I have to restart the pcscd service every 2 or 3 days.
> > > With the upgrade to Fedora 29, I also had a problem with chrome, chromium and Brave : https did not work anymore after a while (http was working). Firefox was still working with https.
> > >
> > > I found out last week that the https issue was directly linked to the pcscd service. When it goes crazy, https is broken and a simple stop/start of the service fix yubioath and chromium ! I guess pcscd is monopolizing a security lib used by chromium for https.
> > >
> > > Arthur
> > >
> > > --------------
> > >
> > > Infos & logs
> > >
> > > % pcscd -v
> > > pcsc-lite version 1.8.24.
> > > Copyright (C) 1999-2002 by David Corcoran <corcoran at musclecard.com>.
> > > Copyright (C) 2001-2015 by Ludovic Rousseau <ludovic.rousseau at free.fr>.
> > > Copyright (C) 2003-2004 by Damien Sauveron <sauveron at labri.fr>.
> > > Report bugs to <pcsclite-muscle at lists.alioth.debian.org>.
> > > Enabled features: Linux x86_64-redhat-linux-gnu libsystemd serial usb libudev usbdropdir=/usr/lib64/pcsc/drivers ipcdir=/var/run/pcscd configdir=/etc/reader.conf.d
> > >
> > > ------------
> > >
> > > % rpm -qa | grep pcsc
> > > pcsc-perl-1.4.14-9.fc29.x86_64
> > > pcsc-lite-1.8.24-1.fc29.x86_64
> > > pcsc-tools-1.5.2-2.fc29.x86_64
> > > pcsc-lite-ccid-1.4.30-1.fc29.x86_64
> > > pcsc-lite-libs-1.8.24-1.fc29.x86_64
> > >
> > > -------------
> > >
> > > journalctl -u pcscd -r
> > > -- Logs begin at Mon 2018-07-02 10:08:02 CEST, end at Mon 2019-02-25 08:56:40 CET. --
> > > févr. 25 08:54:50 desktop pcscd[12183]: 00000002 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 25 08:54:50 desktop pcscd[12183]: 00000007 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/002)
> > > févr. 25 08:54:50 desktop pcscd[12183]: 00003432 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 25 08:54:50 desktop pcscd[12183]: 00000006 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 25 08:54:50 desktop pcscd[12183]: 00000085 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/001/002)
> > > févr. 25 08:54:50 desktop pcscd[12183]: 00000000 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 25 08:54:50 desktop systemd[1]: Started PC/SC Smart Card Daemon.
> > > févr. 25 08:54:50 desktop systemd[1]: Stopped PC/SC Smart Card Daemon.
> > > févr. 25 08:54:50 desktop systemd[1]: pcscd.service: Failed with result 'exit-code'.
> > > févr. 25 08:54:50 desktop systemd[1]: pcscd.service: Main process exited, code=exited, status=1/FAILURE
> > > févr. 25 08:54:50 desktop systemd[1]: Stopping PC/SC Smart Card Daemon...
> > > févr. 25 08:54:37 desktop pcscd[8937]: 00000002 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:54:37 desktop pcscd[8937]: 00000005 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:54:37 desktop pcscd[8937]: 00001313 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:54:37 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:54:37 desktop pcscd[8937]: 00000012 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:54:37 desktop pcscd[8937]: 99999999 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:33 desktop pcscd[8937]: 00000010 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:33 desktop pcscd[8937]: 00000021 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:33 desktop pcscd[8937]: 01053665 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:32 desktop pcscd[8937]: 00000008 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:32 desktop pcscd[8937]: 00000036 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:32 desktop pcscd[8937]: 00159719 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:32 desktop pcscd[8937]: 00000006 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:32 desktop pcscd[8937]: 00000017 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:32 desktop pcscd[8937]: 00874161 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:31 desktop pcscd[8937]: 00000008 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:31 desktop pcscd[8937]: 00000027 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:31 desktop pcscd[8937]: 00173460 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:31 desktop pcscd[8937]: 00000008 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:31 desktop pcscd[8937]: 00000025 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:31 desktop pcscd[8937]: 20353903 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:10 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:10 desktop pcscd[8937]: 00000005 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:10 desktop pcscd[8937]: 00001191 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:50:10 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:50:10 desktop pcscd[8937]: 00000030 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:50:10 desktop pcscd[8937]: 37926950 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:49:32 desktop pcscd[8937]: 00000004 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:49:32 desktop pcscd[8937]: 00000014 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:49:32 desktop pcscd[8937]: 00001051 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:49:32 desktop pcscd[8937]: 00000004 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:49:32 desktop pcscd[8937]: 00000025 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:49:32 desktop pcscd[8937]: 03082224 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:49:29 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:49:29 desktop pcscd[8937]: 00000008 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:49:29 desktop pcscd[8937]: 00001740 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:49:29 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:49:29 desktop pcscd[8937]: 00000017 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:49:29 desktop pcscd[8937]: 47478424 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:48:42 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:48:42 desktop pcscd[8937]: 00000016 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:48:42 desktop pcscd[8937]: 00002033 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:48:42 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:48:42 desktop pcscd[8937]: 00000022 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:48:42 desktop pcscd[8937]: 15270830 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:48:27 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:48:27 desktop pcscd[8937]: 00000010 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:48:27 desktop pcscd[8937]: 00001377 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:48:27 desktop pcscd[8937]: 00000004 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:48:27 desktop pcscd[8937]: 00000011 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:48:27 desktop pcscd[8937]: 01226601 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:48:25 desktop pcscd[8937]: 00000002 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:48:25 desktop pcscd[8937]: 00000006 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:48:25 desktop pcscd[8937]: 00001276 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:48:25 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:48:25 desktop pcscd[8937]: 00000009 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:48:25 desktop pcscd[8937]: 50097325 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:47:35 desktop pcscd[8937]: 00000005 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:47:35 desktop pcscd[8937]: 00000011 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:47:35 desktop pcscd[8937]: 00002946 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:47:35 desktop pcscd[8937]: 00000006 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:47:35 desktop pcscd[8937]: 00000021 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:47:35 desktop pcscd[8937]: 66827022 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:28 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:28 desktop pcscd[8937]: 00000014 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:28 desktop pcscd[8937]: 00001672 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:28 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:28 desktop pcscd[8937]: 00000011 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:28 desktop pcscd[8937]: 01049912 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:27 desktop pcscd[8937]: 00000004 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:27 desktop pcscd[8937]: 00000012 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:27 desktop pcscd[8937]: 00001611 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:27 desktop pcscd[8937]: 00000004 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:27 desktop pcscd[8937]: 00000026 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:27 desktop pcscd[8937]: 04869875 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:22 desktop pcscd[8937]: 00000002 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:22 desktop pcscd[8937]: 00000004 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:22 desktop pcscd[8937]: 00001154 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:22 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:22 desktop pcscd[8937]: 00000017 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:22 desktop pcscd[8937]: 14943735 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:08 desktop pcscd[8937]: 00000005 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:08 desktop pcscd[8937]: 00000015 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:08 desktop pcscd[8937]: 00003405 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:46:08 desktop pcscd[8937]: 00000008 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:46:08 desktop pcscd[8937]: 00000027 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:46:08 desktop pcscd[8937]: 99999999 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:42:18 desktop pcscd[8937]: 00000005 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:42:18 desktop pcscd[8937]: 00000014 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:42:18 desktop pcscd[8937]: 00002570 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:42:18 desktop pcscd[8937]: 00000006 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:42:18 desktop pcscd[8937]: 00000017 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:42:18 desktop pcscd[8937]: 22527473 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:56 desktop pcscd[8937]: 00000002 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:56 desktop pcscd[8937]: 00000013 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:56 desktop pcscd[8937]: 00001064 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:56 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:56 desktop pcscd[8937]: 00000010 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:56 desktop pcscd[8937]: 33008726 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:23 desktop pcscd[8937]: 00000002 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:23 desktop pcscd[8937]: 00000004 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:23 desktop pcscd[8937]: 00001370 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:23 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:23 desktop pcscd[8937]: 00000013 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:23 desktop pcscd[8937]: 08839145 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000005 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000025 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00002748 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000003 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000006 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000410 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000005 winscard.c:1626:SCardTransmit() Card not transacted: 0x80100016
> > > févr. 25 08:41:14 desktop pcscd[8937]: 00000019 ifdwrapper.c:543:IFDTransmit() Card not transacted: 612
> > > févr. 25 08:41:14 desktop pcscd[8937]: 99999999 commands.c:1564:CCID_Receive Overrun error
> > > févr. 25 06:11:54 desktop pcscd[8937]: 00000002 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 25 06:11:54 desktop pcscd[8937]: 00000010 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/002)
> > > févr. 25 06:11:54 desktop pcscd[8937]: 00004252 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 25 06:11:54 desktop pcscd[8937]: 00000008 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 25 06:11:54 desktop pcscd[8937]: 00000150 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/001/002)
> > > févr. 25 06:11:54 desktop pcscd[8937]: 00000000 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 25 06:11:54 desktop systemd[1]: Started PC/SC Smart Card Daemon.
> > > févr. 23 18:27:37 desktop pcscd[2076]: 00000003 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 18:27:37 desktop pcscd[2076]: 00000030 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/002)
> > > févr. 23 18:27:37 desktop pcscd[2076]: 00006611 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 18:27:37 desktop pcscd[2076]: 00000014 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 18:27:37 desktop pcscd[2076]: 00000311 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/001/002)
> > > févr. 23 18:27:37 desktop pcscd[2076]: 00000000 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 18:27:37 desktop systemd[1]: Started PC/SC Smart Card Daemon.
> > > févr. 23 11:41:30 desktop pcscd[16416]: 00000004 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 11:41:30 desktop pcscd[16416]: 00000018 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/002)
> > > févr. 23 11:41:30 desktop pcscd[16416]: 00004475 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 11:41:30 desktop pcscd[16416]: 00000007 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 11:41:30 desktop pcscd[16416]: 00000069 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/001/002)
> > > févr. 23 11:41:30 desktop pcscd[16416]: 00000000 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 11:41:30 desktop systemd[1]: Started PC/SC Smart Card Daemon.
> > > févr. 23 11:41:29 desktop systemd[1]: Stopped PC/SC Smart Card Daemon.
> > > févr. 23 11:41:29 desktop systemd[1]: pcscd.service: Failed with result 'exit-code'.
> > > févr. 23 11:41:29 desktop systemd[1]: pcscd.service: Main process exited, code=exited, status=1/FAILURE
> > > févr. 23 11:41:29 desktop systemd[1]: Stopping PC/SC Smart Card Daemon...
> > > févr. 23 11:41:03 desktop pcscd[15790]: 00000003 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 11:41:03 desktop pcscd[15790]: 00000039 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/002)
> > > févr. 23 11:41:03 desktop pcscd[15790]: 00008303 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 11:41:03 desktop pcscd[15790]: 00000020 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 11:41:03 desktop pcscd[15790]: 00000332 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/001/002)
> > > févr. 23 11:41:03 desktop pcscd[15790]: 00000000 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 11:41:03 desktop systemd[1]: Started PC/SC Smart Card Daemon.
> > > févr. 23 09:54:45 desktop pcscd[8998]: 00000004 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > > févr. 23 09:54:45 desktop pcscd[8998]: 00000014 readerfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/002)
> > > févr. 23 09:54:45 desktop pcscd[8998]: 00006227 ifdhandler.c:150:CreateChannelByNameOrChannel() failed
> > > févr. 23 09:54:45 desktop pcscd[8998]: 00000011 readerfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
> > >
> > > _______________________________________________
> > > pcsclite-muscle mailing list
> > > pcsclite-muscle at lists.infradead.org
> > > http://lists.infradead.org/mailman/listinfo/pcsclite-muscle


-------------- next part --------------
A non-text attachment was scrubbed...
Name: log_all.txt.gz
Type: application/gzip
Size: 233368 bytes
Desc: not available
URL: <http://lists.infradead.org/pipermail/pcsclite-muscle/attachments/20190226/ccd40927/attachment-0001.gz>


More information about the pcsclite-muscle mailing list