SmartCard HSM SO-PIN change

classic Classic list List threaded Threaded
6 messages Options
Reply | Threaded
Open this post in threaded view
|

SmartCard HSM SO-PIN change

marjan stankovic

Hello all,

 

We have bought SmartCard HSMs for testing and evaluating (in March this year). So far it fits our needs.


But we have one strange problem in Windows 7. Is it possible that SO-PIN change does not work?

This is what I can see:

 

pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login --login-type so --so-pin 1234567812345678 --change-pin --new-pin 1234567812345678

Using slot 1 with a present token (0x1)

2014-05-14 10:20:20.798 cannot lock memory, sensitive data may be paged to disk

2014-05-14 10:20:20.832 cannot lock memory, sensitive data may be paged to disk

PIN successfully changed

 

no matter what values I provide for SO-PIN. Afterwards, I see that SO-PIN was not changed (i.e. if I try to reinitialize the card with "new SO-PIN" it will raise invalid pin error).

 

We are using OpenSC v0.13.0.


In Ubuntu it works fine. This seems to be a problem in OpenSC?

 

Best regards,

Marjan Stankovic


------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: SmartCard HSM SO-PIN change

Andreas Schwier (ML)
Dear Marjan,

can you enable the trace output in opensc to see what APDU is send to
the SmartCard-HSM ?

Is the Windows version a recent nightly build or from the SmartCard-HSM
starterkit [1] ?

Andreas

[1] http://www.cardcontact.de/download/sc-hsm-starterkit.zip


On 05/14/2014 11:07 AM, marjan stankovic wrote:

> Hello all,
>
>
>
> We have bought SmartCard HSMs for testing and evaluating (in March this
> year). So far it fits our needs.
>
>
> But we have one strange problem in Windows 7. Is it possible that SO-PIN
> change does not work?
>
> This is what I can see:
>
>
>
> pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login
> --login-type so --so-pin 1234567812345678 --change-pin --new-pin
> 1234567812345678
>
> Using slot 1 with a present token (0x1)
>
> 2014-05-14 10:20:20.798 cannot lock memory, sensitive data may be paged to
> disk
>
> 2014-05-14 10:20:20.832 cannot lock memory, sensitive data may be paged to
> disk
>
> PIN successfully changed
>
>
>
> no matter what values I provide for SO-PIN. Afterwards, I see that SO-PIN
> was not changed (i.e. if I try to reinitialize the card with "new SO-PIN"
> it will raise invalid pin error).
>
>
>
> We are using OpenSC v0.13.0.
>
> In Ubuntu it works fine. This seems to be a problem in OpenSC?
>
>
>
> Best regards,
>
> Marjan Stankovic
>
>
>
> ------------------------------------------------------------------------------
> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
> Instantly run your Selenium tests across 300+ browser/OS combos.
> Get unparalleled scalability from the best Selenium testing platform available
> Simple to use. Nothing to install. Get started now for free."
> http://p.sf.net/sfu/SauceLabs
>
>
>
> _______________________________________________
> Opensc-devel mailing list
> [hidden email]
> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>


------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: SmartCard HSM SO-PIN change

Sanaullah
My Card is working in Linux for change SO-pin using the following command

pkcs11-tool --module /usr/local/lib/opensc-pkcs11.so --login --login-type so --so-pin 3537363231383830 --change-pin --new-pin 0123456789012345



On Wed, May 14, 2014 at 2:27 PM, Andreas Schwier <[hidden email]> wrote:
Dear Marjan,

can you enable the trace output in opensc to see what APDU is send to
the SmartCard-HSM ?

Is the Windows version a recent nightly build or from the SmartCard-HSM
starterkit [1] ?

Andreas

[1] http://www.cardcontact.de/download/sc-hsm-starterkit.zip


On 05/14/2014 11:07 AM, marjan stankovic wrote:
> Hello all,
>
>
>
> We have bought SmartCard HSMs for testing and evaluating (in March this
> year). So far it fits our needs.
>
>
> But we have one strange problem in Windows 7. Is it possible that SO-PIN
> change does not work?
>
> This is what I can see:
>
>
>
> pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login
> --login-type so --so-pin 1234567812345678 --change-pin --new-pin
> 1234567812345678
>
> Using slot 1 with a present token (0x1)
>
> 2014-05-14 10:20:20.798 cannot lock memory, sensitive data may be paged to
> disk
>
> 2014-05-14 10:20:20.832 cannot lock memory, sensitive data may be paged to
> disk
>
> PIN successfully changed
>
>
>
> no matter what values I provide for SO-PIN. Afterwards, I see that SO-PIN
> was not changed (i.e. if I try to reinitialize the card with "new SO-PIN"
> it will raise invalid pin error).
>
>
>
> We are using OpenSC v0.13.0.
>
> In Ubuntu it works fine. This seems to be a problem in OpenSC?
>
>
>
> Best regards,
>
> Marjan Stankovic
>
>
>
> ------------------------------------------------------------------------------
> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
> Instantly run your Selenium tests across 300+ browser/OS combos.
> Get unparalleled scalability from the best Selenium testing platform available
> Simple to use. Nothing to install. Get started now for free."
> http://p.sf.net/sfu/SauceLabs
>
>
>
> _______________________________________________
> Opensc-devel mailing list
> [hidden email]
> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>


------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel


------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: SmartCard HSM SO-PIN change

marjan stankovic
In reply to this post by marjan stankovic
Hallo Andreas,


This is the output when I provide wrong SO-PIN:

============================================================================================================================================
============================================================================================================================================
C:\Users\mst>pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login --login-type so --so-pin 1234567812345679 --change-pin --new-pin 1234567812345678
2014-05-14 11:39:46.764 ===================================
2014-05-14 11:39:46.765 opensc version: 0.13.0
2014-05-14 11:39:46.765 PC/SC options: connect_exclusive=0 disconnect_action=1 transaction_end_action=0 reconnect_action=0 enable_pinpad=1 enable_pace=1
2014-05-14 11:39:46.766 [opensc-pkcs11] reader-pcsc.c:948:pcsc_detect_readers: called
2014-05-14 11:39:46.766 Probing pcsc readers
2014-05-14 11:39:46.766 Establish pcsc context
2014-05-14 11:39:46.770 Found new pcsc reader 'CASTLES EZMINI 0'
2014-05-14 11:39:46.770 CASTLES EZMINI 0 check
2014-05-14 11:39:46.770 current  state: 0x000A0012
2014-05-14 11:39:46.770 previous state: 0x00000000
2014-05-14 11:39:46.770 card absent
2014-05-14 11:39:46.770 Requesting reader features ...
2014-05-14 11:39:46.770 CASTLES EZMINI 0:SCardConnect(DIRECT): 0x00000000
2014-05-14 11:39:46.771 [opensc-pkcs11] reader-pcsc.c:824:detect_reader_features: called
2014-05-14 11:39:46.772 CASTLES EZMINI 0:SCardControl failed: 0x00000032
2014-05-14 11:39:46.772 Found new pcsc reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:46.772 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:46.772 current  state: 0x00010422
2014-05-14 11:39:46.772 previous state: 0x00000000
2014-05-14 11:39:46.772 card present, changed
2014-05-14 11:39:46.772 Requesting reader features ...
2014-05-14 11:39:46.833 OMNIKEY CardMan 3x21 0:SCardConnect(SHARED): 0x00000000
2014-05-14 11:39:46.833 [opensc-pkcs11] reader-pcsc.c:824:detect_reader_features: called
2014-05-14 11:39:46.834 [opensc-pkcs11] reader-pcsc.c:1101:pcsc_detect_readers: returning with: 0 (Success)
2014-05-14 11:39:46.834 PKCS#11 options: plug_and_play=1 max_virtual_slots=16 slots_per_card=4 hide_empty_tokens=1 lock_login=0 pin_unblock_style=0 zero_ckaid_for_ca_certs=0 create_slots_flags=0x8
2014-05-14 11:39:46.835 Creating slot with id 0x0
2014-05-14 11:39:46.835 Creating slot with id 0x1
2014-05-14 11:39:46.835 Creating slot with id 0x2
2014-05-14 11:39:46.835 Creating slot with id 0x3
2014-05-14 11:39:46.835 Creating slot with id 0x4
2014-05-14 11:39:46.836 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:46.836 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:46.836 CASTLES EZMINI 0 check
2014-05-14 11:39:46.836 current  state: 0x000A0222
2014-05-14 11:39:46.836 previous state: 0x000A0012
2014-05-14 11:39:46.837 card present, changed
2014-05-14 11:39:46.837 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 3
2014-05-14 11:39:46.837 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 3
2014-05-14 11:39:46.837 CASTLES EZMINI 0: Detecting smart card
2014-05-14 11:39:46.837 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:46.837 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:46.837 CASTLES EZMINI 0 check
2014-05-14 11:39:46.838 current  state: 0x000B0012
2014-05-14 11:39:46.838 previous state: 0x000A0222
2014-05-14 11:39:46.838 card absent, changed
2014-05-14 11:39:46.838 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 2
2014-05-14 11:39:46.838 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 2
2014-05-14 11:39:46.838 CASTLES EZMINI 0: Card changed
2014-05-14 11:39:46.838 CASTLES EZMINI 0: card removed
2014-05-14 11:39:46.839 slot_token_removed(0x1)
2014-05-14 11:39:46.839 real C_CloseAllSessions(0x1) 0
2014-05-14 11:39:46.839 slot_token_removed(0x2)
2014-05-14 11:39:46.840 real C_CloseAllSessions(0x2) 0
2014-05-14 11:39:46.840 slot_token_removed(0x3)
2014-05-14 11:39:46.840 real C_CloseAllSessions(0x3) 0
2014-05-14 11:39:46.840 slot_token_removed(0x4)
2014-05-14 11:39:46.840 real C_CloseAllSessions(0x4) 0
2014-05-14 11:39:46.841 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:46.841 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:46.841 CASTLES EZMINI 0 check
2014-05-14 11:39:46.841 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:46.842 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:46.842 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:46.842 CASTLES EZMINI 0: card absent
2014-05-14 11:39:46.842 CASTLES EZMINI 0: card removed
2014-05-14 11:39:46.844 slot_token_removed(0x1)
2014-05-14 11:39:46.846 real C_CloseAllSessions(0x1) 0
2014-05-14 11:39:46.848 slot_token_removed(0x2)
2014-05-14 11:39:46.849 real C_CloseAllSessions(0x2) 0
2014-05-14 11:39:46.851 slot_token_removed(0x3)
2014-05-14 11:39:46.852 real C_CloseAllSessions(0x3) 0
2014-05-14 11:39:46.854 slot_token_removed(0x4)
2014-05-14 11:39:46.856 real C_CloseAllSessions(0x4) 0
2014-05-14 11:39:46.857 Creating slot with id 0x5
2014-05-14 11:39:46.868 Creating slot with id 0x6
2014-05-14 11:39:46.869 Creating slot with id 0x7
2014-05-14 11:39:46.871 Creating slot with id 0x8
2014-05-14 11:39:46.872 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:46.873 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:46.884 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:46.886 current  state: 0x00010022
2014-05-14 11:39:46.887 previous state: 0x00010422
2014-05-14 11:39:46.889 card present
2014-05-14 11:39:46.890 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 1
2014-05-14 11:39:46.900 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 1
2014-05-14 11:39:46.902 OMNIKEY CardMan 3x21 0: Detecting smart card
2014-05-14 11:39:46.902 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:46.904 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:46.906 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:46.908 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:46.917 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 1
2014-05-14 11:39:46.918 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 1
2014-05-14 11:39:46.919 OMNIKEY CardMan 3x21 0: First seen the card
2014-05-14 11:39:46.921 OMNIKEY CardMan 3x21 0: Connecting ...
2014-05-14 11:39:46.923 [opensc-pkcs11] card.c:125:sc_connect_card: called
2014-05-14 11:39:46.924 [opensc-pkcs11] reader-pcsc.c:450:pcsc_connect: called
2014-05-14 11:39:46.925 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:46.934 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:46.936 Initial protocol: T=1
2014-05-14 11:39:46.938 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:46.939 ATR try : 3B:DD:18:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:00:70:0A:90:00:8B
2014-05-14 11:39:46.940 ignored - wrong length
2014-05-14 11:39:46.942 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:46.950 ATR try : 3B:7F:96:00:00:00:31:B9:64:40:70:14:10:73:94:01:80:82:90:00
2014-05-14 11:39:46.952 ignored - wrong length
2014-05-14 11:39:46.954 ATR try : 3B:7F:96:00:00:00:31:B8:64:40:70:14:10:73:94:01:80:82:90:00
2014-05-14 11:39:46.955 ignored - wrong length
2014-05-14 11:39:46.957 ATR try : 3B:DF:18:FF:81:91:FE:1F:C3:00:31:B8:64:0C:01:EC:C1:73:94:01:80:82:90:00:B3
2014-05-14 11:39:46.959 ignored - wrong length
2014-05-14 11:39:46.966 ATR try : 3B:DC:18:FF:81:91:FE:1F:C3:80:73:C8:21:13:66:01:0B:03:52:00:05:38
2014-05-14 11:39:46.968 ignored - wrong length
2014-05-14 11:39:46.969 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:46.971 ATR try : 3b:6e:00:ff:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
2014-05-14 11:39:46.973 ignored - wrong length
2014-05-14 11:39:46.974 ATR try : 3b:fe:94:00:ff:80:b1:fa:45:1f:03:45:73:74:45:49:44:20:76:65:72:20:31:2e:30:43
2014-05-14 11:39:46.975 ignored - wrong length
2014-05-14 11:39:46.984 ATR try : 3b:5e:11:ff:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
2014-05-14 11:39:46.985 ignored - wrong length
2014-05-14 11:39:46.987 ATR try : 3b:de:18:ff:c0:80:b1:fe:45:1f:03:45:73:74:45:49:44:20:76:65:72:20:31:2e:30:2b
2014-05-14 11:39:46.988 ignored - wrong length
2014-05-14 11:39:46.991 ATR try : 3b:6e:00:00:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
2014-05-14 11:39:46.999 ignored - wrong length
2014-05-14 11:39:47.001 ATR try : 3b:fe:18:00:00:80:31:fe:45:45:73:74:45:49:44:20:76:65:72:20:31:2e:30:a8
2014-05-14 11:39:47.002 ATR try : 3b:fe:18:00:00:80:31:fe:45:80:31:80:66:40:90:a4:56:1b:16:83:01:90:00:86
2014-05-14 11:39:47.004 ATR try : 3b:fe:18:00:00:80:31:fe:45:80:31:80:66:40:90:a4:16:2a:00:83:01:90:00:e1
2014-05-14 11:39:47.005 ATR try : 3b:fe:18:00:00:80:31:fe:45:80:31:80:66:40:90:a4:16:2a:00:83:0f:90:00:ef
2014-05-14 11:39:47.007 ATR try : 3b:ff:94:00:ff:80:b1:fe:45:1f:03:00:68:d2:76:00:00:28:ff:05:1e:31:80:00:90:00:23
2014-05-14 11:39:47.008 ignored - wrong length
2014-05-14 11:39:47.017 ATR try : 3b:ff:11:00:ff:80:b1:fe:45:1f:03:00:68:d2:76:00:00:28:ff:05:1e:31:80:00:90:00:a6
2014-05-14 11:39:47.018 ignored - wrong length
2014-05-14 11:39:47.020 matching configured ATRs
2014-05-14 11:39:47.022 trying driver 'authentic'
2014-05-14 11:39:47.022 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.024 ATR try : 3B:DD:18:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:00:70:0A:90:00:8B
2014-05-14 11:39:47.033 ignored - wrong length
2014-05-14 11:39:47.035 trying driver 'iasecc'
2014-05-14 11:39:47.036 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.038 ATR try : 3B:7F:96:00:00:00:31:B9:64:40:70:14:10:73:94:01:80:82:90:00
2014-05-14 11:39:47.041 ignored - wrong length
2014-05-14 11:39:47.041 ATR try : 3B:7F:96:00:00:00:31:B8:64:40:70:14:10:73:94:01:80:82:90:00
2014-05-14 11:39:47.043 ignored - wrong length
2014-05-14 11:39:47.053 ATR try : 3B:DF:18:FF:81:91:FE:1F:C3:00:31:B8:64:0C:01:EC:C1:73:94:01:80:82:90:00:B3
2014-05-14 11:39:47.055 ignored - wrong length
2014-05-14 11:39:47.056 ATR try : 3B:DC:18:FF:81:91:FE:1F:C3:80:73:C8:21:13:66:01:0B:03:52:00:05:38
2014-05-14 11:39:47.057 ignored - wrong length
2014-05-14 11:39:47.059 matching built-in ATRs
2014-05-14 11:39:47.068 trying driver 'cardos'
2014-05-14 11:39:47.069 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.071 ATR try : 3b:e2:00:ff:c1:10:31:fe:55:c8:02:9c
2014-05-14 11:39:47.073 ignored - wrong length
2014-05-14 11:39:47.074 ATR try : 3b:e9:00:ff:c1:10:31:fe:55:00:64:05:00:c8:02:31:80:00:47
2014-05-14 11:39:47.075 ignored - wrong length
2014-05-14 11:39:47.084 ATR try : 3b:fb:98:00:ff:c1:10:31:fe:55:00:64:05:20:47:03:31:80:00:90:00:f3
2014-05-14 11:39:47.086 ignored - wrong length
2014-05-14 11:39:47.088 ATR try : 3b:fc:98:00:ff:c1:10:31:fe:55:c8:03:49:6e:66:6f:63:61:6d:65:72:65:28
2014-05-14 11:39:47.090 ignored - wrong length
2014-05-14 11:39:47.091 ATR try : 3b:f4:98:00:ff:c1:10:31:fe:55:4d:34:63:76:b4
2014-05-14 11:39:47.092 ignored - wrong length
2014-05-14 11:39:47.101 ATR try : 3b:f2:18:00:ff:c1:0a:31:fe:55:c8:06:8a
2014-05-14 11:39:47.102 ignored - wrong length
2014-05-14 11:39:47.105 ATR try : 3b:d2:18:02:c1:0a:31:fe:58:c8:0d:51
2014-05-14 11:39:47.106 ignored - wrong length
2014-05-14 11:39:47.107 trying driver 'flex'
2014-05-14 11:39:47.108 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.117 ATR try : 3b:95:15:40:20:68:01:02:00:00
2014-05-14 11:39:47.118 ignored - wrong length
2014-05-14 11:39:47.120 ATR try : 3B:95:15:40:FF:68:01:02:02:01
2014-05-14 11:39:47.122 ignored - wrong length
2014-05-14 11:39:47.124 ATR try : 3B:95:15:40:FF:68:01:02:02:04
2014-05-14 11:39:47.126 ignored - wrong length
2014-05-14 11:39:47.134 ATR try : 3B:85:40:20:68:01:01:05:01
2014-05-14 11:39:47.136 ignored - wrong length
2014-05-14 11:39:47.138 ATR try : 3B:95:94:40:FF:63:01:01:02:01
2014-05-14 11:39:47.139 ignored - wrong length
2014-05-14 11:39:47.141 ATR try : 3B:95:15:40:FF:63:01:01:02:01
2014-05-14 11:39:47.142 ignored - wrong length
2014-05-14 11:39:47.151 ATR try : 3B:95:18:40:FF:64:02:01:01:02
2014-05-14 11:39:47.152 ignored - wrong length
2014-05-14 11:39:47.153 ATR try : 3B:95:18:40:FF:62:01:01:00:00
2014-05-14 11:39:47.155 ignored - wrong length
2014-05-14 11:39:47.158 ATR try : 3B:95:18:40:FF:62:01:02:01:04
2014-05-14 11:39:47.158 ignored - wrong length
2014-05-14 11:39:47.159 ATR try : 3B:95:18:40:FF:62:04:01:01:05
2014-05-14 11:39:47.168 ignored - wrong length
2014-05-14 11:39:47.170 ATR try : 3b:95:15:40:ff:68:01:02:45:47
2014-05-14 11:39:47.173 ignored - wrong length
2014-05-14 11:39:47.174 ATR try : 3B:E2:00:00:40:20:49:06
2014-05-14 11:39:47.175 ignored - wrong length
2014-05-14 11:39:47.176 ATR try : 3B:E2:00:00:40:20:49:05
2014-05-14 11:39:47.185 ignored - wrong length
2014-05-14 11:39:47.186 ATR try : 3B:E2:00:00:40:20:49:07
2014-05-14 11:39:47.188 ignored - wrong length
2014-05-14 11:39:47.189 ATR try : 3B:85:40:20:68:01:01:03:05
2014-05-14 11:39:47.191 ignored - wrong length
2014-05-14 11:39:47.192 ATR try : 3B:02:14:50
2014-05-14 11:39:47.201 ignored - wrong length
2014-05-14 11:39:47.202 ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0
2014-05-14 11:39:47.203 ignored - wrong length
2014-05-14 11:39:47.205 ATR try : 3B:32:15:00:06:80
2014-05-14 11:39:47.207 ignored - wrong length
2014-05-14 11:39:47.208 ATR try : 3B:32:15:00:06:95
2014-05-14 11:39:47.209 ignored - wrong length
2014-05-14 11:39:47.218 ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0
2014-05-14 11:39:47.219 ignored - wrong length
2014-05-14 11:39:47.221 ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0
2014-05-14 11:39:47.223 ignored - wrong length
2014-05-14 11:39:47.224 ATR try : 3B:16:94:81:10:06:01:81:3F
2014-05-14 11:39:47.225 ignored - wrong length
2014-05-14 11:39:47.227 ATR try : 3B:16:94:81:10:06:01:81:2F
2014-05-14 11:39:47.235 ignored - wrong length
2014-05-14 11:39:47.237 trying driver 'cyberflex'
2014-05-14 11:39:47.239 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.241 ATR try : 3b:95:15:40:20:68:01:02:00:00
2014-05-14 11:39:47.242 ignored - wrong length
2014-05-14 11:39:47.243 ATR try : 3B:95:15:40:FF:68:01:02:02:01
2014-05-14 11:39:47.252 ignored - wrong length
2014-05-14 11:39:47.254 ATR try : 3B:95:15:40:FF:68:01:02:02:04
2014-05-14 11:39:47.255 ignored - wrong length
2014-05-14 11:39:47.257 ATR try : 3B:85:40:20:68:01:01:05:01
2014-05-14 11:39:47.258 ignored - wrong length
2014-05-14 11:39:47.259 ATR try : 3B:95:94:40:FF:63:01:01:02:01
2014-05-14 11:39:47.268 ignored - wrong length
2014-05-14 11:39:47.275 ATR try : 3B:95:15:40:FF:63:01:01:02:01
2014-05-14 11:39:47.275 ignored - wrong length
2014-05-14 11:39:47.276 ATR try : 3B:95:18:40:FF:64:02:01:01:02
2014-05-14 11:39:47.285 ignored - wrong length
2014-05-14 11:39:47.287 ATR try : 3B:95:18:40:FF:62:01:01:00:00
2014-05-14 11:39:47.289 ignored - wrong length
2014-05-14 11:39:47.291 ATR try : 3B:95:18:40:FF:62:01:02:01:04
2014-05-14 11:39:47.292 ignored - wrong length
2014-05-14 11:39:47.292 ATR try : 3B:95:18:40:FF:62:04:01:01:05
2014-05-14 11:39:47.301 ignored - wrong length
2014-05-14 11:39:47.302 ATR try : 3b:95:15:40:ff:68:01:02:45:47
2014-05-14 11:39:47.304 ignored - wrong length
2014-05-14 11:39:47.305 ATR try : 3B:E2:00:00:40:20:49:06
2014-05-14 11:39:47.307 ignored - wrong length
2014-05-14 11:39:47.310 ATR try : 3B:E2:00:00:40:20:49:05
2014-05-14 11:39:47.317 ignored - wrong length
2014-05-14 11:39:47.319 ATR try : 3B:E2:00:00:40:20:49:07
2014-05-14 11:39:47.320 ignored - wrong length
2014-05-14 11:39:47.323 ATR try : 3B:85:40:20:68:01:01:03:05
2014-05-14 11:39:47.324 ignored - wrong length
2014-05-14 11:39:47.325 ATR try : 3B:02:14:50
2014-05-14 11:39:47.326 ignored - wrong length
2014-05-14 11:39:47.335 ATR try : 3B:19:14:55:90:01:02:01:00:05:04:B0
2014-05-14 11:39:47.337 ignored - wrong length
2014-05-14 11:39:47.339 ATR try : 3B:32:15:00:06:80
2014-05-14 11:39:47.340 ignored - wrong length
2014-05-14 11:39:47.342 ATR try : 3B:32:15:00:06:95
2014-05-14 11:39:47.343 ignored - wrong length
2014-05-14 11:39:47.351 ATR try : 3B:19:14:59:01:01:0F:01:00:05:08:B0
2014-05-14 11:39:47.353 ignored - wrong length
2014-05-14 11:39:47.354 ATR try : 3B:19:14:55:90:01:01:01:00:05:08:B0
2014-05-14 11:39:47.356 ignored - wrong length
2014-05-14 11:39:47.358 ATR try : 3B:16:94:81:10:06:01:81:3F
2014-05-14 11:39:47.359 ignored - wrong length
2014-05-14 11:39:47.360 ATR try : 3B:16:94:81:10:06:01:81:2F
2014-05-14 11:39:47.368 ignored - wrong length
2014-05-14 11:39:47.370 trying driver 'gpk'
2014-05-14 11:39:47.372 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.374 ATR try : 3B:27:00:80:65:A2:04:01:01:37
2014-05-14 11:39:47.375 ignored - wrong length
2014-05-14 11:39:47.376 ATR try : 3B:27:00:80:65:A2:05:01:01:37
2014-05-14 11:39:47.385 ignored - wrong length
2014-05-14 11:39:47.386 ATR try : 3B:27:00:80:65:A2:0C:01:01:37
2014-05-14 11:39:47.388 ignored - wrong length
2014-05-14 11:39:47.389 ATR try : 3B:A7:00:40:14:80:65:A2:14:01:01:37
2014-05-14 11:39:47.391 ignored - wrong length
2014-05-14 11:39:47.392 ATR try : 3B:A7:00:40:18:80:65:A2:08:01:01:52
2014-05-14 11:39:47.393 ignored - wrong length
2014-05-14 11:39:47.402 ATR try : 3B:A7:00:40:18:80:65:A2:09:01:01:52
2014-05-14 11:39:47.403 ignored - wrong length
2014-05-14 11:39:47.406 ATR try : 3B:A7:00:40:18:80:65:A2:09:01:02:52
2014-05-14 11:39:47.407 ignored - wrong length
2014-05-14 11:39:47.408 ATR try : 3B:A7:00:40:18:80:65:A2:09:01:03:52
2014-05-14 11:39:47.409 ignored - wrong length
2014-05-14 11:39:47.411 trying driver 'gemsafeV1'
2014-05-14 11:39:47.420 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.421 ATR try : 3B:7B:94:00:00:80:65:B0:83:01:01:74:83:00:90:00
2014-05-14 11:39:47.423 ignored - wrong length
2014-05-14 11:39:47.424 ATR try : 3B:6B:00:00:80:65:B0:83:01:01:74:83:00:90:00
2014-05-14 11:39:47.426 ignored - wrong length
2014-05-14 11:39:47.427 ATR try : 3b:6d:00:00:80:31:80:65:b0:83:01:02:90:83:00:90:00
2014-05-14 11:39:47.435 ignored - wrong length
2014-05-14 11:39:47.437 ATR try : 3B:6B:00:00:80:65:B0:83:01:03:74:83:00:90:00
2014-05-14 11:39:47.439 ignored - wrong length
2014-05-14 11:39:47.440 ATR try : 3B:7A:94:00:00:80:65:A2:01:01:01:3D:72:D6:43
2014-05-14 11:39:47.442 ignored - wrong length
2014-05-14 11:39:47.443 ATR try : 3B:7D:94:00:00:80:31:80:65:B0:83:01:01:90:83:00:90:00
2014-05-14 11:39:47.444 ignored - wrong length
2014-05-14 11:39:47.453 ATR try : 3B:7D:96:00:00:80:31:80:65:B0:83:11:48:C8:83:00:90:00
2014-05-14 11:39:47.454 ignored - wrong length
2014-05-14 11:39:47.457 ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:C0:A9:83:00
2014-05-14 11:39:47.458 ignored - wrong length
2014-05-14 11:39:47.459 ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:C0:A9:83:00:90:00
2014-05-14 11:39:47.468 ignored - wrong length
2014-05-14 11:39:47.469 ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:00:C8:83:00
2014-05-14 11:39:47.470 ignored - wrong length
2014-05-14 11:39:47.473 ATR try : 3B:7D:95:00:00:80:31:80:65:B0:83:11:00:C8:83:00:90:00
2014-05-14 11:39:47.474 ignored - wrong length
2014-05-14 11:39:47.475 trying driver 'miocos'
2014-05-14 11:39:47.476 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.485 ATR try : 3B:9D:94:40:23:00:68:10:11:4D:69:6F:43:4F:53:00:90:00
2014-05-14 11:39:47.486 ignored - wrong length
2014-05-14 11:39:47.488 ATR try : 3B:9D:94:40:23:00:68:20:01:4D:69:6F:43:4F:53:00:90:00
2014-05-14 11:39:47.489 ignored - wrong length
2014-05-14 11:39:47.491 trying driver 'mcrd'
2014-05-14 11:39:47.493 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.494 ATR try : 3B:FF:94:00:FF:80:B1:FE:45:1F:03:00:68:D2:76:00:00:28:FF:05:1E:31:80:00:90:00:23
2014-05-14 11:39:47.502 ignored - wrong length
2014-05-14 11:39:47.504 ATR try : 3b:6f:00:ff:00:68:d2:76:00:00:28:ff:05:1e:31:80:00:90:00
2014-05-14 11:39:47.505 ignored - wrong length
2014-05-14 11:39:47.507 ATR try : 3b:ff:11:00:ff:80:b1:fe:45:1f:03:00:68:d2:76:00:00:28:ff:05:1e:31:80:00:90:00:a6
2014-05-14 11:39:47.508 ignored - wrong length
2014-05-14 11:39:47.509 ATR try : 3B:FE:94:00:FF:80:B1:FA:45:1F:03:45:73:74:45:49:44:20
2014-05-14 11:39:47.511 ignored - wrong length
2014-05-14 11:39:47.519 ATR try : 3b:fe:94:00:ff:80:b1:fa:45:1f:03:45:73:74:45:49:44:20:76:65:72:20:31:2e:30:43
2014-05-14 11:39:47.521 ignored - wrong length
2014-05-14 11:39:47.523 ATR try : 3b:6e:00:ff:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
2014-05-14 11:39:47.524 ignored - wrong length
2014-05-14 11:39:47.526 ATR try : 3b:de:18:ff:c0:80:b1:fe:45:1f:03:45:73:74:45:49:44:20:76:65:72:20:31:2e:30:2b
2014-05-14 11:39:47.526 ignored - wrong length
2014-05-14 11:39:47.528 ATR try : 3b:5e:11:ff:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
2014-05-14 11:39:47.536 ignored - wrong length
2014-05-14 11:39:47.538 ATR try : 3b:6e:00:00:45:73:74:45:49:44:20:76:65:72:20:31:2e:30
2014-05-14 11:39:47.541 ignored - wrong length
2014-05-14 11:39:47.541 ATR try : 3B:FE:18:00:00:80:31:FE:45:45:73:74:45:49:44:20:76:65:72:20:31:2E:30:A8
2014-05-14 11:39:47.543 ATR try : 3B:FE:18:00:00:80:31:FE:45:80:31:80:66:40:90:A4:56:1B:16:83:01:90:00:86
2014-05-14 11:39:47.544 ATR try : 3b:fe:18:00:00:80:31:fe:45:80:31:80:66:40:90:a4:16:2a:00:83:01:90:00:e1
2014-05-14 11:39:47.552 ATR try : 3b:fe:18:00:00:80:31:fe:45:80:31:80:66:40:90:a4:16:2a:00:83:0f:90:00:ef
2014-05-14 11:39:47.553 trying driver 'asepcos'
2014-05-14 11:39:47.555 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.557 ATR try : 3b:d6:18:00:81:b1:80:7d:1f:03:80:51:00:61:10:30:8f
2014-05-14 11:39:47.558 ignored - wrong length
2014-05-14 11:39:47.559 ATR try : 3b:d6:18:00:81:b1:fe:7d:1f:03:41:53:45:37:35:35:01
2014-05-14 11:39:47.561 ignored - wrong length
2014-05-14 11:39:47.569 trying driver 'starcos'
2014-05-14 11:39:47.571 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.573 ATR try : 3B:B7:94:00:c0:24:31:fe:65:53:50:4b:32:33:90:00:b4
2014-05-14 11:39:47.574 ignored - wrong length
2014-05-14 11:39:47.576 ATR try : 3B:B7:94:00:81:31:fe:65:53:50:4b:32:33:90:00:d1
2014-05-14 11:39:47.577 ignored - wrong length
2014-05-14 11:39:47.585 ATR try : 3b:b7:18:00:c0:3e:31:fe:65:53:50:4b:32:34:90:00:25
2014-05-14 11:39:47.587 ignored - wrong length
2014-05-14 11:39:47.588 trying driver 'tcos'
2014-05-14 11:39:47.590 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.591 ATR try : 3B:BA:13:00:81:31:86:5D:00:64:05:0A:02:01:31:80:90:00:8B
2014-05-14 11:39:47.593 ignored - wrong length
2014-05-14 11:39:47.594 ATR try : 3B:BA:14:00:81:31:86:5D:00:64:05:14:02:02:31:80:90:00:91
2014-05-14 11:39:47.603 ignored - wrong length
2014-05-14 11:39:47.604 ATR try : 3B:BA:96:00:81:31:86:5D:00:64:05:60:02:03:31:80:90:00:66
2014-05-14 11:39:47.606 ignored - wrong length
2014-05-14 11:39:47.606 ATR try : 3B:BA:96:00:81:31:86:5D:00:64:05:7B:02:03:31:80:90:00:7D
2014-05-14 11:39:47.606 ignored - wrong length
2014-05-14 11:39:47.607 ATR try : 3B:BF:96:00:81:31:FE:5D:00:64:04:11:03:01:31:C0:73:F7:01:D0:00:90:00:7D
2014-05-14 11:39:47.612 ATR try : 3B:BF:B6:00:81:31:FE:5D:00:64:04:28:03:02:31:C0:73:F7:01:D0:00:90:00:67
2014-05-14 11:39:47.620 trying driver 'openpgp'
2014-05-14 11:39:47.621 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.625 ATR try : 3b:fa:13:00:ff:81:31:80:45:00:31:c1:73:c0:01:00:00:90:00:b1
2014-05-14 11:39:47.626 ignored - wrong length
2014-05-14 11:39:47.627 ATR try : 3b:da:18:ff:81:b1:fe:75:1f:03:00:31:c5:73:c0:01:40:00:90:00:0c
2014-05-14 11:39:47.635 ignored - wrong length
2014-05-14 11:39:47.637 trying driver 'jcop'
2014-05-14 11:39:47.638 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.640 ATR try : 3B:E6:00:FF:81:31:FE:45:4A:43:4F:50:33:31:06
2014-05-14 11:39:47.641 ignored - wrong length
2014-05-14 11:39:47.643 trying driver 'oberthur'
2014-05-14 11:39:47.644 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.652 ATR try : 3B:7D:18:00:00:00:31:80:71:8E:64:77:E3:01:00:82:90:00
2014-05-14 11:39:47.654 ignored - wrong length
2014-05-14 11:39:47.655 ATR try : 3B:7D:18:00:00:00:31:80:71:8E:64:77:E3:02:00:82:90:00
2014-05-14 11:39:47.658 ignored - wrong length
2014-05-14 11:39:47.659 ATR try : 3B:7D:11:00:00:00:31:80:71:8E:64:77:E3:01:00:82:90:00
2014-05-14 11:39:47.660 ignored - wrong length
2014-05-14 11:39:47.661 ATR try : 3B:7D:11:00:00:00:31:80:71:8E:64:77:E3:02:00:82:90:00
2014-05-14 11:39:47.669 ignored - wrong length
2014-05-14 11:39:47.671 ATR try : 3B:7B:18:00:00:00:31:C0:64:77:E3:03:00:82:90:00
2014-05-14 11:39:47.672 ignored - wrong length
2014-05-14 11:39:47.674 ATR try : 3B:FB:11:00:00:81:31:FE:45:00:31:C0:64:77:E9:10:00:00:90:00:6A
2014-05-14 11:39:47.676 ignored - wrong length
2014-05-14 11:39:47.677 trying driver 'authentic'
2014-05-14 11:39:47.678 try to match card with ATR 3BFE1800008131FE458031815448534D31738021408107FA
2014-05-14 11:39:47.686 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.688 ATR try : 3B:DD:18:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:00:70:0A:90:00:8B
2014-05-14 11:39:47.689 ignored - wrong length
2014-05-14 11:39:47.691 card not matched
2014-05-14 11:39:47.692 trying driver 'iasecc'
2014-05-14 11:39:47.694 iasecc_match_card(3BFE1800008131FE458031815448534D31738021408107FA) called
2014-05-14 11:39:47.695 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.703 ATR try : 3B:7F:96:00:00:00:31:B8:64:40:70:14:10:73:94:01:80:82:90:00
2014-05-14 11:39:47.705 ignored - wrong length
2014-05-14 11:39:47.708 ATR try : 3B:DD:18:00:81:31:FE:45:80:F9:A0:00:00:00:77:01:08:00:07:90:00:FE
2014-05-14 11:39:47.709 ignored - wrong length
2014-05-14 11:39:47.710 ATR try : 3B:7D:13:00:00:4D:44:57:2D:49:41:53:2D:43:41:52:44:32
2014-05-14 11:39:47.711 ignored - wrong length
2014-05-14 11:39:47.720 ATR try : 3B:7F:18:00:00:00:31:B8:64:50:23:EC:C1:73:94:01:80:82:90:00
2014-05-14 11:39:47.721 ignored - wrong length
2014-05-14 11:39:47.723 ATR try : 3B:DF:18:FF:81:91:FE:1F:C3:00:31:B8:64:0C:01:EC:C1:73:94:01:80:82:90:00:B3
2014-05-14 11:39:47.725 ignored - wrong length
2014-05-14 11:39:47.726 ATR try : 3B:DC:18:FF:81:91:FE:1F:C3:80:73:C8:21:13:66:02:04:03:55:00:02:34
2014-05-14 11:39:47.727 ignored - wrong length
2014-05-14 11:39:47.735 ATR try : 3B:DC:18:FF:81:91:FE:1F:C3:80:73:C8:21:13:66:01:0B:03:52:00:05:38
2014-05-14 11:39:47.737 ignored - wrong length
2014-05-14 11:39:47.738 card not matched
2014-05-14 11:39:47.742 trying driver 'belpic'
2014-05-14 11:39:47.742 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.743 ATR try : 3B:98:13:40:0A:A5:03:01:01:01:AD:13:11
2014-05-14 11:39:47.745 ignored - wrong length
2014-05-14 11:39:47.753 ATR try : 3B:98:94:40:0A:A5:03:01:01:01:AD:13:10
2014-05-14 11:39:47.755 ignored - wrong length
2014-05-14 11:39:47.756 ATR try : 3B:98:94:40:FF:A5:03:01:01:01:AD:13:10
2014-05-14 11:39:47.758 ignored - wrong length
2014-05-14 11:39:47.759 trying driver 'ias'
2014-05-14 11:39:47.761 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.769 ATR try : 3B:65:00:00:D0:00:54:01:31
2014-05-14 11:39:47.771 ignored - wrong length
2014-05-14 11:39:47.772 ATR try : 3B:65:00:00:D0:00:54:01:32
2014-05-14 11:39:47.774 ignored - wrong length
2014-05-14 11:39:47.775 ATR try : 3B:95:95:40:FF:D0:00:54:01:31
2014-05-14 11:39:47.777 ignored - wrong length
2014-05-14 11:39:47.778 ATR try : 3B:95:95:40:FF:D0:00:54:01:32
2014-05-14 11:39:47.786 ignored - wrong length
2014-05-14 11:39:47.787 trying driver 'incrypto34'
2014-05-14 11:39:47.789 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.792 ATR try : 3b:ff:18:00:ff:81:31:fe:55:00:6b:02:09:02:00:01:01:01:44:53:44:10:31:80:92
2014-05-14 11:39:47.793 ignored - wrong length
2014-05-14 11:39:47.794 trying driver 'acos5'
2014-05-14 11:39:47.802 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.804 ATR try : 3b:be:18:00:00:41:05:10:00:00:00:00:00:00:00:00:00:90:00
2014-05-14 11:39:47.805 ignored - wrong length
2014-05-14 11:39:47.807 trying driver 'akis'
2014-05-14 11:39:47.809 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.810 ATR try : 3b:ba:11:00:81:31:fe:4d:55:45:4b:41:45:20:56:31:2e:30:ae
2014-05-14 11:39:47.811 ignored - wrong length
2014-05-14 11:39:47.820 trying driver 'entersafe'
2014-05-14 11:39:47.821 [opensc-pkcs11] card-entersafe.c:104:entersafe_match_card: called
2014-05-14 11:39:47.823 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.828 ATR try : 3b:0f:00:65:46:53:05:19:05:71:df:00:00:00:00:00:00
2014-05-14 11:39:47.830 ignored - wrong length
2014-05-14 11:39:47.837 ATR try : 3b:9f:95:81:31:fe:9f:00:65:46:53:05:30:06:71:df:00:00:00:80:6a:82:5e
2014-05-14 11:39:47.838 ignored - wrong length
2014-05-14 11:39:47.839 trying driver 'epass2003'
2014-05-14 11:39:47.842 [opensc-pkcs11] card-epass2003.c:954:epass2003_match_card: called
2014-05-14 11:39:47.843 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.844 ATR try : 3B:9F:95:81:31:FE:9F:00:66:46:53:05:10:00:11:71:df:00:00:00:6a:82:5e
2014-05-14 11:39:47.845 ignored - wrong length
2014-05-14 11:39:47.854 trying driver 'rutoken'
2014-05-14 11:39:47.855 [opensc-pkcs11] card-rutoken.c:101:rutoken_match_card: called
2014-05-14 11:39:47.857 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.858 ATR try : 3b:6f:00:ff:00:56:72:75:54:6f:6b:6e:73:30:20:00:00:90:00
2014-05-14 11:39:47.860 ignored - wrong length
2014-05-14 11:39:47.861 ATR try : 3b:6f:00:ff:00:56:75:61:54:6f:6b:6e:73:30:20:00:00:90:00
2014-05-14 11:39:47.870 ignored - wrong length
2014-05-14 11:39:47.871 [opensc-pkcs11] card-rutoken.c:107:rutoken_match_card: returning with: 0 (Success)
2014-05-14 11:39:47.872 trying driver 'rutoken_ecp'
2014-05-14 11:39:47.874 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.876 ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:20:45:43:50:A0
2014-05-14 11:39:47.877 ignored - wrong length
2014-05-14 11:39:47.879 ATR try : 3B:8B:01:52:75:74:6F:6B:65:6E:20:44:53:20:C1
2014-05-14 11:39:47.887 ignored - wrong length
2014-05-14 11:39:47.888 [opensc-pkcs11] card-rtecp.c:60:rtecp_match_card: returning with: 0 (Success)
2014-05-14 11:39:47.890 trying driver 'westcos'
2014-05-14 11:39:47.891 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.893 ATR try : 3F:69:00:00:00:64:01:00:00:00:80:90:00
2014-05-14 11:39:47.894 ignored - wrong length
2014-05-14 11:39:47.903 ATR try : 3B:95:94:80:1F:C3:80:73:C8:21:13:54
2014-05-14 11:39:47.904 ignored - wrong length
2014-05-14 11:39:47.906 trying driver 'myeid'
2014-05-14 11:39:47.907 trying driver 'sc-hsm'
2014-05-14 11:39:47.908 ATR     : 3b:fe:18:00:00:81:31:fe:45:80:31:81:54:48:53:4d:31:73:80:21:40:81:07:fa
2014-05-14 11:39:47.910 ATR try : 3B:FE:18:00:00:81:31:FE:45:80:31:81:54:48:53:4D:31:73:80:21:40:81:07:FA
2014-05-14 11:39:47.911 matched: SmartCard-HSM
2014-05-14 11:39:47.920 [opensc-pkcs11] card-sc-hsm.c:949:sc_hsm_init: called
2014-05-14 11:39:47.921 card info name:'SmartCard-HSM', type:26000, flags:0x0, max_send/recv_size:1431/0
2014-05-14 11:39:47.923 [opensc-pkcs11] card.c:1199:sc_card_sm_check: called
2014-05-14 11:39:47.924 [opensc-pkcs11] card.c:1204:sc_card_sm_check: returning with: 0 (Success)
2014-05-14 11:39:47.925 [opensc-pkcs11] card.c:243:sc_connect_card: returning with: 0 (Success)
2014-05-14 11:39:47.927 [opensc-pkcs11] dir.c:140:sc_enum_apps: called
2014-05-14 11:39:47.928 called; type=2, path=3f002f00
2014-05-14 11:39:47.936 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:47.938 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:47.939 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
2014-05-14 11:39:47.941 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:47.942 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:47.944 CLA:0, INS:A4, P1:8, P2:0, data(2) 002EF3FA
2014-05-14 11:39:47.945 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:47.953
Outgoing APDU data [    8 bytes] =====================================
00 A4 08 00 02 2F 00 00 ...../..
======================================================================
2014-05-14 11:39:47.955 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:47.968
Incoming APDU data [    2 bytes] =====================================
6A 86 j.
======================================================================
2014-05-14 11:39:47.969 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:47.970 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:47.976 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:47.978 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock: called
2014-05-14 11:39:47.986 Incorrect parameters P1-P2
2014-05-14 11:39:47.987 [opensc-pkcs11] iso7816.c:493:iso7816_select_file: returning with: -1205 (Incorrect parameters in APDU)
2014-05-14 11:39:47.989 [opensc-pkcs11] card.c:632:sc_select_file: 'SELECT' error: -1205 (Incorrect parameters in APDU)
2014-05-14 11:39:47.990 [opensc-pkcs11] dir.c:150:sc_enum_apps: Cannot select EF.DIR file: -1205 (Incorrect parameters in APDU)
2014-05-14 11:39:47.991 OMNIKEY CardMan 3x21 0: Detecting Framework. 0 on-card applications
2014-05-14 11:39:47.993 OMNIKEY CardMan 3x21 0: generic application <none>
2014-05-14 11:39:47.994 OMNIKEY CardMan 3x21 0: Detected framework 0. Creating tokens.
2014-05-14 11:39:47.996 OMNIKEY CardMan 3x21 0: Try to bind 'generic' token.
2014-05-14 11:39:48.004 Bind PKCS#15 '<anonymous>' application
2014-05-14 11:39:48.005 [opensc-pkcs11] pkcs15.c:1134:sc_pkcs15_bind: called
2014-05-14 11:39:48.007 application(aid:'empty')
2014-05-14 11:39:48.009 PKCS#15 options: use_file_cache=0 use_pin_cache=1 pin_cache_counter=10 pin_cache_ignore_user_consent=0
2014-05-14 11:39:48.012 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.012 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
2014-05-14 11:39:48.014 [opensc-pkcs11] pkcs15-syn.c:130:sc_pkcs15_bind_synthetic: called
2014-05-14 11:39:48.022 no emulator list in config file, trying all builtin emulators
2014-05-14 11:39:48.023 trying westcos
2014-05-14 11:39:48.026 sc_pkcs15_init_func_ex westcos
2014-05-14 11:39:48.027 westcos_detect_card (SmartCard-HSM)
2014-05-14 11:39:48.028 trying openpgp
2014-05-14 11:39:48.030 trying infocamere
2014-05-14 11:39:48.039 trying starcert
2014-05-14 11:39:48.040 trying tcos
2014-05-14 11:39:48.043 trying esteid
2014-05-14 11:39:48.044 trying itacns
2014-05-14 11:39:48.045 [opensc-pkcs11] pkcs15-itacns.c:857:sc_pkcs15emu_itacns_init_ex: called
2014-05-14 11:39:48.046 trying postecert
2014-05-14 11:39:48.055 trying PIV-II
2014-05-14 11:39:48.057 [opensc-pkcs11] pkcs15-piv.c:1010:sc_pkcs15emu_piv_init_ex: called
2014-05-14 11:39:48.058 [opensc-pkcs11] pkcs15-piv.c:231:piv_detect_card: called
2014-05-14 11:39:48.060 trying gemsafeGPK
2014-05-14 11:39:48.061 Entering sc_pkcs15emu_gemsafeGPK_init_ex
2014-05-14 11:39:48.063 [opensc-pkcs11] pkcs15-gemsafeGPK.c:166:gemsafe_detect_card: called
2014-05-14 11:39:48.071 trying gemsafeV1
2014-05-14 11:39:48.073 trying actalis
2014-05-14 11:39:48.074 trying atrust-acos
2014-05-14 11:39:48.076 trying tccardos
2014-05-14 11:39:48.077 trying entersafe
2014-05-14 11:39:48.078 [opensc-pkcs11] pkcs15-esinit.c:80:sc_pkcs15emu_entersafe_init_ex: called
2014-05-14 11:39:48.080 [opensc-pkcs11] pkcs15-esinit.c:36:entersafe_detect_card: called
2014-05-14 11:39:48.088 trying pteid
2014-05-14 11:39:48.090 trying oberthur
2014-05-14 11:39:48.091 [opensc-pkcs11] pkcs15-oberthur.c:1045:sc_pkcs15emu_oberthur_init_ex: called
2014-05-14 11:39:48.093 [opensc-pkcs11] pkcs15-oberthur.c:1032:oberthur_detect_card: called
2014-05-14 11:39:48.094 [opensc-pkcs11] pkcs15-oberthur.c:1034:oberthur_detect_card: returning with: -1413 (Unsupported card)
2014-05-14 11:39:48.096 [opensc-pkcs11] pkcs15-oberthur.c:1055:sc_pkcs15emu_oberthur_init_ex: returning with: -1413 (Unsupported card)
2014-05-14 11:39:48.104 trying sc-hsm
2014-05-14 11:39:48.105 [opensc-pkcs11] pkcs15-sc-hsm.c:540:sc_pkcs15emu_sc_hsm_init: called
2014-05-14 11:39:48.106 called; type=1, path=e82b0601040181c31f0201::
2014-05-14 11:39:48.108 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.109 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.111 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.112 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.121 CLA:0, INS:A4, P1:4, P2:0, data(11) 002EE114
2014-05-14 11:39:48.122 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.124
Outgoing APDU data [   17 bytes] =====================================
00 A4 04 00 0B E8 2B 06 01 04 01 81 C3 1F 02 01 ......+.........
00                                              .
======================================================================
2014-05-14 11:39:48.125 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.147
Incoming APDU data [   11 bytes] =====================================
6F 07 82 01 78 85 02 01 02 90 00 o...x......
======================================================================
2014-05-14 11:39:48.147 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.148 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.154 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.155 processing FCI bytes
2014-05-14 11:39:48.157   shareable: yes
2014-05-14 11:39:48.159   type: DF
2014-05-14 11:39:48.167   EF structure: 0
2014-05-14 11:39:48.168 [opensc-pkcs11] card.c:638:sc_select_file: returning with: 0 (Success)
2014-05-14 11:39:48.170 called; type=0, path=2f02
2014-05-14 11:39:48.171 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.172 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.174 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.176 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.176 CLA:0, INS:A4, P1:0, P2:0, data(2) 002EE114
2014-05-14 11:39:48.184 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.187
Outgoing APDU data [    8 bytes] =====================================
00 A4 00 00 02 2F 02 00 ...../..
======================================================================
2014-05-14 11:39:48.188 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.215
Incoming APDU data [   15 bytes] =====================================
6F 0B 80 02 01 CE 82 01 01 83 02 2F 02 90 00 o........../...
======================================================================
2014-05-14 11:39:48.215 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.216 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.226 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.227 processing FCI bytes
2014-05-14 11:39:48.228   file identifier: 0x2F02
2014-05-14 11:39:48.230   bytes in file: 462
2014-05-14 11:39:48.231   shareable: no
2014-05-14 11:39:48.233   type: working EF
2014-05-14 11:39:48.234   EF structure: 1
2014-05-14 11:39:48.235 [opensc-pkcs11] card.c:638:sc_select_file: returning with: 0 (Success)
2014-05-14 11:39:48.237 called; 512 bytes at index 0
2014-05-14 11:39:48.238 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.240 called; 256 bytes at index 0
2014-05-14 11:39:48.241 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.243 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.245 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.246 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.255 CLA:0, INS:B1, P1:0, P2:0, data(4) 002EE000
2014-05-14 11:39:48.256 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.258
Outgoing APDU data [   10 bytes] =====================================
00 B1 00 00 04 54 02 00 00 00 .....T....
======================================================================
2014-05-14 11:39:48.260 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.300
Incoming APDU data [  258 bytes] =====================================
7F 21 81 E4 7F 4E 81 9D 5F 29 01 00 42 0D 44 45 .!...N.._)..B.DE
44 49 43 4D 30 31 30 30 30 30 31 7F 49 4F 06 0A DICM0100001.IO..
04 00 7F 00 07 02 02 02 02 03 86 41 04 33 72 EF ...........A.3r.
0F 27 89 8D BA 93 68 94 D7 2A 34 E5 76 F1 98 16 .'....h..*4.v...
00 18 90 C3 2A F4 37 64 62 BA 52 74 AD 22 00 DA ....*.7db.Rt."..
54 B3 8A E5 B6 42 FE 65 4A A9 7B 7B B6 D5 F4 1C T....B.eJ.{{....
EE 38 FE 60 A7 21 2F DA D8 70 B7 36 01 5F 20 10 .8.`.!/..p.6._ .
44 45 43 4D 30 31 30 32 31 31 30 30 30 30 30 30 DECM010211000000
7F 4C 10 06 0B 2B 06 01 04 01 81 C3 1F 03 01 01 .L...+..........
53 01 00 5F 25 06 01 04 00 02 01 01 5F 24 06 02 S.._%......._$..
01 00 09 03 00 5F 37 40 3E 6E 27 91 88 92 BD D9 ....._7@>n'.....
7E D2 0C 4B D6 47 D8 1D 6E C5 54 1F 1B 42 B4 FB ~..K.G..n.T..B..
6D DD 94 65 64 10 46 76 48 F2 F6 1B 9A 86 40 DC m..ed.FvH.....@.
AF 69 10 C8 33 9E 61 A9 83 CE 2F D1 18 DD 6A D9 .i..3.a.../...j.
8F A0 5B 32 94 C9 12 27 7F 21 81 E2 7F 4E 81 9B ..[2...'.!...N..
5F 29 01 00 42 0E 44 45 53 52 43 41 43 43 31 30 _)..B.DESRCACC10
90 00                                           ..
======================================================================
2014-05-14 11:39:48.307 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.308 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.330 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.332 [opensc-pkcs11] card-sc-hsm.c:226:sc_hsm_read_binary: returning with: 256
2014-05-14 11:39:48.333 [opensc-pkcs11] card.c:485:sc_read_binary: returning with: 256
2014-05-14 11:39:48.335 called; 256 bytes at index 256
2014-05-14 11:39:48.336 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.338 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.339 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.341 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.342 CLA:0, INS:B1, P1:0, P2:0, data(4) 002EE000
2014-05-14 11:39:48.344 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.345
Outgoing APDU data [   10 bytes] =====================================
00 B1 00 00 04 54 02 01 00 00 .....T....
======================================================================
2014-05-14 11:39:48.354 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.388
Incoming APDU data [  208 bytes] =====================================
30 30 30 31 7F 49 4F 06 0A 04 00 7F 00 07 02 02 0001.IO.........
02 02 03 86 41 04 6A C5 3F C8 FB FC 9E A6 4F 92 ....A.j.?.....O.
7E E3 75 94 58 B2 6B 20 B8 B0 FA CD CE D9 1B F0 ~.u.X.k ........
4C 74 1E 05 1A AD 51 22 14 20 E0 42 63 38 BC CF Lt....Q". .Bc8..
28 70 29 97 C3 C6 50 70 DB 03 7F 65 6A 85 D8 E4 (p)...Pp...ej...
03 04 65 0C DB CF 5F 20 0D 44 45 44 49 43 4D 30 ..e..._ .DEDICM0
31 30 30 30 30 31 7F 4C 10 06 0B 2B 06 01 04 01 100001.L...+....
81 C3 1F 03 01 01 53 01 80 5F 25 06 01 03 01 00 ......S.._%.....
00 01 5F 24 06 02 01 00 09 03 00 5F 37 40 78 CB .._$......._7@x.
38 DA 55 DF B7 25 3C FC 01 71 EA CD 17 27 B6 54 8.U..%<..q...'.T
CF 74 F5 41 EB 5E D1 30 E3 E5 ED C8 86 C2 94 D4 .t.A.^.0........
97 AF 94 F9 B0 A5 5A 16 8E 2C 9D 3B 5A 28 4E D2 ......Z..,.;Z(N.
9E 1A C9 59 A9 6D 50 35 09 7F 23 1E D5 C4 90 00 ...Y.mP5..#.....
======================================================================
2014-05-14 11:39:48.391 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.393 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.422 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.423 [opensc-pkcs11] card-sc-hsm.c:226:sc_hsm_read_binary: returning with: 206
2014-05-14 11:39:48.424 [opensc-pkcs11] card.c:485:sc_read_binary: returning with: 206
2014-05-14 11:39:48.426 called; 50 bytes at index 462
2014-05-14 11:39:48.428 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.430 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.431 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.433 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.434 CLA:0, INS:B1, P1:0, P2:0, data(4) 002EE000
2014-05-14 11:39:48.436 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.437
Outgoing APDU data [   10 bytes] =====================================
00 B1 00 00 04 54 02 01 CE 32 .....T...2
======================================================================
2014-05-14 11:39:48.439 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.465
Incoming APDU data [    2 bytes] =====================================
62 82 b.
======================================================================
2014-05-14 11:39:48.465 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.466 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.472 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.474 End of file/record reached before reading Le bytes
2014-05-14 11:39:48.475 [opensc-pkcs11] card-sc-hsm.c:226:sc_hsm_read_binary: returning with: 0 (Success)
2014-05-14 11:39:48.477 [opensc-pkcs11] card.c:485:sc_read_binary: returning with: 0 (Success)
2014-05-14 11:39:48.478 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.480 [opensc-pkcs11] card.c:478:sc_read_binary: returning with: 462
2014-05-14 11:39:48.488 called, left=462, depth 0
2014-05-14 11:39:48.490 Looking for 'certificate', tag 0x11001f21
2014-05-14 11:39:48.492 decoding 'certificate'
2014-05-14 11:39:48.493  called, left=228, depth 1
2014-05-14 11:39:48.495 Looking for 'certificateBody', tag 0x11001f4e
2014-05-14 11:39:48.496  decoding 'certificateBody'
2014-05-14 11:39:48.497   called, left=157, depth 2
2014-05-14 11:39:48.507 Looking for 'certificateProfileIdentifier', tag 0x10001f29
2014-05-14 11:39:48.507   decoding 'certificateProfileIdentifier'
2014-05-14 11:39:48.509   decoding 'certificateProfileIdentifier' returned 0
2014-05-14 11:39:48.512 Looking for 'certificationAuthorityReference', tag 0x10000002
2014-05-14 11:39:48.512   decoding 'certificationAuthorityReference'
2014-05-14 11:39:48.512 Looking for 'publicKey', tag 0x11001f49
2014-05-14 11:39:48.513   decoding 'publicKey'
2014-05-14 11:39:48.513    called, left=79, depth 3
2014-05-14 11:39:48.513 Looking for 'publicKeyOID', tag 0x6
2014-05-14 11:39:48.513    decoding 'publicKeyOID'
2014-05-14 11:39:48.513 Looking for 'primeOrModulus', tag 0x20000001, OPTIONAL
2014-05-14 11:39:48.513 not present
2014-05-14 11:39:48.513 Looking for 'coefficientAorExponent', tag 0x20000002, OPTIONAL
2014-05-14 11:39:48.513 not present
2014-05-14 11:39:48.513 Looking for 'coefficientB', tag 0x20000003, OPTIONAL
2014-05-14 11:39:48.513 not present
2014-05-14 11:39:48.513 Looking for 'basePointG', tag 0x20000004, OPTIONAL
2014-05-14 11:39:48.513 not present
2014-05-14 11:39:48.514 Looking for 'order', tag 0x20000005, OPTIONAL
2014-05-14 11:39:48.514 not present
2014-05-14 11:39:48.514 Looking for 'publicPoint', tag 0x20000006, OPTIONAL
2014-05-14 11:39:48.514    decoding 'publicPoint'
2014-05-14 11:39:48.529 Looking for 'cofactor', tag 0x20000007, OPTIONAL
2014-05-14 11:39:48.529 not present
2014-05-14 11:39:48.529 Looking for 'modulusSize', tag 0x2, OPTIONAL
2014-05-14 11:39:48.529 not present
2014-05-14 11:39:48.529 [opensc-pkcs11] asn1.c:1464:asn1_decode: returning with: 0 (Success)
2014-05-14 11:39:48.534 Looking for 'certificateHolderReference', tag 0x10001f20
2014-05-14 11:39:48.535   decoding 'certificateHolderReference'
2014-05-14 11:39:48.538 [opensc-pkcs11] asn1.c:1464:asn1_decode: returning with: 0 (Success)
2014-05-14 11:39:48.573 Looking for 'signature', tag 0x10001f37
2014-05-14 11:39:48.574  decoding 'signature'
2014-05-14 11:39:48.576 [opensc-pkcs11] asn1.c:1464:asn1_decode: returning with: 0 (Success)
2014-05-14 11:39:48.578 [opensc-pkcs11] asn1.c:1464:asn1_decode: returning with: 0 (Success)
2014-05-14 11:39:48.580 [opensc-pkcs11] pkcs15-sc-hsm.c:165:sc_pkcs15emu_sc_hsm_decode_cvc: returning with: 0 (Success)
2014-05-14 11:39:48.581 [opensc-pkcs11] card.c:385:sc_list_files: called
2014-05-14 11:39:48.582 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.584 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.585 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.587 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.587 CLA:80, INS:58, P1:0, P2:0, data(0) 00000000
2014-05-14 11:39:48.588 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.591
Outgoing APDU data [    7 bytes] =====================================
80 58 00 00 00 00 00 .X.....
======================================================================
2014-05-14 11:39:48.592 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.621
Incoming APDU data [    6 bytes] =====================================
2F 02 CC 00 90 00 /.....
======================================================================
2014-05-14 11:39:48.621 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.622 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.627 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.628 [opensc-pkcs11] card-sc-hsm.c:320:sc_hsm_list_files: returning with: 4
2014-05-14 11:39:48.630 [opensc-pkcs11] card.c:391:sc_list_files: returning with: 4
2014-05-14 11:39:48.631 called; type=0, path=c400
2014-05-14 11:39:48.632 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.633 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.633 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.635 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.635 CLA:0, INS:A4, P1:0, P2:0, data(2) 002EC884
2014-05-14 11:39:48.637 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.637
Outgoing APDU data [    8 bytes] =====================================
00 A4 00 00 02 C4 00 00 ........
======================================================================
2014-05-14 11:39:48.640 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:48.666
Incoming APDU data [    2 bytes] =====================================
6A 82 j.
======================================================================
2014-05-14 11:39:48.666 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.667 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:48.675 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.676 File not found
2014-05-14 11:39:48.678 [opensc-pkcs11] iso7816.c:493:iso7816_select_file: returning with: -1201 (File not found)
2014-05-14 11:39:48.679 [opensc-pkcs11] card.c:632:sc_select_file: 'SELECT' error: -1201 (File not found)
2014-05-14 11:39:48.680 [opensc-pkcs11] pkcs15-sc-hsm.c:667:sc_pkcs15emu_sc_hsm_init: returning with: 0 (Success)
2014-05-14 11:39:48.681 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:48.689 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock: called
2014-05-14 11:39:48.690 [opensc-pkcs11] pkcs15.c:1194:sc_pkcs15_bind: returning with: 0 (Success)
2014-05-14 11:39:48.691 OMNIKEY CardMan 3x21 0: Creating 'generic' token.
2014-05-14 11:39:48.693 create PKCS#15 tokens; fws:00AF65E8,00000000,00000000
2014-05-14 11:39:48.694 CreateSlotsFlags: 0x8
2014-05-14 11:39:48.696 Use FW data with index 0; fw_data->p15_card 00AF4838
2014-05-14 11:39:48.696 [opensc-pkcs11] pkcs15.c:1617:sc_pkcs15_find_pin_by_flags: called
2014-05-14 11:39:48.697 Find PIN flags:0x10, mask:0xD2, index:-1
2014-05-14 11:39:48.699 [opensc-pkcs11] pkcs15.c:1644:sc_pkcs15_find_pin_by_flags: returning with: -1407 (Requested object not found)
2014-05-14 11:39:48.706 [opensc-pkcs11] pkcs15.c:1617:sc_pkcs15_find_pin_by_flags: called
2014-05-14 11:39:48.708 Find PIN flags:0x12, mask:0xD2, index:-1
2014-05-14 11:39:48.709 [opensc-pkcs11] pkcs15.c:1641:sc_pkcs15_find_pin_by_flags: returning with: 0 (Success)
2014-05-14 11:39:48.711 Flags:0x8; Auth User/Sign PINs 00AF6B30/00000000
2014-05-14 11:39:48.712 Found 0 RSA private keys
2014-05-14 11:39:48.713 Found 0 RSA public keys
2014-05-14 11:39:48.714 Found 0 EC private keys
2014-05-14 11:39:48.715 Found 0 EC public keys
2014-05-14 11:39:48.723 Found 0 GOSTR3410 private keys
2014-05-14 11:39:48.725 Found 0 GOSTR3410 public keys
2014-05-14 11:39:48.726 Found 0 certificates
2014-05-14 11:39:48.728 Found 0 data objects
2014-05-14 11:39:48.729 found 0 FW objects
2014-05-14 11:39:48.730 Found 0 FW objects objects
2014-05-14 11:39:48.731 Found 2 authentication objects
2014-05-14 11:39:48.732 Found authentication object 'UserPIN'
2014-05-14 11:39:48.741 Allocated slot 0x5 for card in reader OMNIKEY CardMan 3x21 0
2014-05-14 11:39:48.743 Initialized token 'SmartCard-HSM (UserPIN)' in slot 0x5
2014-05-14 11:39:48.744 Add objects related to PIN('UserPIN',ID:01)
2014-05-14 11:39:48.745 0 public objects to process
2014-05-14 11:39:48.747 All tokens created
2014-05-14 11:39:48.748 OMNIKEY CardMan 3x21 0: Detection ended
2014-05-14 11:39:48.749 C_Initialize() = CKR_OK
2014-05-14 11:39:48.757 C_GetSlotList(token=0, plug-n-play)
2014-05-14 11:39:48.758 [opensc-pkcs11] reader-pcsc.c:948:pcsc_detect_readers: called
2014-05-14 11:39:48.759 Probing pcsc readers
2014-05-14 11:39:48.764 [opensc-pkcs11] reader-pcsc.c:1101:pcsc_detect_readers: returning with: 0 (Success)
2014-05-14 11:39:48.764 CASTLES EZMINI 0: Detecting smart card
2014-05-14 11:39:48.765 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:48.773 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:48.774 CASTLES EZMINI 0 check
2014-05-14 11:39:48.776 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:48.777 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:48.778 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:48.779 CASTLES EZMINI 0: card absent
2014-05-14 11:39:48.781 CASTLES EZMINI 0: card removed
2014-05-14 11:39:48.782 slot_token_removed(0x1)
2014-05-14 11:39:48.790 real C_CloseAllSessions(0x1) 0
2014-05-14 11:39:48.791 slot_token_removed(0x2)
2014-05-14 11:39:48.792 real C_CloseAllSessions(0x2) 0
2014-05-14 11:39:48.793 slot_token_removed(0x3)
2014-05-14 11:39:48.795 real C_CloseAllSessions(0x3) 0
2014-05-14 11:39:48.796 slot_token_removed(0x4)
2014-05-14 11:39:48.796 real C_CloseAllSessions(0x4) 0
2014-05-14 11:39:48.797 OMNIKEY CardMan 3x21 0: Detecting smart card
2014-05-14 11:39:48.800 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:48.808 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:48.809 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:48.810 current  state: 0x00010122
2014-05-14 11:39:48.812 previous state: 0x00010022
2014-05-14 11:39:48.816 card present
2014-05-14 11:39:48.817 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 5
2014-05-14 11:39:48.824 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 5
2014-05-14 11:39:48.825 OMNIKEY CardMan 3x21 0: Detection ended
2014-05-14 11:39:48.826 was only a size inquiry (3)
2014-05-14 11:39:48.829 C_GetSlotList(token=0, refresh)
2014-05-14 11:39:48.830 CASTLES EZMINI 0: Detecting smart card
2014-05-14 11:39:48.832 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:48.832 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:48.841 CASTLES EZMINI 0 check
2014-05-14 11:39:48.842 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:48.843 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:48.846 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:48.848 CASTLES EZMINI 0: card absent
2014-05-14 11:39:48.849 CASTLES EZMINI 0: card removed
2014-05-14 11:39:48.857 slot_token_removed(0x1)
2014-05-14 11:39:48.858 real C_CloseAllSessions(0x1) 0
2014-05-14 11:39:48.859 slot_token_removed(0x2)
2014-05-14 11:39:48.860 real C_CloseAllSessions(0x2) 0
2014-05-14 11:39:48.861 slot_token_removed(0x3)
2014-05-14 11:39:48.863 real C_CloseAllSessions(0x3) 0
2014-05-14 11:39:48.864 slot_token_removed(0x4)
2014-05-14 11:39:48.865 real C_CloseAllSessions(0x4) 0
2014-05-14 11:39:48.866 OMNIKEY CardMan 3x21 0: Detecting smart card
2014-05-14 11:39:48.874 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:48.875 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:48.877 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:48.878 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:48.879 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 5
2014-05-14 11:39:48.880 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 5
2014-05-14 11:39:48.882 OMNIKEY CardMan 3x21 0: Detection ended
2014-05-14 11:39:48.883 returned 3 slots
2014-05-14 11:39:48.891 C_GetSlotInfo(0xffffffff)
2014-05-14 11:39:48.893 C_GetSlotInfo(0xffffffff) = CKR_OK
2014-05-14 11:39:48.894 C_GetSlotInfo(0x1)
2014-05-14 11:39:48.896 CASTLES EZMINI 0: Detecting smart card
2014-05-14 11:39:48.896 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:48.898 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:48.899 CASTLES EZMINI 0 check
2014-05-14 11:39:48.907 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:48.908 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:48.909 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 0 (Success)
2014-05-14 11:39:48.911 CASTLES EZMINI 0: card absent
2014-05-14 11:39:48.912 CASTLES EZMINI 0: card removed
2014-05-14 11:39:48.913 slot_token_removed(0x1)
2014-05-14 11:39:48.914 real C_CloseAllSessions(0x1) 0
2014-05-14 11:39:48.916 slot_token_removed(0x2)
2014-05-14 11:39:48.924 real C_CloseAllSessions(0x2) 0
2014-05-14 11:39:48.925 slot_token_removed(0x3)
2014-05-14 11:39:48.926 real C_CloseAllSessions(0x3) 0
2014-05-14 11:39:48.928 slot_token_removed(0x4)
2014-05-14 11:39:48.929 real C_CloseAllSessions(0x4) 0
2014-05-14 11:39:48.930 C_GetSlotInfo(0x1) = CKR_OK
2014-05-14 11:39:48.931 C_GetSlotInfo(0x5)
2014-05-14 11:39:48.933 OMNIKEY CardMan 3x21 0: Detecting smart card
2014-05-14 11:39:48.940 [opensc-pkcs11] sc.c:231:sc_detect_card_presence: called
2014-05-14 11:39:48.942 [opensc-pkcs11] reader-pcsc.c:370:pcsc_detect_card_presence: called
2014-05-14 11:39:48.943 OMNIKEY CardMan 3x21 0 check
2014-05-14 11:39:48.944 [opensc-pkcs11] reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
2014-05-14 11:39:48.945 [opensc-pkcs11] reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 5
2014-05-14 11:39:48.946 [opensc-pkcs11] sc.c:236:sc_detect_card_presence: returning with: 5
2014-05-14 11:39:48.948 OMNIKEY CardMan 3x21 0: Detection ended
2014-05-14 11:39:48.949 C_GetSlotInfo(0x5) = CKR_OK
Using slot 2 with a present token (0x5)
2014-05-14 11:39:48.958 C_OpenSession(0x5)
2014-05-14 11:39:48.959 C_OpenSession handle: 0xaef760
2014-05-14 11:39:48.960 C_OpenSession() = CKR_OK
2014-05-14 11:39:48.962 C_GetTokenInfo(5)
2014-05-14 11:39:48.963 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
2014-05-14 11:39:48.964 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:48.965 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:48.967 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
2014-05-14 11:39:48.974 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:48.976 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:48.977 CLA:0, INS:20, P1:0, P2:81, data(0) 00000000
2014-05-14 11:39:48.978 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:48.980
Outgoing APDU data [    4 bytes] =====================================
00 20 00 81 . ..
======================================================================
2014-05-14 11:39:48.982 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:49.014
Incoming APDU data [    2 bytes] =====================================
63 C3 c.
======================================================================
2014-05-14 11:39:49.015 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:49.016 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:49.020 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:49.021 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock: called
2014-05-14 11:39:49.023 Verification failed (remaining tries: 3)
2014-05-14 11:39:49.024 [opensc-pkcs11] card-sc-hsm.c:164:sc_hsm_pin_info: returning with: 0 (Success)
2014-05-14 11:39:49.025 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: 0 (Success)
2014-05-14 11:39:49.026 C_Login(0xaef760, 0)
2014-05-14 11:39:49.028 [opensc-pkcs11] pkcs15-pin.c:293:sc_pkcs15_verify_pin: called
2014-05-14 11:39:49.029 PIN(001820EC;len:16)
2014-05-14 11:39:49.030 Auth(type:0;method:1)
2014-05-14 11:39:49.031 PIN value validated
2014-05-14 11:39:49.033 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:49.041 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
2014-05-14 11:39:49.042 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
2014-05-14 11:39:49.043 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: 0 (Success)
2014-05-14 11:39:49.044 PIN cmd result 0
2014-05-14 11:39:49.045 [opensc-pkcs11] pkcs15-pin.c:590:sc_pkcs15_pincache_add: called
2014-05-14 11:39:49.047 cannot lock memory, sensitive data may be paged to disk
2014-05-14 11:39:49.048 PIN(SOPIN) cached
2014-05-14 11:39:49.049 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:49.057 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock: called
2014-05-14 11:39:49.058 [opensc-pkcs11] pkcs15-pin.c:372:sc_pkcs15_verify_pin: returning with: 0 (Success)
2014-05-14 11:39:49.060 PKCS15 verify PIN returned 0
2014-05-14 11:39:49.061 C_GetTokenInfo(5)
2014-05-14 11:39:49.062 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
2014-05-14 11:39:49.063 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
2014-05-14 11:39:49.064 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:49.066 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
2014-05-14 11:39:49.067 [opensc-pkcs11] apdu.c:654:sc_transmit: called
2014-05-14 11:39:49.076 [opensc-pkcs11] apdu.c:509:sc_single_transmit: called
2014-05-14 11:39:49.077 CLA:0, INS:20, P1:0, P2:81, data(0) 00000000
2014-05-14 11:39:49.078 reader 'OMNIKEY CardMan 3x21 0'
2014-05-14 11:39:49.079
Outgoing APDU data [    4 bytes] =====================================
00 20 00 81 . ..
======================================================================
2014-05-14 11:39:49.081 [opensc-pkcs11] reader-pcsc.c:182:pcsc_internal_transmit: called
2014-05-14 11:39:49.114
Incoming APDU data [    2 bytes] =====================================
63 C3 c.
======================================================================
2014-05-14 11:39:49.114 [opensc-pkcs11] apdu.c:524:sc_single_transmit: returning with: 0 (Success)
2014-05-14 11:39:49.115 [opensc-pkcs11] apdu.c:676:sc_transmit: returning with: 0 (Success)
2014-05-14 11:39:49.122 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:49.124 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock: called
2014-05-14 11:39:49.125 Verification failed (remaining tries: 3)
2014-05-14 11:39:49.126 [opensc-pkcs11] card-sc-hsm.c:164:sc_hsm_pin_info: returning with: 0 (Success)
2014-05-14 11:39:49.127 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: 0 (Success)
2014-05-14 11:39:49.132 Changing PIN (session 0xaef760; login user 0)
2014-05-14 11:39:49.133 Change 'UserPIN' (ref:129,type:0)
2014-05-14 11:39:49.138 [opensc-pkcs11] card.c:315:sc_lock: called
2014-05-14 11:39:49.139 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
2014-05-14 11:39:49.141 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
2014-05-14 11:39:49.142 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: 0 (Success)
2014-05-14 11:39:49.143 [opensc-pkcs11] pkcs15-pin.c:590:sc_pkcs15_pincache_add: called
2014-05-14 11:39:49.144 cannot lock memory, sensitive data may be paged to disk
2014-05-14 11:39:49.146 PIN(SOPIN) cached
2014-05-14 11:39:49.155 [opensc-pkcs11] card.c:353:sc_unlock: called
2014-05-14 11:39:49.155 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock: called
2014-05-14 11:39:49.156 PIN change returns 0
2014-05-14 11:39:49.158 libopensc return value: 0 (Success)
PIN successfully changed

============================================================================================================================================
============================================================================================================================================

Best regards,
Marjan



On 14 May 2014 11:27, Andreas Schwier <[hidden email]> wrote:
Dear Marjan,

can you enable the trace output in opensc to see what APDU is send to
the SmartCard-HSM ?

Is the Windows version a recent nightly build or from the SmartCard-HSM
starterkit [1] ?

Andreas

[1] http://www.cardcontact.de/download/sc-hsm-starterkit.zip


On 05/14/2014 11:07 AM, marjan stankovic wrote:
> Hello all,
>
>
>
> We have bought SmartCard HSMs for testing and evaluating (in March this
> year). So far it fits our needs.
>
>
> But we have one strange problem in Windows 7. Is it possible that SO-PIN
> change does not work?
>
> This is what I can see:
>
>
>
> pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login
> --login-type so --so-pin 1234567812345678 --change-pin --new-pin
> 1234567812345678
>
> Using slot 1 with a present token (0x1)
>
> 2014-05-14 10:20:20.798 cannot lock memory, sensitive data may be paged to
> disk
>
> 2014-05-14 10:20:20.832 cannot lock memory, sensitive data may be paged to
> disk
>
> PIN successfully changed
>
>
>
> no matter what values I provide for SO-PIN. Afterwards, I see that SO-PIN
> was not changed (i.e. if I try to reinitialize the card with "new SO-PIN"
> it will raise invalid pin error).
>
>
>
> We are using OpenSC v0.13.0.
>
> In Ubuntu it works fine. This seems to be a problem in OpenSC?
>
>
>
> Best regards,
>
> Marjan Stankovic
>
>
>
> ------------------------------------------------------------------------------
> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
> Instantly run your Selenium tests across 300+ browser/OS combos.
> Get unparalleled scalability from the best Selenium testing platform available
> Simple to use. Nothing to install. Get started now for free."
> http://p.sf.net/sfu/SauceLabs
>
>
>
> _______________________________________________
> Opensc-devel mailing list
> [hidden email]
> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>


------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel



------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: SmartCard HSM SO-PIN change

marjan stankovic
In reply to this post by Andreas Schwier (ML)
Hi Andreas,

This solved the issue. I downloaded SmartCard-HSM Starterkit from http://www.cardcontact.de/download/sc-hsm-starterkit.zip.

But why is release 0.13.0 still sitting on https://github.com/OpenSC/OpenSC/wiki as last stable and not updated?

Regards,
Marjan


On 14 May 2014 12:00, Andreas Schwier <[hidden email]> wrote:
Hi Marjan,

that Windows version is quite outdated (from 2012). Better try a recent
nightly build or the version from our Starterkit.

Andreas

On 05/14/2014 11:46 AM, marjan stankovic wrote:
> Hallo Andreas,
>
> I used OpenSC from
> http://sourceforge.net/projects/opensc/files/OpenSC/opensc-0.13.0/ (both 32
> and 64 bit).
>
> This is the output when I provide wrong SO-PIN:
>
> ============================================================================================================================================
> ============================================================================================================================================
> C:\Users\mst>pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll
> --login --login-type so --so-pin 1234567812345679 --change-pin --new-pin
> 1234567812345678
> 2014-05-14 11:39:46.764 ===================================
> 2014-05-14 11:39:46.765 opensc version: 0.13.0
...
> 2014-05-14 11:39:48.931 C_GetSlotInfo(0x5)
> 2014-05-14 11:39:48.933 OMNIKEY CardMan 3x21 0: Detecting smart card
> 2014-05-14 11:39:48.940 [opensc-pkcs11] sc.c:231:sc_detect_card_presence:
> called
> 2014-05-14 11:39:48.942 [opensc-pkcs11]
> reader-pcsc.c:370:pcsc_detect_card_presence: called
> 2014-05-14 11:39:48.943 OMNIKEY CardMan 3x21 0 check
> 2014-05-14 11:39:48.944 [opensc-pkcs11]
> reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
> 2014-05-14 11:39:48.945 [opensc-pkcs11]
> reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 5
> 2014-05-14 11:39:48.946 [opensc-pkcs11] sc.c:236:sc_detect_card_presence:
> returning with: 5
> 2014-05-14 11:39:48.948 OMNIKEY CardMan 3x21 0: Detection ended
> 2014-05-14 11:39:48.949 C_GetSlotInfo(0x5) = CKR_OK
> Using slot 2 with a present token (0x5)
> 2014-05-14 11:39:48.958 C_OpenSession(0x5)
> 2014-05-14 11:39:48.959 C_OpenSession handle: 0xaef760
> 2014-05-14 11:39:48.960 C_OpenSession() = CKR_OK
> 2014-05-14 11:39:48.962 C_GetTokenInfo(5)
> 2014-05-14 11:39:48.963 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
> 2014-05-14 11:39:48.964 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
> 2014-05-14 11:39:48.965 [opensc-pkcs11] card.c:315:sc_lock: called
> 2014-05-14 11:39:48.967 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
> 2014-05-14 11:39:48.974 [opensc-pkcs11] apdu.c:654:sc_transmit: called
> 2014-05-14 11:39:48.976 [opensc-pkcs11] apdu.c:509:sc_single_transmit:
> called
> 2014-05-14 11:39:48.977 CLA:0, INS:20, P1:0, P2:81, data(0) 00000000
> 2014-05-14 11:39:48.978 reader 'OMNIKEY CardMan 3x21 0'
> 2014-05-14 11:39:48.980
> Outgoing APDU data [    4 bytes] =====================================
> 00 20 00 81 . ..
> ======================================================================
> 2014-05-14 11:39:48.982 [opensc-pkcs11]
> reader-pcsc.c:182:pcsc_internal_transmit: called
> 2014-05-14 11:39:49.014
> Incoming APDU data [    2 bytes] =====================================
> 63 C3 c.
> ======================================================================
> 2014-05-14 11:39:49.015 [opensc-pkcs11] apdu.c:524:sc_single_transmit:
> returning with: 0 (Success)
> 2014-05-14 11:39:49.016 [opensc-pkcs11] apdu.c:676:sc_transmit: returning
> with: 0 (Success)
> 2014-05-14 11:39:49.020 [opensc-pkcs11] card.c:353:sc_unlock: called
> 2014-05-14 11:39:49.021 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
> called
> 2014-05-14 11:39:49.023 Verification failed (remaining tries: 3)
> 2014-05-14 11:39:49.024 [opensc-pkcs11] card-sc-hsm.c:164:sc_hsm_pin_info:
> returning with: 0 (Success)
> 2014-05-14 11:39:49.025 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
> with: 0 (Success)
> 2014-05-14 11:39:49.026 C_Login(0xaef760, 0)
> 2014-05-14 11:39:49.028 [opensc-pkcs11]
> pkcs15-pin.c:293:sc_pkcs15_verify_pin: called
> 2014-05-14 11:39:49.029 PIN(001820EC;len:16)
> 2014-05-14 11:39:49.030 Auth(type:0;method:1)
> 2014-05-14 11:39:49.031 PIN value validated
> 2014-05-14 11:39:49.033 [opensc-pkcs11] card.c:315:sc_lock: called
> 2014-05-14 11:39:49.041 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
> 2014-05-14 11:39:49.042 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
> 2014-05-14 11:39:49.043 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
> with: 0 (Success)
> 2014-05-14 11:39:49.044 PIN cmd result 0
> 2014-05-14 11:39:49.045 [opensc-pkcs11]
> pkcs15-pin.c:590:sc_pkcs15_pincache_add: called
> 2014-05-14 11:39:49.047 cannot lock memory, sensitive data may be paged to
> disk
> 2014-05-14 11:39:49.048 PIN(SOPIN) cached
> 2014-05-14 11:39:49.049 [opensc-pkcs11] card.c:353:sc_unlock: called
> 2014-05-14 11:39:49.057 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
> called
> 2014-05-14 11:39:49.058 [opensc-pkcs11]
> pkcs15-pin.c:372:sc_pkcs15_verify_pin: returning with: 0 (Success)
> 2014-05-14 11:39:49.060 PKCS15 verify PIN returned 0
> 2014-05-14 11:39:49.061 C_GetTokenInfo(5)
> 2014-05-14 11:39:49.062 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
> 2014-05-14 11:39:49.063 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu: called
> 2014-05-14 11:39:49.064 [opensc-pkcs11] card.c:315:sc_lock: called
> 2014-05-14 11:39:49.066 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
> 2014-05-14 11:39:49.067 [opensc-pkcs11] apdu.c:654:sc_transmit: called
> 2014-05-14 11:39:49.076 [opensc-pkcs11] apdu.c:509:sc_single_transmit:
> called
> 2014-05-14 11:39:49.077 CLA:0, INS:20, P1:0, P2:81, data(0) 00000000
> 2014-05-14 11:39:49.078 reader 'OMNIKEY CardMan 3x21 0'
> 2014-05-14 11:39:49.079
> Outgoing APDU data [    4 bytes] =====================================
> 00 20 00 81 . ..
> ======================================================================
> 2014-05-14 11:39:49.081 [opensc-pkcs11]
> reader-pcsc.c:182:pcsc_internal_transmit: called
> 2014-05-14 11:39:49.114
> Incoming APDU data [    2 bytes] =====================================
> 63 C3 c.
> ======================================================================
> 2014-05-14 11:39:49.114 [opensc-pkcs11] apdu.c:524:sc_single_transmit:
> returning with: 0 (Success)
> 2014-05-14 11:39:49.115 [opensc-pkcs11] apdu.c:676:sc_transmit: returning
> with: 0 (Success)
> 2014-05-14 11:39:49.122 [opensc-pkcs11] card.c:353:sc_unlock: called
> 2014-05-14 11:39:49.124 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
> called
> 2014-05-14 11:39:49.125 Verification failed (remaining tries: 3)
> 2014-05-14 11:39:49.126 [opensc-pkcs11] card-sc-hsm.c:164:sc_hsm_pin_info:
> returning with: 0 (Success)
> 2014-05-14 11:39:49.127 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
> with: 0 (Success)
> 2014-05-14 11:39:49.132 Changing PIN (session 0xaef760; login user 0)
> 2014-05-14 11:39:49.133 Change 'UserPIN' (ref:129,type:0)
> 2014-05-14 11:39:49.138 [opensc-pkcs11] card.c:315:sc_lock: called
> 2014-05-14 11:39:49.139 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock: called
> 2014-05-14 11:39:49.141 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
> 2014-05-14 11:39:49.142 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
> with: 0 (Success)
> 2014-05-14 11:39:49.143 [opensc-pkcs11]
> pkcs15-pin.c:590:sc_pkcs15_pincache_add: called
> 2014-05-14 11:39:49.144 cannot lock memory, sensitive data may be paged to
> disk
> 2014-05-14 11:39:49.146 PIN(SOPIN) cached
> 2014-05-14 11:39:49.155 [opensc-pkcs11] card.c:353:sc_unlock: called
> 2014-05-14 11:39:49.155 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
> called
> 2014-05-14 11:39:49.156 PIN change returns 0
> 2014-05-14 11:39:49.158 libopensc return value: 0 (Success)
> PIN successfully changed
>
> ============================================================================================================================================
> ============================================================================================================================================
>
> Best regards,
> Marjan
>
>
>
> On 14 May 2014 11:27, Andreas Schwier <[hidden email]>wrote:
>
>> Dear Marjan,
>>
>> can you enable the trace output in opensc to see what APDU is send to
>> the SmartCard-HSM ?
>>
>> Is the Windows version a recent nightly build or from the SmartCard-HSM
>> starterkit [1] ?
>>
>> Andreas
>>
>> [1] http://www.cardcontact.de/download/sc-hsm-starterkit.zip
>>
>>
>> On 05/14/2014 11:07 AM, marjan stankovic wrote:
>>> Hello all,
>>>
>>>
>>>
>>> We have bought SmartCard HSMs for testing and evaluating (in March this
>>> year). So far it fits our needs.
>>>
>>>
>>> But we have one strange problem in Windows 7. Is it possible that SO-PIN
>>> change does not work?
>>>
>>> This is what I can see:
>>>
>>>
>>>
>>> pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login
>>> --login-type so --so-pin 1234567812345678 --change-pin --new-pin
>>> 1234567812345678
>>>
>>> Using slot 1 with a present token (0x1)
>>>
>>> 2014-05-14 10:20:20.798 cannot lock memory, sensitive data may be paged
>> to
>>> disk
>>>
>>> 2014-05-14 10:20:20.832 cannot lock memory, sensitive data may be paged
>> to
>>> disk
>>>
>>> PIN successfully changed
>>>
>>>
>>>
>>> no matter what values I provide for SO-PIN. Afterwards, I see that SO-PIN
>>> was not changed (i.e. if I try to reinitialize the card with "new SO-PIN"
>>> it will raise invalid pin error).
>>>
>>>
>>>
>>> We are using OpenSC v0.13.0.
>>>
>>> In Ubuntu it works fine. This seems to be a problem in OpenSC?
>>>
>>>
>>>
>>> Best regards,
>>>
>>> Marjan Stankovic
>>>
>>>
>>>
>>>
>> ------------------------------------------------------------------------------
>>> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
>>> Instantly run your Selenium tests across 300+ browser/OS combos.
>>> Get unparalleled scalability from the best Selenium testing platform
>> available
>>> Simple to use. Nothing to install. Get started now for free."
>>> http://p.sf.net/sfu/SauceLabs
>>>
>>>
>>>
>>> _______________________________________________
>>> Opensc-devel mailing list
>>> [hidden email]
>>> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>>>
>>
>>
>>
>> ------------------------------------------------------------------------------
>> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
>> Instantly run your Selenium tests across 300+ browser/OS combos.
>> Get unparalleled scalability from the best Selenium testing platform
>> available
>> Simple to use. Nothing to install. Get started now for free."
>> http://p.sf.net/sfu/SauceLabs
>> _______________________________________________
>> Opensc-devel mailing list
>> [hidden email]
>> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>>
>



------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel
Reply | Threaded
Open this post in threaded view
|

Re: SmartCard HSM SO-PIN change

Andreas Schwier (ML)
That's because OpenSC has no fixed release cycle. It all depends on
someone in the OpenSC team to take the initiative to prepare a new
release. As we at CardContact don't have access to all cards and token
required for regression testing, we can't do it ourself.

However, we do regular testing of OpenSC head versions with the
SmartCard-HSM and update the Starterkit every once in a while.

Andreas

On 05/14/2014 12:21 PM, marjan stankovic wrote:

> Hi Andreas,
>
> This solved the issue. I downloaded SmartCard-HSM Starterkit from
> http://www.cardcontact.de/download/sc-hsm-starterkit.zip.
>
> But why is release 0.13.0 still sitting on
> https://github.com/OpenSC/OpenSC/wiki as last stable and not updated?
>
> Regards,
> Marjan
>
>
> On 14 May 2014 12:00, Andreas Schwier <[hidden email]>wrote:
>
>> Hi Marjan,
>>
>> that Windows version is quite outdated (from 2012). Better try a recent
>> nightly build or the version from our Starterkit.
>>
>> Andreas
>>
>> On 05/14/2014 11:46 AM, marjan stankovic wrote:
>>> Hallo Andreas,
>>>
>>> I used OpenSC from
>>> http://sourceforge.net/projects/opensc/files/OpenSC/opensc-0.13.0/(both 32
>>> and 64 bit).
>>>
>>> This is the output when I provide wrong SO-PIN:
>>>
>>>
>> ============================================================================================================================================
>>>
>> ============================================================================================================================================
>>> C:\Users\mst>pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll
>>> --login --login-type so --so-pin 1234567812345679 --change-pin --new-pin
>>> 1234567812345678
>>> 2014-05-14 11:39:46.764 ===================================
>>> 2014-05-14 11:39:46.765 opensc version: 0.13.0
>> ...
>>> 2014-05-14 11:39:48.931 C_GetSlotInfo(0x5)
>>> 2014-05-14 11:39:48.933 OMNIKEY CardMan 3x21 0: Detecting smart card
>>> 2014-05-14 11:39:48.940 [opensc-pkcs11] sc.c:231:sc_detect_card_presence:
>>> called
>>> 2014-05-14 11:39:48.942 [opensc-pkcs11]
>>> reader-pcsc.c:370:pcsc_detect_card_presence: called
>>> 2014-05-14 11:39:48.943 OMNIKEY CardMan 3x21 0 check
>>> 2014-05-14 11:39:48.944 [opensc-pkcs11]
>>> reader-pcsc.c:299:refresh_attributes: returning with: 0 (Success)
>>> 2014-05-14 11:39:48.945 [opensc-pkcs11]
>>> reader-pcsc.c:375:pcsc_detect_card_presence: returning with: 5
>>> 2014-05-14 11:39:48.946 [opensc-pkcs11] sc.c:236:sc_detect_card_presence:
>>> returning with: 5
>>> 2014-05-14 11:39:48.948 OMNIKEY CardMan 3x21 0: Detection ended
>>> 2014-05-14 11:39:48.949 C_GetSlotInfo(0x5) = CKR_OK
>>> Using slot 2 with a present token (0x5)
>>> 2014-05-14 11:39:48.958 C_OpenSession(0x5)
>>> 2014-05-14 11:39:48.959 C_OpenSession handle: 0xaef760
>>> 2014-05-14 11:39:48.960 C_OpenSession() = CKR_OK
>>> 2014-05-14 11:39:48.962 C_GetTokenInfo(5)
>>> 2014-05-14 11:39:48.963 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
>>> 2014-05-14 11:39:48.964 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu:
>> called
>>> 2014-05-14 11:39:48.965 [opensc-pkcs11] card.c:315:sc_lock: called
>>> 2014-05-14 11:39:48.967 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock:
>> called
>>> 2014-05-14 11:39:48.974 [opensc-pkcs11] apdu.c:654:sc_transmit: called
>>> 2014-05-14 11:39:48.976 [opensc-pkcs11] apdu.c:509:sc_single_transmit:
>>> called
>>> 2014-05-14 11:39:48.977 CLA:0, INS:20, P1:0, P2:81, data(0) 00000000
>>> 2014-05-14 11:39:48.978 reader 'OMNIKEY CardMan 3x21 0'
>>> 2014-05-14 11:39:48.980
>>> Outgoing APDU data [    4 bytes] =====================================
>>> 00 20 00 81 . ..
>>> ======================================================================
>>> 2014-05-14 11:39:48.982 [opensc-pkcs11]
>>> reader-pcsc.c:182:pcsc_internal_transmit: called
>>> 2014-05-14 11:39:49.014
>>> Incoming APDU data [    2 bytes] =====================================
>>> 63 C3 c.
>>> ======================================================================
>>> 2014-05-14 11:39:49.015 [opensc-pkcs11] apdu.c:524:sc_single_transmit:
>>> returning with: 0 (Success)
>>> 2014-05-14 11:39:49.016 [opensc-pkcs11] apdu.c:676:sc_transmit: returning
>>> with: 0 (Success)
>>> 2014-05-14 11:39:49.020 [opensc-pkcs11] card.c:353:sc_unlock: called
>>> 2014-05-14 11:39:49.021 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
>>> called
>>> 2014-05-14 11:39:49.023 Verification failed (remaining tries: 3)
>>> 2014-05-14 11:39:49.024 [opensc-pkcs11]
>> card-sc-hsm.c:164:sc_hsm_pin_info:
>>> returning with: 0 (Success)
>>> 2014-05-14 11:39:49.025 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
>>> with: 0 (Success)
>>> 2014-05-14 11:39:49.026 C_Login(0xaef760, 0)
>>> 2014-05-14 11:39:49.028 [opensc-pkcs11]
>>> pkcs15-pin.c:293:sc_pkcs15_verify_pin: called
>>> 2014-05-14 11:39:49.029 PIN(001820EC;len:16)
>>> 2014-05-14 11:39:49.030 Auth(type:0;method:1)
>>> 2014-05-14 11:39:49.031 PIN value validated
>>> 2014-05-14 11:39:49.033 [opensc-pkcs11] card.c:315:sc_lock: called
>>> 2014-05-14 11:39:49.041 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock:
>> called
>>> 2014-05-14 11:39:49.042 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
>>> 2014-05-14 11:39:49.043 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
>>> with: 0 (Success)
>>> 2014-05-14 11:39:49.044 PIN cmd result 0
>>> 2014-05-14 11:39:49.045 [opensc-pkcs11]
>>> pkcs15-pin.c:590:sc_pkcs15_pincache_add: called
>>> 2014-05-14 11:39:49.047 cannot lock memory, sensitive data may be paged
>> to
>>> disk
>>> 2014-05-14 11:39:49.048 PIN(SOPIN) cached
>>> 2014-05-14 11:39:49.049 [opensc-pkcs11] card.c:353:sc_unlock: called
>>> 2014-05-14 11:39:49.057 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
>>> called
>>> 2014-05-14 11:39:49.058 [opensc-pkcs11]
>>> pkcs15-pin.c:372:sc_pkcs15_verify_pin: returning with: 0 (Success)
>>> 2014-05-14 11:39:49.060 PKCS15 verify PIN returned 0
>>> 2014-05-14 11:39:49.061 C_GetTokenInfo(5)
>>> 2014-05-14 11:39:49.062 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
>>> 2014-05-14 11:39:49.063 [opensc-pkcs11] apdu.c:687:sc_transmit_apdu:
>> called
>>> 2014-05-14 11:39:49.064 [opensc-pkcs11] card.c:315:sc_lock: called
>>> 2014-05-14 11:39:49.066 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock:
>> called
>>> 2014-05-14 11:39:49.067 [opensc-pkcs11] apdu.c:654:sc_transmit: called
>>> 2014-05-14 11:39:49.076 [opensc-pkcs11] apdu.c:509:sc_single_transmit:
>>> called
>>> 2014-05-14 11:39:49.077 CLA:0, INS:20, P1:0, P2:81, data(0) 00000000
>>> 2014-05-14 11:39:49.078 reader 'OMNIKEY CardMan 3x21 0'
>>> 2014-05-14 11:39:49.079
>>> Outgoing APDU data [    4 bytes] =====================================
>>> 00 20 00 81 . ..
>>> ======================================================================
>>> 2014-05-14 11:39:49.081 [opensc-pkcs11]
>>> reader-pcsc.c:182:pcsc_internal_transmit: called
>>> 2014-05-14 11:39:49.114
>>> Incoming APDU data [    2 bytes] =====================================
>>> 63 C3 c.
>>> ======================================================================
>>> 2014-05-14 11:39:49.114 [opensc-pkcs11] apdu.c:524:sc_single_transmit:
>>> returning with: 0 (Success)
>>> 2014-05-14 11:39:49.115 [opensc-pkcs11] apdu.c:676:sc_transmit: returning
>>> with: 0 (Success)
>>> 2014-05-14 11:39:49.122 [opensc-pkcs11] card.c:353:sc_unlock: called
>>> 2014-05-14 11:39:49.124 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
>>> called
>>> 2014-05-14 11:39:49.125 Verification failed (remaining tries: 3)
>>> 2014-05-14 11:39:49.126 [opensc-pkcs11]
>> card-sc-hsm.c:164:sc_hsm_pin_info:
>>> returning with: 0 (Success)
>>> 2014-05-14 11:39:49.127 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
>>> with: 0 (Success)
>>> 2014-05-14 11:39:49.132 Changing PIN (session 0xaef760; login user 0)
>>> 2014-05-14 11:39:49.133 Change 'UserPIN' (ref:129,type:0)
>>> 2014-05-14 11:39:49.138 [opensc-pkcs11] card.c:315:sc_lock: called
>>> 2014-05-14 11:39:49.139 [opensc-pkcs11] reader-pcsc.c:517:pcsc_lock:
>> called
>>> 2014-05-14 11:39:49.141 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
>>> 2014-05-14 11:39:49.142 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning
>>> with: 0 (Success)
>>> 2014-05-14 11:39:49.143 [opensc-pkcs11]
>>> pkcs15-pin.c:590:sc_pkcs15_pincache_add: called
>>> 2014-05-14 11:39:49.144 cannot lock memory, sensitive data may be paged
>> to
>>> disk
>>> 2014-05-14 11:39:49.146 PIN(SOPIN) cached
>>> 2014-05-14 11:39:49.155 [opensc-pkcs11] card.c:353:sc_unlock: called
>>> 2014-05-14 11:39:49.155 [opensc-pkcs11] reader-pcsc.c:554:pcsc_unlock:
>>> called
>>> 2014-05-14 11:39:49.156 PIN change returns 0
>>> 2014-05-14 11:39:49.158 libopensc return value: 0 (Success)
>>> PIN successfully changed
>>>
>>>
>> ============================================================================================================================================
>>>
>> ============================================================================================================================================
>>>
>>> Best regards,
>>> Marjan
>>>
>>>
>>>
>>> On 14 May 2014 11:27, Andreas Schwier <[hidden email]
>>> wrote:
>>>
>>>> Dear Marjan,
>>>>
>>>> can you enable the trace output in opensc to see what APDU is send to
>>>> the SmartCard-HSM ?
>>>>
>>>> Is the Windows version a recent nightly build or from the SmartCard-HSM
>>>> starterkit [1] ?
>>>>
>>>> Andreas
>>>>
>>>> [1] http://www.cardcontact.de/download/sc-hsm-starterkit.zip
>>>>
>>>>
>>>> On 05/14/2014 11:07 AM, marjan stankovic wrote:
>>>>> Hello all,
>>>>>
>>>>>
>>>>>
>>>>> We have bought SmartCard HSMs for testing and evaluating (in March this
>>>>> year). So far it fits our needs.
>>>>>
>>>>>
>>>>> But we have one strange problem in Windows 7. Is it possible that
>> SO-PIN
>>>>> change does not work?
>>>>>
>>>>> This is what I can see:
>>>>>
>>>>>
>>>>>
>>>>> pkcs11-tool --module C:/Windows/System32/opensc-pkcs11.dll --login
>>>>> --login-type so --so-pin 1234567812345678 --change-pin --new-pin
>>>>> 1234567812345678
>>>>>
>>>>> Using slot 1 with a present token (0x1)
>>>>>
>>>>> 2014-05-14 10:20:20.798 cannot lock memory, sensitive data may be paged
>>>> to
>>>>> disk
>>>>>
>>>>> 2014-05-14 10:20:20.832 cannot lock memory, sensitive data may be paged
>>>> to
>>>>> disk
>>>>>
>>>>> PIN successfully changed
>>>>>
>>>>>
>>>>>
>>>>> no matter what values I provide for SO-PIN. Afterwards, I see that
>> SO-PIN
>>>>> was not changed (i.e. if I try to reinitialize the card with "new
>> SO-PIN"
>>>>> it will raise invalid pin error).
>>>>>
>>>>>
>>>>>
>>>>> We are using OpenSC v0.13.0.
>>>>>
>>>>> In Ubuntu it works fine. This seems to be a problem in OpenSC?
>>>>>
>>>>>
>>>>>
>>>>> Best regards,
>>>>>
>>>>> Marjan Stankovic
>>>>>
>>>>>
>>>>>
>>>>>
>>>>
>> ------------------------------------------------------------------------------
>>>>> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
>>>>> Instantly run your Selenium tests across 300+ browser/OS combos.
>>>>> Get unparalleled scalability from the best Selenium testing platform
>>>> available
>>>>> Simple to use. Nothing to install. Get started now for free."
>>>>> http://p.sf.net/sfu/SauceLabs
>>>>>
>>>>>
>>>>>
>>>>> _______________________________________________
>>>>> Opensc-devel mailing list
>>>>> [hidden email]
>>>>> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>>>>>
>>>>
>>>>
>>>>
>>>>
>> ------------------------------------------------------------------------------
>>>> "Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
>>>> Instantly run your Selenium tests across 300+ browser/OS combos.
>>>> Get unparalleled scalability from the best Selenium testing platform
>>>> available
>>>> Simple to use. Nothing to install. Get started now for free."
>>>> http://p.sf.net/sfu/SauceLabs
>>>> _______________________________________________
>>>> Opensc-devel mailing list
>>>> [hidden email]
>>>> https://lists.sourceforge.net/lists/listinfo/opensc-devel
>>>>
>>>
>>
>>
>


------------------------------------------------------------------------------
"Accelerate Dev Cycles with Automated Cross-Browser Testing - For FREE
Instantly run your Selenium tests across 300+ browser/OS combos.
Get unparalleled scalability from the best Selenium testing platform available
Simple to use. Nothing to install. Get started now for free."
http://p.sf.net/sfu/SauceLabs
_______________________________________________
Opensc-devel mailing list
[hidden email]
https://lists.sourceforge.net/lists/listinfo/opensc-devel