[Pcsclite-muscle] Different behaviour with Select MF on Omnikey 5427 and 5022

Wed Nov 4 05:15:27 EST 2020

and the logs taken with a 5022, same processing.

Stéphan GUILLOUX
Ingénieur Développement Sr

40 avenue de la libération 57160 Châtel St-Germain
Tél. 03 87 65 98 80 / poste 221
stephan.guilloux at crisalid.com

LES LOGICIELS QUI VOUS DONNENT DES AILES ! - www.crisalid.com

Le mer. 4 nov. 2020 à 11:14, Stephan Guilloux
<stephan.guilloux at crisalid.com> a écrit :
>
> Here you are.
>
> I took the opportunity to update PCSC and CCID to the latest versions
> available (1.9.0 and 1.4.33).
> Results are the same.
>
> To reproduce, I ran the last official scard_example.go and rebuilt it
> with the libs above.
> scard_example output is inserted in each PCSC log file.
>
> For comparison, the same process is used with a 5427 and a 5022.
> 5427 logs are attached in this mail.
> 5022 will be sent in a second mail.
>
> Stephan.
>
>
> Le mar. 3 nov. 2020 à 18:41, Ludovic Rousseau
> <ludovic.rousseau at gmail.com> a écrit :
> >
> > Le mar. 3 nov. 2020 à 15:52, Stephan Guilloux
> > <stephan.guilloux at crisalid.com> a écrit :
> > >
> > > Spent some time to reduce the code to the strict minimum, and it comes
> > > almost the same as
> > >      https://github.com/ebfe/scard/blob/master/example_test.go
> > > (traces are a bit different though)
> > >
> > > APDU is take from the same example:
> > >      0x00, 0xa4, 0x00, 0x0c, 0x02, 0x3f, 0x00
> >
> > The next step is to generate a pcscd trace as documented in
> > https://pcsclite.apdu.fr/#support
> >
> > Bye
> >
> > --
> >  Dr. Ludovic Rousseau
> >
> > _______________________________________________
> > pcsclite-muscle mailing list
> > pcsclite-muscle at lists.infradead.org
> > http://lists.infradead.org/mailman/listinfo/pcsclite-muscle
-------------- next part --------------
[root at Rasp-8AC500 ~]$ ./scard-example 
Found 1 readers:
[0] HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00
Waiting for a Card
Connecting to card in  HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00
Card status:
     reader: HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00
     state: 10034
     active protocol: 2
     atr: 3b 8f 80 01 80 4f 0c a0 00 00 03 06 03 00 01 00 00 00 00 6a
Transmit:
	c-apdu: 00 a4 00 0c 02 3f 00
	r-apdu: 6a 81
[root at Rasp-8AC500 ~]$ 

----------------------------------------------------------------------

00000000 debuglog.c:299:DebugLogSetLevel() debug level=debug
00000137 debuglog.c:320:DebugLogSetCategory() Debug options: APDU
[36m00000025[0m [1996279808] [34mpcscdaemon.c:353:main() Force colored logs[0m
[36m00002267[0m [1996279808] configfile.l:293:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
[36m00000736[0m [1996279808] configfile.l:330:DBGetReaderListDir() Skipping non regular file: ..
[36m00000038[0m [1996279808] configfile.l:330:DBGetReaderListDir() Skipping non regular file: .
[36m00001345[0m [1996279808] [34mpcscdaemon.c:663:main() pcsc-lite 1.9.0 daemon ready.[0m
[36m00025703[0m [1982047168] [34mhotplug_libusb.c:538:HPAddHotPluggable() Adding USB device: 1:12:0[0m
[36m00008360[0m [1982047168] [34mreaderfactory.c:1075:RFInitializeReader() Attempting startup of HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so[0m
[36m00000960[0m [1982047168] [34mreaderfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0[0m
[36m00000191[0m [1982047168] [34mifdhandler.c:2008:init_driver() Driver version: 1.4.33[0m
[36m00007610[0m [1982047168] [34mifdhandler.c:2025:init_driver() LogLevel: 0x0003[0m
[36m00000055[0m [1982047168] [34mifdhandler.c:2036:init_driver() DriverOptions: 0x0000[0m
[36m00001389[0m [1982047168] [34mifdhandler.c:2049:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F[0m
[36m00000036[0m [1982047168] [34mifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:076b/5022:libusb-1.0:1:12:0[0m
[36m00000051[0m [1982047168] ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:076b/5022:libusb-1.0:1:12:0
[36m00000067[0m [1982047168] ccid_usb.c:282:OpenUSBByName() interface_number: 0
[36m00000026[0m [1982047168] ccid_usb.c:284:OpenUSBByName() usb bus/device: 1/12
[36m00000027[0m [1982047168] [34mccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist[0m
[36m00007691[0m [1982047168] [34mccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau at free.fr)[0m
[36m00000056[0m [1982047168] [34mccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver[0m
[36m00000029[0m [1982047168] [34mccid_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.[0m
[36m00012070[0m [1982047168] ccid_usb.c:406:OpenUSBByName() Try device: 1/12
[36m00000058[0m [1982047168] ccid_usb.c:416:OpenUSBByName() vid/pid : 076B/5022
[36m00000025[0m [1982047168] ccid_usb.c:483:OpenUSBByName() Checking device: 1/12
[36m00000025[0m [1982047168] ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 1/12
[36m00000477[0m [1982047168] [34mccid_usb.c:660:OpenUSBByName() Found Vendor/Product: 076B/5022 (HID Global OMNIKEY 5022 Smart Card Reader)[0m
[36m00000029[0m [1982047168] [34mccid_usb.c:662:OpenUSBByName() Using USB bus/device: 1/12[0m
[36m00000026[0m [1982047168] [34mccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0[0m
[36m00011779[0m [1982047168] ccid_usb.c:1327:InterruptRead() before (0), timeout: 100 ms
[36m00000595[0m [1982047168] ccid_usb.c:1373:InterruptRead() after (0) (0)
[36m00000156[0m [1982047168] NotifySlotChange: 50 02 
[36m00000130[0m [1982047168] -> 000000 65 00 00 00 00 00 00 00 00 00 
[36m00000572[0m [1982047168] <- 000000 81 00 00 00 00 00 00 02 00 00 
[36m00000153[0m [1982047168] -> 000000 65 00 00 00 00 00 01 00 00 00 
[36m00000595[0m [1982047168] <- 000000 81 00 00 00 00 00 01 02 00 00 
[36m00000150[0m [1982047168] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0xFB3, usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000122[0m [1982047168] [34mreaderfactory.c:396:RFAddReader() Using the reader polling thread[0m
[36m00000121[0m [1982047168] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000128[0m [1982047168] -> 000000 65 00 00 00 00 00 02 00 00 00 
[36m00000606[0m [1982047168] <- 000000 81 00 00 00 00 00 02 02 00 00 
[36m00000275[0m [1982047168] ifdhandler.c:1989:IFDHICCPresence() Card absent
[36m00000465[0m [1982047168] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0xFAE, usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000178[0m [1982047168] [34mifdhandler.c:476:IFDHGetCapabilities() Reader supports 1 slot(s)[0m
[36m00000620[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000054[0m [1963975616] -> 000000 65 00 00 00 00 00 03 00 00 00 
[36m00000827[0m [1982047168] [34mhotplug_libusb.c:442:HPEstablishUSBNotifications() Driver ifd-ccid.bundle does not support IFD_GENERATE_HOTPLUG. Using active polling instead.[0m
[36m00000099[0m [1982047168] [34mhotplug_libusb.c:451:HPEstablishUSBNotifications() Polling forced every 1 second(s)[0m
[36m00000097[0m [1996279808] readerfactory.c:1410:RFWaitForReaderInit() Waiting init for reader: HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00
[36m00000978[0m [1963975616] <- 000000 81 00 00 00 00 00 03 02 00 00 
[36m00000032[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card absent
[36m00000030[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000045[0m [1963975616] -> 000000 65 00 00 00 00 00 04 00 00 00 
[36m00000804[0m [1963975616] <- 000000 81 00 00 00 00 00 04 02 00 00 
[36m00000029[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card absent
[36m00000026[0m [1963975616] [34mifdhandler.c:310:IFDHPolling() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0) 600000 ms[0m
[36m00000022[0m [1963975616] ccid_usb.c:1327:InterruptRead() before (0), timeout: 600000 ms
[36m04860568[0m [1996279808] winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
[36m00000108[0m [1996279808] winscard_msg_srv.c:268:ProcessEventsServer() ProcessCommonChannelRequest detects: 6
[36m00000026[0m [1996279808] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 6
[36m00000320[0m [1953493952] winscard_svc.c:340:ContextThread() Authorized PC/SC client
[36m00000035[0m [1953493952] winscard_svc.c:344:ContextThread() Thread is started: dwClientID=6, threadContext @0x1d2e740
[36m00000070[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_VERSION from client 6
[36m00000051[0m [1953493952] winscard_svc.c:374:ContextThread() Client is protocol version 4:4
[36m00000025[0m [1953493952] winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 6
[36m00000339[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: ESTABLISH_CONTEXT from client 6
[36m00000059[0m [1953493952] winscard.c:215:SCardEstablishContext() Establishing Context: 0xF7B587E
[36m00000035[0m [1953493952] winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 6
[36m00000294[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 6
[36m00000306[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 6
[36m00000641[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6
[36m00000384[0m [1953493952] winscard_svc.c:834:MSGSendReaderStates() Send reader states: 6
[36m00000326[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 6
[36m00000040[0m [1953493952] winscard_svc.c:442:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 6
[36m00000356[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6
[36m00000039[0m [1953493952] winscard_svc.c:834:MSGSendReaderStates() Send reader states: 6
[36m02056549[0m [1963975616] ccid_usb.c:1373:InterruptRead() after (0) (0)
[36m00000060[0m [1963975616] NotifySlotChange: 50 03 
[36m00000036[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000040[0m [1963975616] -> 000000 65 00 00 00 00 00 05 00 00 00 
[36m00000559[0m [1963975616] <- 000000 81 00 00 00 00 00 05 01 00 00 
[36m00000035[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card present
[36m00000027[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000075[0m [1963975616] -> 000000 65 00 00 00 00 00 06 00 00 00 
[36m00000605[0m [1963975616] <- 000000 81 00 00 00 00 00 06 01 00 00 
[36m00000030[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card present
[36m00000028[0m [1963975616] [34mifdhandler.c:1167:IFDHPowerICC() action: PowerUp, usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000080[0m [1963975616] -> 000000 62 00 00 00 00 00 07 00 00 00 
[36m00009310[0m [1963975616] <- 000000 80 14 00 00 00 00 07 00 00 00 3B 8F 80 01 80 4F 0C A0 00 00 03 06 03 00 01 00 00 00 00 6A 
[36m00000074[0m [1963975616] eventhandler.c:406:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
[36m00000085[0m [1963975616] [34meventhandler.c:423:EHStatusHandlerThread() Card inserted into HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00[0m
[36m00000029[0m [1963975616] winscard_svc.c:822:MSGSignalClient() Signal client: 6
[36m00000062[0m [1963975616] winscard_svc.c:825:MSGSignalClient() SIGNAL rv=0x0 for client 6
[36m00000130[0m [1963975616] [34mCard ATR: 3B 8F 80 01 80 4F 0C A0 00 00 03 06 03 00 01 00 00 00 00 6A [0m
[36m00000034[0m [1963975616] [34mifdhandler.c:310:IFDHPolling() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0) 5000 ms[0m
[36m00000025[0m [1963975616] ccid_usb.c:1327:InterruptRead() before (0), timeout: 5000 ms
[36m00000272[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6
[36m00000039[0m [1953493952] winscard_svc.c:834:MSGSendReaderStates() Send reader states: 6
[36m00000300[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 6
[36m00000035[0m [1953493952] winscard_svc.c:442:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 6
[36m00000529[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CONNECT from client 6
[36m00000061[0m [1953493952] winscard_svc.c:499:ContextThread() Authorized client for 'HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00'
[36m00000027[0m [1953493952] winscard.c:259:SCardConnect() Attempting Connect to HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00 using protocol: 3
[36m00000026[0m [1953493952] readerfactory.c:821:RFReaderInfo() RefReader() count was: 1
[36m00000023[0m [1953493952] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE
[36m00000027[0m [1953493952] [34mprothandler.c:108:PHSetProtocol() Attempting PTS to T=1[0m
[36m00000030[0m [1953493952] [34mifdhandler.c:708:IFDHSetProtocolParameters() protocol T=1, usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000041[0m [1953493952] ifdhandler.c:1030:IFDHSetProtocolParameters() Timeout: 167723 ms
[36m00000024[0m [1953493952] commands.c:2265:SetParameters() length: 7 bytes
[36m00000051[0m [1953493952] -> 000000 61 07 00 00 00 00 08 01 00 00 11 10 00 4D 00 20 00 
[36m00000696[0m [1953493952] <- 000000 82 07 00 00 00 00 08 00 00 01 11 10 00 4D 00 20 00 
[36m00000048[0m [1953493952] ifdhandler.c:1100:IFDHSetProtocolParameters() T=1: IFSC=32, IFSD=254
[36m00000031[0m [1953493952] winscard.c:431:SCardConnect() Active Protocol: T=1
[36m00000042[0m [1953493952] winscard.c:456:SCardConnect() hCard Identity: 1b3cbb6e
[36m00000033[0m [1953493952] winscard.c:518:SCardConnect() UnrefReader() count was: 2
[36m00000029[0m [1953493952] winscard_svc.c:513:ContextThread() CONNECT rv=0x0 for client 6
[36m00000574[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 6
[36m00000331[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: STATUS from client 6
[36m00000063[0m [1953493952] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
[36m00000035[0m [1953493952] winscard.c:1300:SCardStatus() UnrefReader() count was: 2
[36m00000026[0m [1953493952] winscard_svc.c:632:ContextThread() STATUS rv=0x0 for client 6
[36m00001452[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 6
[36m00000118[0m [1953493952] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
[36m00000030[0m [1953493952] winscard.c:1595:SCardTransmit() Send Protocol: T=1
[36m00000034[0m [1953493952] [34mAPDU: 00 A4 00 0C 02 3F 00 [0m
[36m00000028[0m [1953493952] [34mifdhandler.c:1316:IFDHTransmitToICC() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000055[0m [1953493952] commands.c:1634:CmdXfrBlockAPDU_extended() T=0 (extended): 7 bytes
[36m00000051[0m [1953493952] -> 000000 6F 07 00 00 00 00 09 00 00 00 00 A4 00 0C 02 3F 00 
[36m00000998[0m [1953493952] <- 000000 80 02 00 00 00 00 09 00 00 00 6A 81 
[36m00000054[0m [1953493952] [34mSW: 6A 81 [0m
[36m00000025[0m [1953493952] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
[36m00000025[0m [1953493952] winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 6
[36m00000443[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: DISCONNECT from client 6
[36m00000063[0m [1953493952] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
[36m00000025[0m [1953493952] winscard.c:881:SCardDisconnect() Active Contexts: -1
[36m00000022[0m [1953493952] winscard.c:882:SCardDisconnect() dwDisposition: 1
[36m00000029[0m [1953493952] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000042[0m [1953493952] -> 000000 65 00 00 00 00 00 0A 00 00 00 
[36m00000691[0m [1953493952] <- 000000 81 00 00 00 00 00 0A 00 00 00 
[36m00000038[0m [1953493952] ifdhandler.c:1989:IFDHICCPresence() Card present
[36m00000028[0m [1953493952] [34mifdhandler.c:1167:IFDHPowerICC() action: Reset, usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000038[0m [1953493952] -> 000000 62 00 00 00 00 00 0B 00 00 00 
[36m00006924[0m [1953493952] <- 000000 80 14 00 00 00 00 0B 00 00 00 3B 8F 80 01 80 4F 0C A0 00 00 03 06 03 00 01 00 00 00 00 6A 
[36m00000045[0m [1953493952] winscard.c:920:SCardDisconnect() Reset complete.
[36m00000049[0m [1953493952] Card ATR: 3B 8F 80 01 80 4F 0C A0 00 00 03 06 03 00 01 00 00 00 00 6A 
[36m00000027[0m [1953493952] winscard.c:1017:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
[36m00000027[0m [1953493952] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0xFB2, usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000023[0m [1953493952] [34mwinscard.c:1030:SCardDisconnect() Stopping polling thread[0m
[36m00000023[0m [1953493952] [34mifdhandler.c:346:IFDHStopPolling() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000070[0m [1953493952] winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2
[36m00000029[0m [1953493952] winscard_svc.c:550:ContextThread() DISCONNECT rv=0x0 for client 6
[36m00000037[0m [1963975616] ccid_usb.c:1373:InterruptRead() after (0) (3)
[36m00000029[0m [1963975616] ccid_usb.c:1388:InterruptRead() InterruptRead (1/12): 3
[36m00000301[0m [1953493952] winscard_svc.c:362:ContextThread() Received command: RELEASE_CONTEXT from client 6
[36m00000055[0m [1953493952] winscard.c:229:SCardReleaseContext() Releasing Context: 0xF7B587E
[36m00000026[0m [1953493952] winscard_svc.c:476:ContextThread() RELEASE_CONTEXT rv=0x0 for client 6
[36m00000253[0m [1953493952] winscard_svc.c:354:ContextThread() Client die: 6
[36m00000127[0m [1953493952] winscard_svc.c:1057:MSGCleanupClient() Thread is stopping: dwClientID=6, threadContext @0x1d2e740
[36m00000026[0m [1953493952] winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x1d2e740
[36m00399356[0m [1963975616] eventhandler.c:494:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
[36m00000063[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000047[0m [1963975616] -> 000000 65 00 00 00 00 00 0C 00 00 00 
[36m00000528[0m [1963975616] <- 000000 81 00 00 00 00 00 0C 00 00 00 
[36m00000034[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card present
[36m00000027[0m [1963975616] [34mifdhandler.c:310:IFDHPolling() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0) 5000 ms[0m
[36m00000033[0m [1963975616] ccid_usb.c:1327:InterruptRead() before (0), timeout: 5000 ms
[36m01968805[0m [1963975616] ccid_usb.c:1373:InterruptRead() after (0) (0)
[36m00000060[0m [1963975616] NotifySlotChange: 50 02 
[36m00000036[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000049[0m [1963975616] -> 000000 65 00 00 00 00 00 0D 00 00 00 
[36m00000562[0m [1963975616] <- 000000 81 00 00 00 00 00 0D 02 00 00 
[36m00000033[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card absent
[36m00000024[0m [1963975616] eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
[36m00000033[0m [1963975616] ifdhandler.c:1868:IFDHICCPresence() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0)
[36m00000036[0m [1963975616] -> 000000 65 00 00 00 00 00 0E 00 00 00 
[36m00000491[0m [1963975616] <- 000000 81 00 00 00 00 00 0E 02 00 00 
[36m00000030[0m [1963975616] ifdhandler.c:1989:IFDHICCPresence() Card absent
[36m00000024[0m [1963975616] [34meventhandler.c:358:EHStatusHandlerThread() Card Removed From HID Global OMNIKEY 5022 Smart Card Reader (KJ-16145171-1919-000030) 00 00[0m
[36m00000038[0m [1963975616] [34mifdhandler.c:310:IFDHPolling() usb:076b/5022:libusb-1.0:1:12:0 (lun: 0) 600000 ms[0m
[36m00000024[0m [1963975616] ccid_usb.c:1327:InterruptRead() before (0), timeout: 600000 ms