log.txt

00000000 debuglog.c:299:DebugLogSetLevel() debug level=debug
00000011 debuglog.c:320:DebugLogSetCategory() Debug options: APDU
00000005 pcscdaemon.c:353:main() Force colored logs
00000081 configfile.l:369:DBGetReaderList() Parsing conf file: /nix/store/q2fcggmfa107qg4a9885cyh6mc6xkk0d-reader.conf
00000026 pcscdaemon.c:663:main() pcsc-lite 1.8.26 daemon ready.
00003788 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000107 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000137 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x054C, PID: 0x0CD3, path: /dev/bus/usb/001/052
00000118 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000114 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x8087, PID: 0x07DC, path: /dev/bus/usb/001/003
00000109 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x8087, PID: 0x07DC, path: /dev/bus/usb/001/003
00000099 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000119 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x5986, PID: 0x0397, path: /dev/bus/usb/001/004
00000109 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x5986, PID: 0x0397, path: /dev/bus/usb/001/004
00000103 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000100 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x072F, PID: 0x2200, path: /dev/bus/usb/001/054
00000005 hotplug_libudev.c:441:HPAddDevice() Adding USB device: ACS ACR122U PICC Interface
00000030 readerfactory.c:1074:RFInitializeReader() Attempting startup of ACS ACR122U PICC Interface 00 00 using /nix/store/l5bqib79hf2zaq6ivp3nkbccmvpkfvrq-pcscd-plugins/ifd-ccid.bundle/Contents/Linux/libccid.so
00000245 readerfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0
00000017 ifdhandler.c:1961:init_driver() Driver version: 1.4.31
00000577 ifdhandler.c:1978:init_driver() LogLevel: 0x0003
00000008 ifdhandler.c:1989:init_driver() DriverOptions: 0x0000
00000172 ifdhandler.c:2002:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000005 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/2200:libudev:0:/dev/bus/usb/001/054
00000003 ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:072f/2200:libudev:0:/dev/bus/usb/001/054
00000007 ccid_usb.c:269:OpenUSBByName() interface_number: 0
00000002 ccid_usb.c:270:OpenUSBByName() usb bus/device: 1/54
00000003 ccid_usb.c:302:OpenUSBByName() Using: /nix/store/rwvhqhz0l4g5lbx16ffpv9xw3jzkx3xp-ccid-1.4.31/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000514 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
00000006 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
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.
00004528 ccid_usb.c:406:OpenUSBByName() Try device: 1/54
00000008 ccid_usb.c:416:OpenUSBByName() vid/pid : 072F/2200
00000002 ccid_usb.c:482:OpenUSBByName() Checking device: 1/54
00000002 ccid_usb.c:553:OpenUSBByName() Trying to open USB bus/device: 1/54
00000065 ccid_usb.c:659:OpenUSBByName() Found Vendor/Product: 072F/2200 (ACS ACR122U PICC Interface)
00000004 ccid_usb.c:661:OpenUSBByName() Using USB bus/device: 1/54
00000001 ccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0
00000701 ccid_usb.c:1306:InterruptRead() before (0), timeout: 100 ms
00013407 ccid_usb.c:1352:InterruptRead() after (0) (0)
00000013 NotifySlotChange: 50 02 
00000004 -> 000000 65 00 00 00 00 00 00 00 00 00 
00000495 <- 000000 81 00 00 00 00 00 00 02 81 00 
00000009 -> 000000 65 00 00 00 00 00 01 00 00 00 
00000488 <- 000000 81 00 00 00 00 00 01 02 81 00 
00000010 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB3, usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000002 readerfactory.c:396:RFAddReader() Using the reader polling thread
00000003 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000003 -> 000000 65 00 00 00 00 00 02 00 00 00 
00000513 <- 000000 81 00 00 00 00 00 02 02 81 00 
00000008 ifdhandler.c:1941:IFDHICCPresence() Card absent
00000066 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAE, usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000006 ifdhandler.c:476:IFDHGetCapabilities() Reader supports 1 slot(s)
00000022 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 03 00 00 00 
00000291 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000152 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x138A, PID: 0x0011, path: /dev/bus/usb/001/002
00000059 <- 000000 81 00 00 00 00 00 03 02 81 00 
00000010 ifdhandler.c:1941:IFDHICCPresence() Card absent
00000006 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 04 00 00 00 
00000101 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000171 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x17EF, PID: 0x1010, path: /dev/bus/usb/001/026
00000151 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x17EF, PID: 0x100F, path: /dev/bus/usb/001/027
00000037 <- 000000 81 00 00 00 00 00 04 02 81 00 
00000009 ifdhandler.c:1941:IFDHICCPresence() Card absent
00000004 ifdhandler.c:309:IFDHPolling() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0) 600000 ms
00000011 ccid_usb.c:1306:InterruptRead() before (0), timeout: 600000 ms
00000136 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1BCF, PID: 0x0005, path: /dev/bus/usb/001/028
00000151 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x17EF, PID: 0x100F, path: /dev/bus/usb/001/027
00000165 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x05AC, PID: 0x024F, path: /dev/bus/usb/001/030
00000141 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x05AC, PID: 0x024F, path: /dev/bus/usb/001/030
00000146 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x17EF, PID: 0x100F, path: /dev/bus/usb/001/027
00000144 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x17EF, PID: 0x1010, path: /dev/bus/usb/001/026
00000200 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00000111 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00000122 hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x17EF, PID: 0x1010, path: /dev/bus/usb/002/004
02172101 ccid_usb.c:1352:InterruptRead() after (0) (0)
00000051 NotifySlotChange: 50 03 
00000014 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000016 -> 000000 65 00 00 00 00 00 05 00 00 00 
00000512 <- 000000 81 00 00 00 00 00 05 00 81 00 
00000036 ifdhandler.c:1941:IFDHICCPresence() Card present
00000012 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000017 -> 000000 65 00 00 00 00 00 06 00 00 00 
00002849 <- 000000 81 00 00 00 00 00 06 00 81 00 
00000027 ifdhandler.c:1941:IFDHICCPresence() Card present
00000013 ifdhandler.c:1153:IFDHPowerICC() action: PowerUp, usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000016 -> 000000 62 00 00 00 00 00 07 01 00 00 
00000915 <- 000000 80 14 00 00 00 00 07 00 81 00 3B 8F 80 01 80 4F 0C A0 00 00 03 06 03 00 01 00 00 00 00 6A 
00000013 eventhandler.c:406:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000004 eventhandler.c:423:EHStatusHandlerThread() Card inserted into ACS ACR122U PICC Interface 00 00
00000010 Card ATR: 3B 8F 80 01 80 4F 0C A0 00 00 03 06 03 00 01 00 00 00 00 6A 
00000006 ifdhandler.c:309:IFDHPolling() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0) 5000 ms
00000004 ccid_usb.c:1306:InterruptRead() before (0), timeout: 5000 ms
03230143 winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
00000029 winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 14
00000003 pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 14
00000107 winscard_svc.c:340:ContextThread() Authorized PC/SC client
00000005 winscard_svc.c:343:ContextThread() Thread is started: dwClientID=14, threadContext @0x853ba0
00000007 winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 14
00000004 winscard_svc.c:373:ContextThread() Client is protocol version 4:4
00000003 winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 14
00000033 winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 14
00000008 winscard.c:215:SCardEstablishContext() Establishing Context: 0x1E0657E6
00000002 winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 14
00000096 winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 14
00000029 winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 14
00000048 winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14
00000050 winscard_svc.c:834:MSGSendReaderStates() Send reader states: 14
00000039 winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14
00000016 winscard_svc.c:442:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14
00000085 winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
00000011 winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 15
00000002 pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 15
00000057 winscard_svc.c:340:ContextThread() Authorized PC/SC client
00000005 winscard_svc.c:343:ContextThread() Thread is started: dwClientID=15, threadContext @0x8464c0
00000011 winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 15
00000013 winscard_svc.c:373:ContextThread() Client is protocol version 4:4
00000004 winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 15
00000025 winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 15
00000005 winscard.c:215:SCardEstablishContext() Establishing Context: 0x36D160B5
00000002 winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 15
00000088 winscard_svc.c:361:ContextThread() Received command: CONNECT from client 15
00000007 winscard_svc.c:499:ContextThread() Authorized client for 'ACS ACR122U PICC Interface 00 00'
00000002 winscard.c:258:SCardConnect() Attempting Connect to ACS ACR122U PICC Interface 00 00 using protocol: 3
00000003 readerfactory.c:821:RFReaderInfo() RefReader() count was: 1
00000002 winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE
00000002 prothandler.c:107:PHSetProtocol() Attempting PTS to T=1
00000004 ifdhandler.c:694:IFDHSetProtocolParameters() protocol T=1, usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000003 ifdhandler.c:709:IFDHSetProtocolParameters() Timeout: 3000 ms
00000002 winscard.c:430:SCardConnect() Active Protocol: T=1
00000004 winscard.c:456:SCardConnect() hCard Identity: b342ce
00000016 winscard.c:518:SCardConnect() UnrefReader() count was: 2
00000004 winscard_svc.c:513:ContextThread() CONNECT rv=0x0 for client 15
00000151 winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
00000002 winscard.c:1595:SCardTransmit() Send Protocol: T=1
00000003 APDU: 00 A4 04 00 08 A0 00 00 05 27 47 11 17 
00000002 ifdhandler.c:1302:IFDHTransmitToICC() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000002 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 13 bytes
00000004 -> 000000 6F 0D 00 00 00 00 08 00 00 00 00 A4 04 00 08 A0 00 00 05 27 47 11 17 
00006627 <- 000000 80 00 00 00 00 00 08 00 FE 00 
00000017 SW: 
00000005 winscard.c:1640:SCardTransmit() UnrefReader() count was: 2
00000004 winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 15
00003441 winscard_svc.c:361:ContextThread() Received command: DISCONNECT from client 15
00000022 readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:881:SCardDisconnect() Active Contexts: 1
00000003 winscard.c:882:SCardDisconnect() dwDisposition: 2
00000006 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000007 -> 000000 65 00 00 00 00 00 09 00 00 00 
00000561 <- 000000 81 00 00 00 00 00 09 00 81 00 
00000016 ifdhandler.c:1941:IFDHICCPresence() Card present
00000005 ifdhandler.c:1153:IFDHPowerICC() action: PowerDown, usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000006 -> 000000 63 00 00 00 00 00 0A 00 00 00 
00000449 <- 000000 81 00 00 00 00 00 0A 00 81 00 
00000011 winscard.c:904:SCardDisconnect() powerState: POWER_STATE_UNPOWERED
00000006 ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB2, usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000003 winscard.c:1030:SCardDisconnect() Stopping polling thread
00000003 ifdhandler.c:345:IFDHStopPolling() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000070 ccid_usb.c:1352:InterruptRead() after (0) (3)
00000007 ccid_usb.c:1365:InterruptRead() InterruptRead (1/54): 3
00000009 winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2
00000005 winscard_svc.c:550:ContextThread() DISCONNECT rv=0x0 for client 15
00000099 winscard_svc.c:361:ContextThread() Received command: RELEASE_CONTEXT from client 15
00000010 winscard.c:229:SCardReleaseContext() Releasing Context: 0x36D160B5
00000062 winscard_svc.c:476:ContextThread() RELEASE_CONTEXT rv=0x0 for client 15
00000065 winscard_svc.c:354:ContextThread() Client die: 15
00000035 winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=15, threadContext @0x8464c0
00000030 winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x8464c0
00005814 winscard_svc.c:354:ContextThread() Client die: 14
00000040 winscard.c:229:SCardReleaseContext() Releasing Context: 0x1E0657E6
00000005 winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=14, threadContext @0x853ba0
00000003 winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x853ba0
00393996 ifdhandler.c:1821:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0)
00000035 -> 000000 65 00 00 00 00 00 0B 00 00 00 
00000494 <- 000000 81 00 00 00 00 00 0B 00 81 00 
00000024 ifdhandler.c:1941:IFDHICCPresence() Card present
00000011 ifdhandler.c:309:IFDHPolling() usb:072f/2200:libudev:0:/dev/bus/usb/001/054 (lun: 0) 600000 ms
00000008 ccid_usb.c:1306:InterruptRead() before (0), timeout: 600000 ms