Le samedi 30 avril 2011 19:26:19, Viktor TARASOV a écrit :
> Hello Gilles,
>
> Le 29/04/2011 19:11, BLANC Gilles a écrit :
> > I have seen that you have modified some things. Sadly, I still encounter
> > the same problem by now...
>
> When stating some problems, good practice is to join the logs.
>
> It's extremely simple: in opensc.conf, in 'app default' section, set 'debug
> = 8;' and comment out the line starting as 'debug_file = ...'.
Hello,
It seems not to be so easy to send simple verbose logs on this ML: my message
is moderated since monday... :/
"The reason it is being held:
Message body is too big: 1146293 bytes with a limit of 50 KB"
(well... 1.1mb, oups, but it is really a simple execution !)
In a nutshell, I have succeded to make it work (at least delete, I'm testing
the other functionalities today), but it crashes sometimes...
Here is my original email :
"""
Actually I had problem with log activation: I have finally found that it was
not using /etc/opensc.conf but /usr/local/etc/opensc.conf...
When I watched to the logs, I found that the profile files were not correctly
installed. So I copied them in the system, and... tadam ! Delete works ! :)
Well... except when it bugs. :/
Sometimes it refuses to delete an object, and even sometimes it crashes ! I
join you the log corresponding to one refusal and one crash. Maybe it is a
mishandling, but I do not think so, since I have cleaned then loaded all
certificates and key pair with the Oberthur official sw on Windows...
"""
I join you the last 50kb logs.
Thanks,
--
Gilles Blanc - gbl...@linagora.com
Ingénieur expert informatique embarquée
Représentant du personnel - Secrétaire du CE
Groupe LINAGORA - http://www.linagora.com
Tél.: +33(0)1 46 96 63 39 - Fax : +33(0)1 46 96 63 64
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x8C
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 9 bytes
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:568:iasecc_parse_docp: iasecc_parse_docp() parse_get_tlv retuned 9; tag 8C; size 7
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x9C
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x9C
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 9 bytes
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:568:iasecc_parse_docp: iasecc_parse_docp() parse_get_tlv retuned 9; tag 9C; size 7
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:64:iasecc_parse_acls: iasecc_parse_docp() SCBs 12:12:12:12:FF:12:00
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:65:iasecc_parse_acls: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:612:iasecc_parse_docp: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:64:iasecc_parse_acls: iasecc_parse_docp() SCBs 12:12:12:12:FF:12:00
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:65:iasecc_parse_acls: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:612:iasecc_parse_docp: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:633:iasecc_sdo_parse_data: iasecc_sdo_parse_data() parsed IASECC_DOCP_TAG rv 0
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:740:iasecc_sdo_parse: docp.acls_contact.size 7, docp.size.size 2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:742:iasecc_sdo_parse: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] card-iasecc.c:2541:iasecc_sdo_get_tagged_data: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] card-iasecc.c:2563:iasecc_sdo_get_data: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] card.c:730:sc_card_ctl: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:302:iasecc_sdo_get_data: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:1233:iasecc_pkcs15_delete_sdo: iasecc_pkcs15_delete_sdo() SDO class 0x10, ref 0x3
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:562:iasecc_sdo_convert_to_file: called
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:568:iasecc_sdo_convert_to_file: SDO class 0x10
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:89:iasecc_sdo_convert_acl: called
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:100:iasecc_sdo_convert_acl: OP:11, mask:0x40
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:101:iasecc_sdo_convert_acl: AMB:FB, scbs:12121212FF1200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:102:iasecc_sdo_convert_acl: docp.acls_contact:FB121212121200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:117:iasecc_sdo_convert_acl: ii:0, scb:0x12
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:138:iasecc_sdo_convert_acl: returns method 20; ref 2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:139:iasecc_sdo_convert_acl: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:581:iasecc_sdo_convert_to_file: ii:0, method:20, ref:2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:89:iasecc_sdo_convert_acl: called
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:100:iasecc_sdo_convert_acl: OP:15, mask:0x20
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:101:iasecc_sdo_convert_acl: AMB:FB, scbs:12121212FF1200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:102:iasecc_sdo_convert_acl: docp.acls_contact:FB121212121200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:117:iasecc_sdo_convert_acl: ii:1, scb:0x12
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:138:iasecc_sdo_convert_acl: returns method 20; ref 2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:139:iasecc_sdo_convert_acl: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:581:iasecc_sdo_convert_to_file: ii:1, method:20, ref:2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:89:iasecc_sdo_convert_acl: called
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:100:iasecc_sdo_convert_acl: OP:9, mask:0x10
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:101:iasecc_sdo_convert_acl: AMB:FB, scbs:12121212FF1200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:102:iasecc_sdo_convert_acl: docp.acls_contact:FB121212121200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:117:iasecc_sdo_convert_acl: ii:2, scb:0x12
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:138:iasecc_sdo_convert_acl: returns method 20; ref 2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:139:iasecc_sdo_convert_acl: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:581:iasecc_sdo_convert_to_file: ii:2, method:20, ref:2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:89:iasecc_sdo_convert_acl: called
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:100:iasecc_sdo_convert_acl: OP:26, mask:0x8
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:101:iasecc_sdo_convert_acl: AMB:FB, scbs:12121212FF1200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:102:iasecc_sdo_convert_acl: docp.acls_contact:FB121212121200
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:117:iasecc_sdo_convert_acl: ii:3, scb:0x12
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:138:iasecc_sdo_convert_acl: returns method 20; ref 2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:139:iasecc_sdo_convert_acl: returning with: 0 (Success)
0xb6998760 11:23:58.706 [opensc-pkcs11] pkcs15-iasecc.c:581:iasecc_sdo_convert_to_file: ii:3, method:20, ref:2
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:89:iasecc_sdo_convert_acl: called
0xb6998760 11:23:58.706 [opensc-pkcs11] iasecc-sdo.c:100:iasecc_sdo_convert_acl: OP:23, mask:0x2
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:101:iasecc_sdo_convert_acl: AMB:FB, scbs:12121212FF1200
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:102:iasecc_sdo_convert_acl: docp.acls_contact:FB121212121200
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:117:iasecc_sdo_convert_acl: ii:5, scb:0x12
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:138:iasecc_sdo_convert_acl: returns method 20; ref 2
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:139:iasecc_sdo_convert_acl: returning with: 0 (Success)
0xb6998760 11:23:58.707 [opensc-pkcs11] pkcs15-iasecc.c:581:iasecc_sdo_convert_to_file: ii:4, method:20, ref:2
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:89:iasecc_sdo_convert_acl: called
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:100:iasecc_sdo_convert_acl: OP:22, mask:0x1
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:101:iasecc_sdo_convert_acl: AMB:FB, scbs:12121212FF1200
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:102:iasecc_sdo_convert_acl: docp.acls_contact:FB121212121200
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:117:iasecc_sdo_convert_acl: ii:6, scb:0x0
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:138:iasecc_sdo_convert_acl: returns method 0; ref 0
0xb6998760 11:23:58.707 [opensc-pkcs11] iasecc-sdo.c:139:iasecc_sdo_convert_acl: returning with: 0 (Success)
0xb6998760 11:23:58.707 [opensc-pkcs11] pkcs15-iasecc.c:581:iasecc_sdo_convert_to_file: ii:5, method:0, ref:0
0xb6998760 11:23:58.707 [opensc-pkcs11] pkcs15-iasecc.c:589:iasecc_sdo_convert_to_file: returning with: 0 (Success)
0xb6998760 11:23:58.732 [opensc-pkcs11] pkcs15-lib.c:3170:sc_pkcs15init_authenticate: called
0xb6998760 11:23:58.732 [opensc-pkcs11] pkcs15-lib.c:3171:sc_pkcs15init_authenticate: path '', op=23
0xb6998760 11:23:58.732 [opensc-pkcs11] pkcs15-lib.c:3182:sc_pkcs15init_authenticate: acl 0x88651d0
0xb6998760 11:23:58.732 [opensc-pkcs11] pkcs15-lib.c:3196:sc_pkcs15init_authenticate: verify acl(method:32,reference:2)
0xb6998760 11:23:58.732 [opensc-pkcs11] pkcs15-lib.c:3030:sc_pkcs15init_verify_secret: called
0xb6998760 11:23:58.732 [opensc-pkcs11] pkcs15-lib.c:3034:sc_pkcs15init_verify_secret: get and verify PIN('security environment',type:0x20,reference:0x2)
0xb6998760 11:23:58.732 [opensc-pkcs11] card.c:720:sc_card_ctl: called
0xb6998760 11:23:58.732 [opensc-pkcs11] card-iasecc.c:2699:iasecc_card_ctl: CMD SC_CARDCTL_GET_CHV_REFERENCE_IN_SE
0xb6998760 11:23:58.732 [opensc-pkcs11] card-iasecc.c:2646:iasecc_get_chv_reference_from_se: called
0xb6998760 11:23:58.732 [opensc-pkcs11] card-iasecc.c:1340:iasecc_se_get_info: called
0xb6998760 11:23:58.732 [opensc-pkcs11] card-iasecc.c:1293:iasecc_se_get_info_from_cache: called
0xb6998760 11:23:58.732 [opensc-pkcs11] card-iasecc.c:1347:iasecc_se_get_info: No SE#2 info in cache, try to use 'GET DATA'
0xb6998760 11:23:58.732 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.732 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.732 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.732 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 16 bytes] =====================================
00 CB 3F FF 0A 4D 08 70 06 BF FB 02 02 7B 80 00 ..?..M.p.....{..
======================================================================
0xb6998760 11:23:58.732 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.750 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 21 bytes] =====================================
70 11 BF FB 02 0D 7B 0B A4 09 80 01 00 95 01 08 p.....{.........
83 01 01 90 00 .....
======================================================================
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:324:iasecc_se_parse: called
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:332:iasecc_se_parse: IASECC_SDO_TEMPLATE: size 17, size_size 1
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:348:iasecc_se_parse: IASECC_SDO_TEMPLATE SE: size 13, size_size 1
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:212:iasecc_crt_parse: iasecc_crt_parse(0xA4) called
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:219:iasecc_crt_parse: iasecc_crt_parse(0xA4) CRT 80 -> 0
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:219:iasecc_crt_parse: iasecc_crt_parse(0xA4) CRT 95 -> 8
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:219:iasecc_crt_parse: iasecc_crt_parse(0xA4) CRT 83 -> 1
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:250:iasecc_crt_parse: returning with: 11
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:373:iasecc_se_parse: returning with: 0 (Success)
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1256:iasecc_se_cache_info: called
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:1250:iasecc_docp_copy: called
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:1286:iasecc_docp_copy: returning with: 0 (Success)
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1281:iasecc_se_cache_info: returning with: 0 (Success)
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1369:iasecc_se_get_info: returning with: 0 (Success)
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:260:iasecc_se_get_crt: called
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:264:iasecc_se_get_crt: CRT search template: A4:0:8, refs 0:0:...
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:279:iasecc_se_get_crt: iasecc_se_get_crt() found CRT with refs 1:0:...
0xb6998760 11:23:58.751 [opensc-pkcs11] iasecc-sdo.c:280:iasecc_se_get_crt: returning with: 0 (Success)
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:2664:iasecc_get_chv_reference_from_se: returning with: 1
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:730:sc_card_ctl: returning with: 1
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:3038:sc_pkcs15init_verify_secret: Card CTL(GET_CHV_REFERENCE_IN_SE) returned 1
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:3040:sc_pkcs15init_verify_secret: CHV(ref:1) found in SE(ref:2)
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:1653:sc_pkcs15init_get_pin_reference: called
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15.c:988:__sc_pkcs15_search_objects: called; class=0x00, type=0x601
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15.c:1047:__sc_pkcs15_search_objects: returning with: 4
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:1663:sc_pkcs15init_get_pin_reference: found 4 auth objects; looking for AUTH object(auth_method:1,reference:1)
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:1669:sc_pkcs15init_get_pin_reference: check PIN(Pin de la carte,auth_method:1,type:1,reference:1,flags:830)
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:1672:sc_pkcs15init_get_pin_reference: returning with: 1
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:3053:sc_pkcs15init_verify_secret: found PIN reference 1
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15.c:988:__sc_pkcs15_search_objects: called; class=0x00, type=0x601
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15.c:1047:__sc_pkcs15_search_objects: returning with: 4
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:3079:sc_pkcs15init_verify_secret: found PIN object 'Pin de la carte'
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:3084:sc_pkcs15init_verify_secret: PIN object 'Pin de la carte'; pin_obj->content.len:4
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-lib.c:3090:sc_pkcs15init_verify_secret: 've got 'security environment' value from cache
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-pin.c:231:sc_pkcs15_verify_pin: called
0xb6998760 11:23:58.751 [opensc-pkcs11] pkcs15-pin.c:232:sc_pkcs15_verify_pin: PIN(0xbf82f994;len:4)
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.751 [opensc-pkcs11] sec.c:157:sc_pin_cmd: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:2125:iasecc_pin_cmd: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:2128:iasecc_pin_cmd: iasecc_pin_cmd(card:0x87fbd38) cmd 0x0, PIN type 0x1, PIN reference 1, PIN-1 0xbf82f994:4, PIN-2 (nil):0
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1721:iasecc_pin_verify: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1722:iasecc_pin_verify: Verify PIN(type:1,ref:1,data(len:4,0xbf82f994)
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1876:iasecc_pin_get_policy: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:1877:iasecc_pin_get_policy: iasecc_pin_get_policy(card:0x87fbd38)
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:675:iasecc_select_file: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:679:iasecc_select_file: iasecc_select_file(card:0x87fbd38) path.len 2; path.type 0; aid_len 0
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:680:iasecc_select_file: iasecc_select_file() path:3f00
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:688:iasecc_select_file: EF.ATR(aid:'A000000077010800070000FE00000100')
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:231:iasecc_select_mf: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:675:iasecc_select_file: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:679:iasecc_select_file: iasecc_select_file(card:0x87fbd38) path.len 16; path.type 1; aid_len 0
0xb6998760 11:23:58.751 [opensc-pkcs11] card-iasecc.c:680:iasecc_select_file: iasecc_select_file() path:a000000077010800070000fe00000100::
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:23:58.751 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.751 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.751 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.751 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 22 bytes] =====================================
00 A4 04 00 10 A0 00 00 00 77 01 08 00 07 00 00 .........w......
FE 00 00 01 00 00 ......
======================================================================
0xb6998760 11:23:58.751 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.785 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
90 00 ..
======================================================================
0xb6998760 11:23:58.786 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:804:iasecc_select_file: iasecc_select_file() apdu.resp 0
0xb6998760 11:23:58.786 [opensc-pkcs11] card.c:1007:sc_print_cache: card cache invalid
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:866:iasecc_select_file: returning with: 0 (Success)
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:261:iasecc_select_mf: returning with: 0 (Success)
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:726:iasecc_select_file: returning with: 0 (Success)
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:1908:iasecc_pin_get_policy: iasecc_pin_get_policy() reference 1
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:2551:iasecc_sdo_get_data: called
0xb6998760 11:23:58.786 [opensc-pkcs11] card-iasecc.c:2504:iasecc_sdo_get_tagged_data: called
0xb6998760 11:23:58.786 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.786 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.786 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.786 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 17 bytes] =====================================
00 CB 3F FF 0B 4D 09 70 07 BF 81 01 03 7F 41 80 ..?..M.p......A.
00 .
======================================================================
0xb6998760 11:23:58.786 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.804 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 17 bytes] =====================================
70 0D BF 81 01 09 7F 41 06 80 01 40 81 01 04 90 p......A...@....
00 .
======================================================================
0xb6998760 11:23:58.805 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:701:iasecc_sdo_parse: called
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:709:iasecc_sdo_parse: IASECC_SDO_TEMPLATE: size 13, size_size 1
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:727:iasecc_sdo_parse: sz 9, sz_size 1
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:623:iasecc_sdo_parse_data: called
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:624:iasecc_sdo_parse_data: iasecc_sdo_parse_data() class 1; ref 1
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x7F
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x7F41
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 9 bytes
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:629:iasecc_sdo_parse_data: iasecc_sdo_parse_data() tlv.tag 0x7F41
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:437:iasecc_parse_chv: called
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x80
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x80
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 3 bytes
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:444:iasecc_parse_chv: iasecc_parse_chv() get and parse TLV returned 3; tag 80; size 1
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x81
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x81
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 3 bytes
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:444:iasecc_parse_chv: iasecc_parse_chv() get and parse TLV returned 3; tag 81; size 1
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:458:iasecc_parse_chv: returning with: 0 (Success)
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:740:iasecc_sdo_parse: docp.acls_contact.size 0, docp.size.size 0
0xb6998760 11:23:58.805 [opensc-pkcs11] iasecc-sdo.c:742:iasecc_sdo_parse: returning with: 0 (Success)
0xb6998760 11:23:58.805 [opensc-pkcs11] card-iasecc.c:2541:iasecc_sdo_get_tagged_data: returning with: 0 (Success)
0xb6998760 11:23:58.805 [opensc-pkcs11] card-iasecc.c:2504:iasecc_sdo_get_tagged_data: called
0xb6998760 11:23:58.805 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.805 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.805 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.805 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 16 bytes] =====================================
00 CB 3F FF 0A 4D 08 70 06 BF 81 01 02 A0 80 00 ..?..M.p........
======================================================================
0xb6998760 11:23:58.805 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.824 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 28 bytes] =====================================
70 18 BF 81 01 14 A0 12 9A 01 03 9B 01 03 A1 0A p...............
8C 08 F3 00 00 13 FF 00 90 12 90 00 ............
======================================================================
0xb6998760 11:23:58.824 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:701:iasecc_sdo_parse: called
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:709:iasecc_sdo_parse: IASECC_SDO_TEMPLATE: size 24, size_size 1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:727:iasecc_sdo_parse: sz 20, sz_size 1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:623:iasecc_sdo_parse_data: called
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:624:iasecc_sdo_parse_data: iasecc_sdo_parse_data() class 1; ref 1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0xA0
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0xA0
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 20 bytes
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:629:iasecc_sdo_parse_data: iasecc_sdo_parse_data() tlv.tag 0xA0
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:631:iasecc_sdo_parse_data: iasecc_sdo_parse_data() parse IASECC_DOCP_TAG: 0xA0; size 18
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:561:iasecc_parse_docp: called
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x9A
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x9A
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 3 bytes
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:568:iasecc_parse_docp: iasecc_parse_docp() parse_get_tlv retuned 3; tag 9A; size 1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x9B
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x9B
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 3 bytes
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:568:iasecc_parse_docp: iasecc_parse_docp() parse_get_tlv retuned 3; tag 9B; size 1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0xA1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0xA1
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 12 bytes
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:568:iasecc_parse_docp: iasecc_parse_docp() parse_get_tlv retuned 12; tag A1; size 10
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:561:iasecc_parse_docp: called
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:404:iasecc_parse_get_tlv: iasecc_parse_get_tlv() called for tag 0x8C
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:414:iasecc_parse_get_tlv: iasecc_parse_get_tlv() tlv->tag 0x8C
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:425:iasecc_parse_get_tlv: iasecc_parse_get_tlv() parsed 10 bytes
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:568:iasecc_parse_docp: iasecc_parse_docp() parse_get_tlv retuned 10; tag 8C; size 8
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:64:iasecc_parse_acls: iasecc_parse_docp() SCBs 00:00:13:FF:FF:FF:00
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:65:iasecc_parse_acls: returning with: 0 (Success)
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:612:iasecc_parse_docp: returning with: 0 (Success)
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:64:iasecc_parse_acls: iasecc_parse_docp() SCBs 00:00:13:FF:FF:FF:00
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:65:iasecc_parse_acls: returning with: 0 (Success)
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:612:iasecc_parse_docp: returning with: 0 (Success)
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:633:iasecc_sdo_parse_data: iasecc_sdo_parse_data() parsed IASECC_DOCP_TAG rv 0
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:740:iasecc_sdo_parse: docp.acls_contact.size 8, docp.size.size 0
0xb6998760 11:23:58.824 [opensc-pkcs11] iasecc-sdo.c:742:iasecc_sdo_parse: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:2541:iasecc_sdo_get_tagged_data: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:2563:iasecc_sdo_get_data: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0x0
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0x0
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0x13
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1340:iasecc_se_get_info: called
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1293:iasecc_se_get_info_from_cache: called
0xb6998760 11:23:58.825 [opensc-pkcs11] iasecc-sdo.c:1250:iasecc_docp_copy: called
0xb6998760 11:23:58.825 [opensc-pkcs11] iasecc-sdo.c:1286:iasecc_docp_copy: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1322:iasecc_se_get_info_from_cache: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1369:iasecc_se_get_info: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] iasecc-sdo.c:295:iasecc_se_get_crt_by_usage: called
0xb6998760 11:23:58.825 [opensc-pkcs11] iasecc-sdo.c:298:iasecc_se_get_crt_by_usage: CRT search template with TAG:0xA4 and UQB:0x8
0xb6998760 11:23:58.825 [opensc-pkcs11] iasecc-sdo.c:308:iasecc_se_get_crt_by_usage: iasecc_se_get_crt() found CRT with refs 2:0:...
0xb6998760 11:23:58.825 [opensc-pkcs11] iasecc-sdo.c:309:iasecc_se_get_crt_by_usage: returning with: 0 (Success)
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1947:iasecc_pin_get_policy: iasecc_pin_get_policy() scb:0x13; sdo_ref:[2,0,...]
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1952:iasecc_pin_get_policy: 'SM' and 'EXTERNAL AUTHENTICATION' protection methods are not supported: SCB:0x13
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0xFF
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0xFF
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0xFF
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1925:iasecc_pin_get_policy: iasecc_pin_get_policy() set info acls: SCB 0x0
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1985:iasecc_pin_get_policy: PIN policy: size max/min 64/4, tries max/left 3/3
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:1991:iasecc_pin_get_policy: iasecc_pin_get_policy() restore current DF
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:675:iasecc_select_file: called
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:679:iasecc_select_file: iasecc_select_file(card:0x87fbd38) path.len 13; path.type 1; aid_len 0
0xb6998760 11:23:58.825 [opensc-pkcs11] card-iasecc.c:680:iasecc_select_file: iasecc_select_file() path:e828bd080ff2504f5420415750::
0xb6998760 11:23:58.825 [opensc-pkcs11] card.c:1007:sc_print_cache: card cache invalid
0xb6998760 11:23:58.825 [opensc-pkcs11] card.c:1007:sc_print_cache: card cache invalid
0xb6998760 11:23:58.825 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.825 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.825 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.825 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 19 bytes] =====================================
00 A4 04 00 0D E8 28 BD 08 0F F2 50 4F 54 20 41 ......(....POT A
57 50 00 WP.
======================================================================
0xb6998760 11:23:58.825 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.839 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
6A 86 j.
======================================================================
0xb6998760 11:23:58.839 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.839 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Incorrect parameters P1-P2
0xb6998760 11:23:58.839 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.839 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.839 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.839 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 19 bytes] =====================================
00 A4 04 0C 0D E8 28 BD 08 0F F2 50 4F 54 20 41 ......(....POT A
57 50 00 WP.
======================================================================
0xb6998760 11:23:58.839 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.863 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
90 00 ..
======================================================================
0xb6998760 11:23:58.863 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:650:iasecc_emulate_fcp: called
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:664:iasecc_emulate_fcp: returning with: 0 (Success)
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:804:iasecc_select_file: iasecc_select_file() apdu.resp 261
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:806:iasecc_select_file: apdu.resp 62:1B:82...
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:886:iasecc_process_fci: called
0xb6998760 11:23:58.863 [opensc-pkcs11] asn1.c:320:sc_asn1_find_tag: invalid TLV object
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:889:iasecc_process_fci: processing FCI: 0x6F tag (nil)
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:897:iasecc_process_fci: processing FCI: 0x62 tag 0xbf82814d
0xb6998760 11:23:58.863 [opensc-pkcs11] card-iasecc.c:899:iasecc_process_fci: FCP length 27
0xb6998760 11:23:58.863 [opensc-pkcs11] iso7816.c:318:iso7816_process_fci: processing FCI bytes
0xb6998760 11:23:58.864 [opensc-pkcs11] iso7816.c:349:iso7816_process_fci: shareable: no
0xb6998760 11:23:58.864 [opensc-pkcs11] iso7816.c:369:iso7816_process_fci: type: DF
0xb6998760 11:23:58.864 [opensc-pkcs11] iso7816.c:371:iso7816_process_fci: EF structure: 0
0xb6998760 11:23:58.864 [opensc-pkcs11] iso7816.c:382:iso7816_process_fci: File name: E8 28 BD 08 0F F2 50 4F 54 20 41 57 50 .(....POT AWP
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:912:iasecc_process_fci: iasecc_process_fci() type 4; let's parse file ACLs
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:924:iasecc_process_fci: ACLs(2) '0200'
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:932:iasecc_process_fci: ACLs mask 0x2, offs 1, op 0x3, acls[offs] 0x0
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:956:iasecc_process_fci: returning with: 0 (Success)
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:824:iasecc_select_file: FileType 4
0xb6998760 11:23:58.864 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:866:iasecc_select_file: returning with: 0 (Success)
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:2010:iasecc_pin_get_policy: returning with: 0 (Success)
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:1693:iasecc_pin_is_verified: called
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:1698:iasecc_pin_is_verified: Verify ACL(method:0;ref:0)
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:1602:iasecc_chv_verify: called
0xb6998760 11:23:58.864 [opensc-pkcs11] card-iasecc.c:1603:iasecc_chv_verify: CHV PIN reference 1, data_len 0
0xb6998760 11:23:58.864 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.864 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.864 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.864 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 4 bytes] =====================================
00 20 00 01 . ..
======================================================================
0xb6998760 11:23:58.864 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:58.875 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
90 00 ..
======================================================================
0xb6998760 11:23:58.875 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:1642:iasecc_chv_verify: returning with: 0 (Success)
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:1708:iasecc_pin_is_verified: returning with: 0 (Success)
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:202:iasecc_chv_cache_is_verified: called
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:208:iasecc_chv_cache_is_verified: data_sha1: 4170AC2A2782A1516FE9E13D7322AE482C1BD594
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:215:iasecc_chv_cache_is_verified: PIN-1 status 'verified'
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:1757:iasecc_pin_verify: returning with: 0 (Success)
0xb6998760 11:23:58.875 [opensc-pkcs11] card-iasecc.c:2137:iasecc_pin_cmd: returning with: 0 (Success)
0xb6998760 11:23:58.875 [opensc-pkcs11] sec.c:204:sc_pin_cmd: returning with: 0 (Success)
0xb6998760 11:23:58.875 [opensc-pkcs11] pkcs15-pin.c:496:sc_pkcs15_pincache_add: called
0xb6998760 11:23:58.876 [opensc-pkcs11] pkcs15-pin.c:530:sc_pkcs15_pincache_add: PIN(Pin de la carte) cached
0xb6998760 11:23:58.876 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:58.876 [opensc-pkcs11] pkcs15-pin.c:289:sc_pkcs15_verify_pin: returning with: 0 (Success)
0xb6998760 11:23:58.876 [opensc-pkcs11] card.c:571:sc_select_file: called; type=0, path=
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:675:iasecc_select_file: called
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:679:iasecc_select_file: iasecc_select_file(card:0x87fbd38) path.len 0; path.type 0; aid_len 0
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:680:iasecc_select_file: iasecc_select_file() path:
0xb6998760 11:23:58.876 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:726:iasecc_select_file: returning with: 0 (Success)
0xb6998760 11:23:58.876 [opensc-pkcs11] card.c:597:sc_select_file: returning with: 0 (Success)
0xb6998760 11:23:58.876 [opensc-pkcs11] pkcs15-lib.c:3144:sc_pkcs15init_verify_secret: returning with: 0 (Success)
0xb6998760 11:23:58.876 [opensc-pkcs11] pkcs15-lib.c:3203:sc_pkcs15init_authenticate: returning with: 0 (Success)
0xb6998760 11:23:58.876 [opensc-pkcs11] card.c:720:sc_card_ctl: called
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:2681:iasecc_card_ctl: CMD SC_CARDCTL_IASECC_SDO_DELETE: sdo_class 10
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:2348:iasecc_sdo_delete: called
0xb6998760 11:23:58.876 [opensc-pkcs11] card-iasecc.c:2355:iasecc_sdo_delete: delete SDO BF9003
0xb6998760 11:23:58.876 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:58.876 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:58.876 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:58.876 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 11 bytes] =====================================
00 DB 3F FF 06 70 04 BF 90 03 00 ..?..p.....
======================================================================
0xb6998760 11:23:58.876 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:59.193 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
90 00 ..
======================================================================
0xb6998760 11:23:59.193 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:59.194 [opensc-pkcs11] card-iasecc.c:2368:iasecc_sdo_delete: returning with: 0 (Success)
0xb6998760 11:23:59.194 [opensc-pkcs11] card.c:730:sc_card_ctl: returning with: 0 (Success)
0xb6998760 11:23:59.194 [opensc-pkcs11] pkcs15-iasecc.c:1272:iasecc_pkcs15_delete_sdo: returning with: 0 (Success)
0xb6998760 11:23:59.194 [opensc-pkcs11] pkcs15-iasecc.c:1213:iasecc_pkcs15_delete_sdo: called
0xb6998760 11:23:59.194 [opensc-pkcs11] pkcs15-iasecc.c:1214:iasecc_pkcs15_delete_sdo: iasecc_pkcs15_delete_sdo() class 0x20; reference 131
0xb6998760 11:23:59.194 [opensc-pkcs11] pkcs15-iasecc.c:298:iasecc_sdo_get_data: called
0xb6998760 11:23:59.194 [opensc-pkcs11] card.c:720:sc_card_ctl: called
0xb6998760 11:23:59.194 [opensc-pkcs11] card-iasecc.c:2690:iasecc_card_ctl: CMD SC_CARDCTL_IASECC_SDO_GET_DATA: sdo_class 20
0xb6998760 11:23:59.194 [opensc-pkcs11] card-iasecc.c:2551:iasecc_sdo_get_data: called
0xb6998760 11:23:59.194 [opensc-pkcs11] card-iasecc.c:2504:iasecc_sdo_get_tagged_data: called
0xb6998760 11:23:59.194 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:23:59.194 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:23:59.194 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:23:59.194 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 17 bytes] =====================================
00 CB 3F FF 0B 4D 09 70 07 BF A0 03 03 7F 49 80 ..?..M.p......I.
00 .
======================================================================
0xb6998760 11:23:59.194 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:23:59.210 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
6A 88 j.
======================================================================
0xb6998760 11:23:59.211 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:59.211 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: Referenced data not found
0xb6998760 11:23:59.211 [opensc-pkcs11] card-iasecc.c:2536:iasecc_sdo_get_tagged_data: SDO get data error: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] card-iasecc.c:2558:iasecc_sdo_get_data: cannot parse ECC SDO data: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] card.c:730:sc_card_ctl: returning with: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] pkcs15-iasecc.c:300:iasecc_sdo_get_data: IasEcc: GET DATA error: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] pkcs15-iasecc.c:1230:iasecc_pkcs15_delete_sdo: returning with: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] pkcs15-iasecc.c:1300:iasecc_pkcs15_delete_object: Cannot delete RSA_PUBLIC SDO: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] pkcs15-lib.c:2779:sc_pkcs15init_delete_object: Card specific delete object failed: -1216 (Data object not found)
0xb6998760 11:23:59.211 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:23:59.211 [opensc-pkcs11] reader-pcsc.c:544:pcsc_unlock: called
0xb6998760 11:23:59.213 [opensc-pkcs11] misc.c:59:sc_to_cryptoki_error_common: libopensc return value: -1216 (Data object not found)
0xb6998760 11:24:45.476 [opensc-pkcs11] pkcs11-object.c:146:C_DestroyObject: C_DestroyObject(hSession=0x8836a08, hObject=0x8600d18)
0xb6998760 11:24:45.476 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:24:45.476 [opensc-pkcs11] reader-pcsc.c:507:pcsc_lock: called
0xb6998760 11:24:45.476 [opensc-pkcs11] pkcs15-lib.c:297:sc_pkcs15init_bind: called
0xb6998760 11:24:45.476 [opensc-pkcs11] card.c:720:sc_card_ctl: called
0xb6998760 11:24:45.476 [opensc-pkcs11] card.c:727:sc_card_ctl: card_ctl(4) not supported
0xb6998760 11:24:45.477 [opensc-pkcs11] card.c:571:sc_select_file: called; type=2, path=3f0050154946
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:675:iasecc_select_file: called
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:679:iasecc_select_file: iasecc_select_file(card:0x87fbd38) path.len 6; path.type 2; aid_len 0
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:680:iasecc_select_file: iasecc_select_file() path:3f0050154946
0xb6998760 11:24:45.477 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:688:iasecc_select_file: EF.ATR(aid:'A000000077010800070000FE00000100')
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:231:iasecc_select_mf: called
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:675:iasecc_select_file: called
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:679:iasecc_select_file: iasecc_select_file(card:0x87fbd38) path.len 16; path.type 1; aid_len 0
0xb6998760 11:24:45.477 [opensc-pkcs11] card-iasecc.c:680:iasecc_select_file: iasecc_select_file() path:a000000077010800070000fe00000100::
0xb6998760 11:24:45.477 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:24:45.477 [opensc-pkcs11] card.c:1018:sc_print_cache: current_df(type=1, aid_len=0) e828bd080ff2504f5420415750::
0xb6998760 11:24:45.477 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:24:45.477 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:24:45.477 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:24:45.477 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 22 bytes] =====================================
00 A4 04 00 10 A0 00 00 00 77 01 08 00 07 00 00 .........w......
FE 00 00 01 00 00 ......
======================================================================
0xb6998760 11:24:45.477 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:24:45.512 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
90 00 ..
======================================================================
0xb6998760 11:24:45.512 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:24:45.512 [opensc-pkcs11] card-iasecc.c:804:iasecc_select_file: iasecc_select_file() apdu.resp 0
0xb6998760 11:24:45.512 [opensc-pkcs11] card.c:1007:sc_print_cache: card cache invalid
0xb6998760 11:24:45.512 [opensc-pkcs11] card-iasecc.c:866:iasecc_select_file: returning with: 0 (Success)
0xb6998760 11:24:45.512 [opensc-pkcs11] card-iasecc.c:261:iasecc_select_mf: returning with: 0 (Success)
0xb6998760 11:24:45.512 [opensc-pkcs11] card.c:1007:sc_print_cache: card cache invalid
0xb6998760 11:24:45.512 [opensc-pkcs11] apdu.c:524:sc_transmit_apdu: called
0xb6998760 11:24:45.512 [opensc-pkcs11] card.c:292:sc_lock: called
0xb6998760 11:24:45.512 [opensc-pkcs11] reader-pcsc.c:241:pcsc_transmit: reader 'OMNIKEY CardMan 3x21 00 00'
0xb6998760 11:24:45.512 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Outgoing APDU data [ 10 bytes] =====================================
00 A4 09 04 04 50 15 49 46 00 .....P.IF.
======================================================================
0xb6998760 11:24:45.512 [opensc-pkcs11] reader-pcsc.c:174:pcsc_internal_transmit: called
0xb6998760 11:24:45.528 [opensc-pkcs11] apdu.c:184:sc_apdu_log:
Incoming APDU data [ 2 bytes] =====================================
6A 82 j.
======================================================================
0xb6998760 11:24:45.528 [opensc-pkcs11] card.c:330:sc_unlock: called
0xb6998760 11:24:45.528 [opensc-pkcs11] iso7816.c:103:iso7816_check_sw: File not found
0xb6998760 11:24:45.528 [opensc-pkcs11] card-iasecc.c:802:iasecc_select_file: iasecc_select_file() check SW failed: -1201 (File not found)
0xb6998760 11:24:45.528 [opensc-pkcs11] card.c:597:sc_select_file: returning with: -1201 (File not found)
0xb6998760 11:24:45.528 [opensc-pkcs11] profile.c:327:sc_profile_load: called
0xb6998760 11:24:45.528 [opensc-pkcs11] profile.c:357:sc_profile_load: Using profile directory '/usr/local/share/opensc'.
0xb6998760 11:24:45.528 [opensc-pkcs11] profile.c:365:sc_profile_load: Trying profile file /usr/local/share/opensc/pkcs15.profile
0xb6998760 11:24:45.530 [opensc-pkcs11] profile.c:370:sc_profile_load: profile /usr/local/share/opensc/pkcs15.profile loaded ok
0xb6998760 11:24:45.530 [opensc-pkcs11] profile.c:380:sc_profile_load: returning with: 0 (Success)
0xb6998760 11:24:45.530 [opensc-pkcs11] profile.c:327:sc_profile_load: called
0xb6998760 11:24:45.530 [opensc-pkcs11] profile.c:357:sc_profile_load: Using profile directory '/usr/local/share/opensc'.
0xb6998760 11:24:45.530 [opensc-pkcs11] profile.c:365:sc_profile_load: Trying profile file /usr/local/share/opensc/iasecc.profile
0xb6998760 11:24:45.531 [opensc-pkcs11] profile.c:370:sc_profile_load: profile /usr/local/share/opensc/iasecc.profile loaded ok
0xb6998760 11:24:45.531 [opensc-pkcs11] profile.c:380:sc_profile_load: returning with: 0 (Success)
0xb6998760 11:24:45.531 [opensc-pkcs11] profile.c:392:sc_profile_finish: called
0xb6998760 11:24:45.531 [opensc-pkcs11] profile.c:435:sc_profile_finish: returning with: 0 (Success)
0xb6998760 11:24:45.531 [opensc-pkcs11] pkcs15-lib.c:379:sc_pkcs15init_bind: returning with: 0 (Success)
0xb6998760 11:24:45.531 [opensc-pkcs11] pkcs15-lib.c:2742:sc_pkcs15init_delete_object: called
_______________________________________________
opensc-devel mailing list
opensc-devel@lists.opensc-project.org
http://www.opensc-project.org/mailman/listinfo/opensc-devel
