Here it is the complete pcscd log: [root@localhost ~]# /usr/sbin/pcscd -f -d 00000000 pcscdaemon.c:267:main() pcscd set to foreground with debug send to stderr 00000123 debuglog.c:239:DebugLogSetLevel() debug level=debug 00002756 pcscdaemon.c:506:main() pcsc-lite 1.5.2 daemon ready. 00158377 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0002 00007322 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00007443 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00007371 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0002 00006801 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00006375 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00007518 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x174F, PID: 0x142B 00004018 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x174F, PID: 0x142B 00005065 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0002 00006326 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x14E1, PID: 0x6000 00005205 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00006491 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x0CA6, PID: 0x0010 00000038 hotplug_libhal.c:342:HPAddDevice() Adding USB device: usb_device_ca6_10_noserial_if0 01002371 readerfactory.c:999:RFInitializeReader() Attempting startup of CASTLES EZ100PU 00 00 using /usr/lib/pcsc/drivers/ezusb.bundle/Contents/Linux/ezusb.so 00000552 readerfactory.c:873:RFBindFunctions() Loading IFD Handler 3.0 00134857 readerfactory.c:248:RFAddReader() Using the pcscd polling thread 00010553 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00010190 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x03F0, PID: 0x0024 00009612 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x045E, PID: 0x0040 00009300 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00010833 hotplug_libhal.c:307:get_driver() Looking a driver for VID: 0x1D6B, PID: 0x0001 00083313 Card ATR: 3B AC 00 40 2A 00 12 25 00 64 80 00 03 10 00 90 00 03929757 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel packet arrival 00000150 winscard_msg_srv.c:248:SHMProcessEventsServer() SHMProcessCommonChannelRequest detects: 6 00000043 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation is requested: 6 00000233 winscard_svc.c:133:ContextThread() Thread is started: 6 00000135 winscard_msg_srv.c:317:SHMProcessEventsContext() command CMD_VERSION received by client 6 00000049 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 00000382 winscard_msg_srv.c:317:SHMProcessEventsContext() command ESTABLISH_CONTEXT received by client 6 00000168 winscard.c:242:SCardEstablishContext() Establishing Context: 16997701 00000653 winscard_msg_srv.c:317:SHMProcessEventsContext() command RELEASE_CONTEXT received by client 6 00000152 winscard.c:253:SCardReleaseContext() Releasing Context: 16997701 00000310 winscard_msg_srv.c:306:SHMProcessEventsContext() Client has disappeared: 6 00000100 winscard_svc.c:146:ContextThread() Client die: 6 00516129 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel packet arrival 00000073 winscard_msg_srv.c:248:SHMProcessEventsServer() SHMProcessCommonChannelRequest detects: 6 00000026 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation is requested: 6 00000118 winscard_svc.c:133:ContextThread() Thread is started: 6 00000069 winscard_msg_srv.c:317:SHMProcessEventsContext() command CMD_VERSION received by client 6 00000023 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 00000203 winscard_msg_srv.c:317:SHMProcessEventsContext() command ESTABLISH_CONTEXT received by client 6 00000059 winscard.c:242:SCardEstablishContext() Establishing Context: 16985507 06471322 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel packet arrival 00000113 winscard_msg_srv.c:248:SHMProcessEventsServer() SHMProcessCommonChannelRequest detects: 12 00000042 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation is requested: 12 00000285 winscard_svc.c:133:ContextThread() Thread is started: 12 00000204 winscard_msg_srv.c:317:SHMProcessEventsContext() command CMD_VERSION received by client 12 00000062 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 00000307 winscard_msg_srv.c:317:SHMProcessEventsContext() command ESTABLISH_CONTEXT received by client 12 00000110 winscard.c:242:SCardEstablishContext() Establishing Context: 17028891 00004660 winscard_msg_srv.c:317:SHMProcessEventsContext() command CONNECT received by client 12 00000042 winscard.c:303:SCardConnect() Attempting Connect to CASTLES EZ100PU 00 00 using protocol: 3 00000026 prothandler.c:128:PHSetProtocol() Attempting PTS to T=0 00134492 winscard.c:449:SCardConnect() Active Protocol: T=0 00000070 winscard.c:459:SCardConnect() hCard Identity: 1923a 00002105 utils.c:115:StatSynchronize() status file: /var/run/pcscd.events/event.1631.16985507 00001958 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00001399 winscard_msg_srv.c:317:SHMProcessEventsContext() command DISCONNECT received by client 12 00000061 winscard.c:880:SCardDisconnect() Active Contexts: 1 00047624 utils.c:115:StatSynchronize() status file: /var/run/pcscd.events/event.1631.16985507 00001846 winscard_msg_srv.c:317:SHMProcessEventsContext() command RELEASE_CONTEXT received by client 12 00000067 winscard.c:253:SCardReleaseContext() Releasing Context: 17028891 00000197 winscard_msg_srv.c:306:SHMProcessEventsContext() Client has disappeared: 12 00000094 winscard_svc.c:146:ContextThread() Client die: 12 00024081 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel packet arrival 00000110 winscard_msg_srv.c:248:SHMProcessEventsServer() SHMProcessCommonChannelRequest detects: 12 00000042 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation is requested: 12 00000128 winscard_svc.c:133:ContextThread() Thread is started: 12 00000095 winscard_msg_srv.c:317:SHMProcessEventsContext() command CMD_VERSION received by client 12 00000055 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 00000719 winscard_msg_srv.c:317:SHMProcessEventsContext() command ESTABLISH_CONTEXT received by client 12 00000095 winscard.c:242:SCardEstablishContext() Establishing Context: 16988079 00003018 winscard_msg_srv.c:317:SHMProcessEventsContext() command CONNECT received by client 12 00000066 winscard.c:303:SCardConnect() Attempting Connect to CASTLES EZ100PU 00 00 using protocol: 3 00000045 winscard.c:449:SCardConnect() Active Protocol: T=0 00000051 winscard.c:459:SCardConnect() hCard Identity: 12763 00000747 utils.c:115:StatSynchronize() status file: /var/run/pcscd.events/event.1631.16985507 00001918 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00005124 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00002799 winscard_msg_srv.c:317:SHMProcessEventsContext() command BEGIN_TRANSACTION received by client 12 00000070 winscard.c:1057:SCardBeginTransaction() Status: 0x00000000 00003484 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00005214 winscard_msg_srv.c:317:SHMProcessEventsContext() command END_TRANSACTION received by client 12 00000069 winscard.c:1208:SCardEndTransaction() Status: 0x00000000 00013489 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel packet arrival 00000157 winscard_msg_srv.c:248:SHMProcessEventsServer() SHMProcessCommonChannelRequest detects: 14 00000042 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation is requested: 14 00000241 winscard_svc.c:133:ContextThread() Thread is started: 14 00000114 winscard_msg_srv.c:317:SHMProcessEventsContext() command CMD_VERSION received by client 14 00000046 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 00000233 winscard_msg_srv.c:317:SHMProcessEventsContext() command ESTABLISH_CONTEXT received by client 14 00000077 winscard.c:242:SCardEstablishContext() Establishing Context: 16992725 00001446 winscard_msg_srv.c:317:SHMProcessEventsContext() command CONNECT received by client 14 00000062 winscard.c:303:SCardConnect() Attempting Connect to CASTLES EZ100PU 00 00 using protocol: 3 00000043 winscard.c:449:SCardConnect() Active Protocol: T=0 00000042 winscard.c:459:SCardConnect() hCard Identity: 15e6d 00002252 utils.c:115:StatSynchronize() status file: /var/run/pcscd.events/event.1631.16985507 00002947 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 14 00001491 winscard_msg_srv.c:317:SHMProcessEventsContext() command DISCONNECT received by client 14 00000065 winscard.c:880:SCardDisconnect() Active Contexts: 2 00002746 winscard_msg_srv.c:317:SHMProcessEventsContext() command RELEASE_CONTEXT received by client 14 00000091 winscard.c:253:SCardReleaseContext() Releasing Context: 16992725 00000190 winscard_msg_srv.c:306:SHMProcessEventsContext() Client has disappeared: 14 00000097 winscard_svc.c:146:ContextThread() Client die: 14 00002369 winscard_msg_srv.c:317:SHMProcessEventsContext() command BEGIN_TRANSACTION received by client 12 00000069 winscard.c:1057:SCardBeginTransaction() Status: 0x00000000 00002760 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00002952 winscard_msg_srv.c:317:SHMProcessEventsContext() command END_TRANSACTION received by client 12 00000068 winscard.c:1208:SCardEndTransaction() Status: 0x00000000 00004267 winscard_msg_srv.c:317:SHMProcessEventsContext() command BEGIN_TRANSACTION received by client 12 00000066 winscard.c:1057:SCardBeginTransaction() Status: 0x00000000 00002895 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00004044 winscard_msg_srv.c:317:SHMProcessEventsContext() command RECONNECT received by client 12 00000062 winscard.c:532:SCardReconnect() Attempting reconnect to token. 00002250 winscard_msg_srv.c:317:SHMProcessEventsContext() command BEGIN_TRANSACTION received by client 12 00000623 winscard.c:1057:SCardBeginTransaction() Status: 0x00000000 00001643 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB received by client 12 00148735 winscard_msg_srv.c:317:SHMProcessEventsContext() command END_TRANSACTION received by client 12 00000123 winscard.c:1208:SCardEndTransaction() Status: 0x00000000 00011417 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel packet arrival 00000112 winscard_msg_srv.c:248:SHMProcessEventsServer() SHMProcessCommonChannelRequest detects: 14 00000042 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation is requested: 14 00000131 winscard_svc.c:133:ContextThread() Thread is started: 14 00000142 winscard_msg_srv.c:317:SHMProcessEventsContext() command CMD_VERSION received by client 14 00000060 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 00000381 winscard_msg_srv.c:317:SHMProcessEventsContext() command ESTABLISH_CONTEXT received by client 14 00000113 winscard.c:242:SCardEstablishContext() Establishing Context: 17010347 00001817 winscard_msg_srv.c:317:SHMProcessEventsContext() command CONNECT received by client 14 00000062 winscard.c:303:SCardConnect() Attempting Connect to CASTLES EZ100PU 00 00 using protocol: 3 00000039 winscard.c:340:SCardConnect() Waiting for release of lock
I am seeking for a process using the reader in exclusive mode or using a PCSC transaction but not found at the moment... Fabrice DIMITRIOU Ingénieur logiciel Responsable SI __________________________________ TMM Software 525 Avenue André Malraux 07500 Guilherand Granges - France Tél. +33 (0)4 75 61 44 48 [email protected] www.tmm-software.com -----Message d'origine----- De : Muscle [mailto:[email protected]] De la part de Ludovic Rousseau Envoyé : vendredi 29 mars 2013 16:18 À : MUSCLE Objet : Re: [Muscle] CPS card blocking with PCSC 2013/3/29 Fabrice Dimitriou <[email protected]>: > Here it is the pcsd log: Incomplete log. > 00000070 winscard.c:1057:SCardBeginTransaction() Status: 0x00000000 > 00001355 winscard_msg_srv.c:317:SHMProcessEventsContext() command GET_ATTRIB > received by client 12 > 00145492 winscard_msg_srv.c:317:SHMProcessEventsContext() command > END_TRANSACTION received by client 12 > 00000108 winscard.c:1208:SCardEndTransaction() Status: 0x00000000 > 00009304 winscard_msg_srv.c:239:SHMProcessEventsServer() Common channel > packet arrival > 00000117 winscard_msg_srv.c:248:SHMProcessEventsServer() > SHMProcessCommonChannelRequest detects: 14 > 00000042 pcscdaemon.c:147:SVCServiceRunLoop() A new context thread creation > is requested: 14 > 00000132 winscard_svc.c:133:ContextThread() Thread is started: 14 > 00000098 winscard_msg_srv.c:317:SHMProcessEventsContext() command > CMD_VERSION received by client 14 > 00000056 winscard_svc.c:189:ContextThread() Client is protocol version 3:0 > 00000294 winscard_msg_srv.c:317:SHMProcessEventsContext() command > ESTABLISH_CONTEXT received by client 14 > 00000118 winscard.c:242:SCardEstablishContext() Establishing Context: > 17031666 > 00001527 winscard_msg_srv.c:317:SHMProcessEventsContext() command CONNECT > received by client 14 > 00000066 winscard.c:303:SCardConnect() Attempting Connect to CASTLES EZ100PU > 00 00 using protocol: 3 > 00000040 winscard.c:340:SCardConnect() Waiting for release of lock > > In winscard.c source code, it refers to: > > if (rContext->hLockId != 0) > { > Log1(PCSC_LOG_INFO, "Waiting for release of lock"); > while (rContext->hLockId != 0) > (void)SYS_USleep(PCSCLITE_LOCK_POLL_RATE); > Log1(PCSC_LOG_INFO, "Lock released"); > } > > Which is a endless loop !!!! > > How can get out from this loop? Another thread or another application is using the reader in exclusive mode or using a PCSC transaction. Send a FULL pcsc-spy log please. Bye -- Dr. Ludovic Rousseau _______________________________________________ Muscle mailing list [email protected] http://lists.musclecard.com/mailman/listinfo/muscle_lists.musclecard.com _______________________________________________ Muscle mailing list [email protected] http://lists.musclecard.com/mailman/listinfo/muscle_lists.musclecard.com
