Attachment 596290 Details for Bug 832983 – pcscd log 2

pcscd log 2

log.txt (text/plain), 10.07 KB, created by Manuel Bejarano on 2012-07-04 21:44:16 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Manuel Bejarano

Created: 2012-07-04 21:44:16 UTC

Size: 10.07 KB

patch

obsolete

>00000000 debuglog.c:269:DebugLogSetLevel() debug level=debug
>00000037 debuglog.c:298:DebugLogSetCategory() Debug options: APDU
>00000154 utils.c:53:GetDaemonPid() Can't open /var/run/pcscd/pcscd.pid: No such file or directory
>00000112 configfile.l:245:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
>00000015 configfile.l:257:DBGetReaderListDir() Skipping non regular file: .
>00000004 configfile.l:257:DBGetReaderListDir() Skipping non regular file: ..
>00000006 configfile.l:298:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin
>00000054 pcscdaemon.c:518:main() pcsc-lite 1.8.3 daemon ready.
>00002126 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/003/001
>00000121 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/003/001
>00000124 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x0483, PID: 0x2016, path: /dev/bus/usb/003/002
>00000202 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/004/001
>00000137 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/004/001
>00000135 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x072F, PID: 0x90CC, path: /dev/bus/usb/004/002
>00000032 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x072F, PID: 0x90CC, path: /dev/bus/usb/004/002
>00000006 hotplug_libudev.c:311:HPAddDevice() Adding USB device: ACS AET65
>00000036 readerfactory.c:936:RFInitializeReader() Attempting startup of ACS AET65 00 00 using /usr/lib64/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
>00000192 readerfactory.c:826:RFBindFunctions() Loading IFD Handler 3.0
>00000033 ifdhandler.c:1768:init_driver() Driver version: 1.4.6
>00000593 ifdhandler.c:1785:init_driver() LogLevel: 0x0003
>00000008 ifdhandler.c:1796:init_driver() DriverOptions: 0x0000
>00000095 ifdhandler.c:1809:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
>00000008 ifdhandler.c:83:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/90cc:libudev:0:/dev/bus/usb/004/002
>00000009 ccid_usb.c:180:OpenUSBByName() Reader index: 0, Device: usb:072f/90cc:libudev:0:/dev/bus/usb/004/002
>00000021 ccid_usb.c:212:OpenUSBByName() interface_number: 0
>00000491 ccid_usb.c:245:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
>00000007 ccid_usb.c:246:OpenUSBByName() ifdProductString: Generic CCID driver
>00000008 ccid_usb.c:247:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later
>00001310 ccid_usb.c:365:OpenUSBByName() Checking device: 4/2
>00000006 ccid_usb.c:420:OpenUSBByName() Trying to open USB bus/device: 4/2
>00000151 ccid_usb.c:509:OpenUSBByName() Found Vendor/Product: 072F/90CC (ACS ACR 38U-CCID)
>00000006 ccid_usb.c:511:OpenUSBByName() Using USB bus/device: 4/2
>00000005 ccid_usb.c:1029:ControlUSB() request: 0x03
>00002128 receive: 
>00000009 ccid_usb.c:973:get_data_rates() IFD does not support GET_DATA_RATES request: 0
>00004091 ccid_usb.c:1078:InterruptRead() before (0)
>00101950 ccid_usb.c:1120:InterruptRead() after (0) (2)
>00000080 -> 000000 65 00 00 00 00 00 00 00 00 00 
>00002876 <- 000000 81 00 00 00 00 00 00 01 00 00 
>00000017 ifdhandler.c:353:IFDHGetCapabilities() tag: 0xFB3, usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000006 readerfactory.c:296:RFAddReader() Using the reader polling thread
>00000005 ifdhandler.c:1631:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000006 -> 000000 65 00 00 00 00 00 01 00 00 00 
>00001968 <- 000000 81 00 00 00 00 00 01 01 00 00 
>00000009 ifdhandler.c:1749:IFDHICCPresence() Card present
>00000138 ifdhandler.c:353:IFDHGetCapabilities() tag: 0xFAE, usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000095 ifdhandler.c:441:IFDHGetCapabilities() Reader supports 1 slot(s)
>00000085 ifdhandler.c:1631:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000031 -> 000000 65 00 00 00 00 00 02 00 00 00 
>00000227 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
>00000225 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/005/001
>00000216 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/006/001
>00000209 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
>00000066 readerfactory.c:1303:RFWaitForReaderInit() Waiting init for reader: ACS AET65 00 00
>00000706 <- 000000 81 00 00 00 00 00 02 01 00 00 
>00000031 ifdhandler.c:1749:IFDHICCPresence() Card present
>00000024 ifdhandler.c:1631:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000024 -> 000000 65 00 00 00 00 00 03 00 00 00 
>00001906 <- 000000 81 00 00 00 00 00 03 01 00 00 
>00000018 ifdhandler.c:1749:IFDHICCPresence() Card present
>00000010 ifdhandler.c:1114:IFDHPowerICC() action: PowerUp, usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000007 -> 000000 62 00 00 00 00 00 04 01 00 00 
>00007414 readerfactory.c:1303:RFWaitForReaderInit() Waiting init for reader: ACS AET65 00 00
>00010122 readerfactory.c:1303:RFWaitForReaderInit() Waiting init for reader: ACS AET65 00 00
>00010109 readerfactory.c:1303:RFWaitForReaderInit() Waiting init for reader: ACS AET65 00 00
>00010121 readerfactory.c:1303:RFWaitForReaderInit() Waiting init for reader: ACS AET65 00 00
>00010131 readerfactory.c:1303:RFWaitForReaderInit() Waiting init for reader: ACS AET65 00 00
>00003134 <- 000000 80 13 00 00 00 00 04 00 00 00 3B BE 95 00 00 41 03 00 00 00 00 00 00 00 00 00 02 90 00 
>00000093 eventhandler.c:256:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
>00000055 Card ATR: 3B BE 95 00 00 41 03 00 00 00 00 00 00 00 00 00 02 90 00 
>00000046 ifdhandler.c:1631:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000045 -> 000000 65 00 00 00 00 00 05 00 00 00 
>00001709 <- 000000 81 00 00 00 00 00 05 00 00 00 
>00000057 ifdhandler.c:1749:IFDHICCPresence() Card present
>00000043 ifdhandler.c:282:IFDHPolling() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0) 5000 ms
>00000042 ccid_usb.c:1078:InterruptRead() before (0)
>04418448 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival
>00000057 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 9
>00000011 pcscdaemon.c:93:SVCServiceRunLoop() A new context thread creation is requested: 9
>00000142 winscard_svc.c:297:ContextThread() Thread is started: dwClientID=9, threadContext @0x2413500
>00000075 winscard_svc.c:315:ContextThread() Received command: CMD_VERSION from client 9
>00000023 winscard_svc.c:327:ContextThread() Client is protocol version 4:2
>00000010 winscard_svc.c:347:ContextThread() CMD_VERSION rv=0x0 for client 9
>00000110 winscard_svc.c:315:ContextThread() Received command: ESTABLISH_CONTEXT from client 9
>00000034 winscard.c:193:SCardEstablishContext() Establishing Context: 0x103DA97
>00000009 winscard_svc.c:408:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 9
>00000062 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
>00000073 winscard_svc.c:315:ContextThread() Received command: RELEASE_CONTEXT from client 9
>00000016 winscard.c:204:SCardReleaseContext() Releasing Context: 0x103DA97
>00000011 winscard_svc.c:423:ContextThread() RELEASE_CONTEXT rv=0x0 for client 9
>00000082 winscard_svc.c:307:ContextThread() Client die: 9
>00000023 winscard_svc.c:918:MSGCleanupClient() Thread is stopping: dwClientID=9, threadContext @0x2413500
>00000010 winscard_svc.c:924:MSGCleanupClient() Freeing SCONTEXT @0x2413500
>00582898 ccid_usb.c:1120:InterruptRead() after (0) (2)
>00000028 ifdhandler.c:1631:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000016 -> 000000 65 00 00 00 00 00 06 00 00 00 
>00002933 <- 000000 81 00 00 00 00 00 06 00 00 00 
>00000018 ifdhandler.c:1749:IFDHICCPresence() Card present
>00000011 ifdhandler.c:1114:IFDHPowerICC() action: PowerDown, usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000012 -> 000000 63 00 00 00 00 00 07 00 00 00 
>00098947 <- 000000 81 00 00 00 00 00 07 01 00 00 
>00000011 eventhandler.c:446:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
>00000006 ifdhandler.c:1631:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0)
>00000006 -> 000000 65 00 00 00 00 00 08 00 00 00 
>00001972 <- 000000 81 00 00 00 00 00 08 01 00 00 
>00000009 ifdhandler.c:1749:IFDHICCPresence() Card present
>00000006 ifdhandler.c:282:IFDHPolling() usb:072f/90cc:libudev:0:/dev/bus/usb/004/002 (lun: 0) 600000 ms
>00000005 ccid_usb.c:1078:InterruptRead() before (0)
>23049369 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival
>00000044 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 9
>00000011 pcscdaemon.c:93:SVCServiceRunLoop() A new context thread creation is requested: 9
>00000100 winscard_svc.c:297:ContextThread() Thread is started: dwClientID=9, threadContext @0x2413500
>00000044 winscard_svc.c:315:ContextThread() Received command: CMD_VERSION from client 9
>00000012 winscard_svc.c:327:ContextThread() Client is protocol version 4:2
>00000005 winscard_svc.c:347:ContextThread() CMD_VERSION rv=0x0 for client 9
>00000128 winscard_svc.c:315:ContextThread() Received command: ESTABLISH_CONTEXT from client 9
>00000014 winscard.c:193:SCardEstablishContext() Establishing Context: 0x103068B
>00000005 winscard_svc.c:408:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 9
>00000045 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
>00000070 winscard_svc.c:315:ContextThread() Received command: RELEASE_CONTEXT from client 9
>00000030 winscard.c:204:SCardReleaseContext() Releasing Context: 0x103068B
>00000009 winscard_svc.c:423:ContextThread() RELEASE_CONTEXT rv=0x0 for client 9
>00000262 winscard_svc.c:307:ContextThread() Client die: 9
>00000036 winscard_svc.c:918:MSGCleanupClient() Thread is stopping: dwClientID=9, threadContext @0x2413500
>00000024 winscard_svc.c:924:MSGCleanupClient() Freeing SCONTEXT @0x2413500

Actions: View

Attachments on bug 832983: 592642 | 596290 | 596505 | 600628